builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s-pgo
slave: talos-linux64-ix-051
starttime: 1509048622.39
results: success (0)
revision: 7290c8fce8a1f5c4bce930d951eaf1d6326bd788
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:22.392509) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:22.393010) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:22.393307) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:22.415869) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:22.416300) =========
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=5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-26 13:10:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 221M=0s
2017-10-26 13:10:22 (221 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.128378
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:22.564181) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:22.564572) =========
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=5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020498
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:22.614158) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-26 13:10:22.614496) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7290c8fce8a1f5c4bce930d951eaf1d6326bd788 --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 7290c8fce8a1f5c4bce930d951eaf1d6326bd788 --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=5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-26 13:10:22,588 truncating revision to first 12 chars
2017-10-26 13:10:22,588 Setting DEBUG logging.
2017-10-26 13:10:22,588 attempt 1/10
2017-10-26 13:10:22,589 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7290c8fce8a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-26 13:10:22,982 unpacking tar archive at: mozilla-central-7290c8fce8a1/testing/mozharness/
program finished with exit code 0
elapsedTime=1.036588
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-26 13:10:23.672106) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:23.672461) =========
script_repo_revision: 7290c8fce8a1f5c4bce930d951eaf1d6326bd788
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:23.672874) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:23.673169) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-26 13:10:23.690932) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 57 secs) (at 2017-10-26 13:10:23.691345) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
13:10:23 INFO - MultiFileLogger online at 20171026 13:10:23 in /builds/slave/test
13:10:23 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
13:10:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
13:10:23 INFO - {'append_to_log': False,
13:10:23 INFO - 'base_work_dir': '/builds/slave/test',
13:10:23 INFO - 'blob_upload_branch': 'Firefox',
13:10:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
13:10:23 INFO - 'branch': 'Firefox',
13:10:23 INFO - 'buildbot_json_path': 'buildprops.json',
13:10:23 INFO - 'code_coverage': False,
13:10:23 INFO - 'config_files': ('talos/linux_config.py',),
13:10:23 INFO - 'default_actions': ('clobber',
13:10:23 INFO - 'read-buildbot-config',
13:10:23 INFO - 'download-and-extract',
13:10:23 INFO - 'populate-webroot',
13:10:23 INFO - 'create-virtualenv',
13:10:23 INFO - 'install',
13:10:23 INFO - 'setup-mitmproxy',
13:10:23 INFO - 'run-tests'),
13:10:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
13:10:23 INFO - 'disable_ccov_upload': False,
13:10:23 INFO - 'disable_stylo': False,
13:10:23 INFO - 'download_minidump_stackwalk': True,
13:10:23 INFO - 'download_symbols': 'ondemand',
13:10:23 INFO - 'enable_stylo': False,
13:10:23 INFO - 'enable_webrender': False,
13:10:23 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
13:10:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
13:10:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
13:10:23 INFO - 'gecko_profile': False,
13:10:23 INFO - 'gecko_profile_interval': 0,
13:10:23 INFO - 'installer_path': 'installer.exe',
13:10:23 INFO - 'log_level': 'info',
13:10:23 INFO - 'log_name': 'talos',
13:10:23 INFO - 'log_to_console': True,
13:10:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
13:10:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
13:10:23 INFO - 'opt_config_files': (),
13:10:23 INFO - 'pip_index': False,
13:10:23 INFO - 'suite': 'g4-stylo-disabled-e10s',
13:10:23 INFO - 'system_bits': '32',
13:10:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
13:10:23 INFO - 'title': 'talos-linux64-ix-051',
13:10:23 INFO - 'tooltool_cache': '/builds/tooltool_cache',
13:10:23 INFO - 'use_talos_json': True,
13:10:23 INFO - 'verify': 'False',
13:10:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
13:10:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
13:10:23 INFO - 'work_dir': 'build'}
13:10:23 INFO - [mozharness: 2017-10-26 20:10:23.802719Z] Running clobber step.
13:10:23 INFO - Running pre-action listener: _resource_record_pre_action
13:10:23 INFO - Running main action method: clobber
13:10:23 INFO - rmtree: /builds/slave/test/build
13:10:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
13:10:25 INFO - Running post-action listener: _resource_record_post_action
13:10:25 INFO - [mozharness: 2017-10-26 20:10:25.376718Z] Finished clobber step (success)
13:10:25 INFO - [mozharness: 2017-10-26 20:10:25.376813Z] Running read-buildbot-config step.
13:10:25 INFO - Running pre-action listener: _resource_record_pre_action
13:10:25 INFO - Running main action method: read_buildbot_config
13:10:25 INFO - Using buildbot properties:
13:10:25 INFO - {
13:10:25 INFO - "project": "",
13:10:25 INFO - "product": "firefox",
13:10:25 INFO - "who": "ffxbld@noreply.mozilla.org",
13:10:25 INFO - "installer_path": "public/build/target.tar.bz2",
13:10:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
13:10:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
13:10:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-stylo-disabled-e10s",
13:10:25 INFO - "stage_platform": "linux64-pgo",
13:10:25 INFO - "basedir": "/builds/slave/test",
13:10:25 INFO - "buildnumber": 139,
13:10:25 INFO - "platform": "ubuntu64_hw",
13:10:25 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
13:10:25 INFO - "slavebuilddir": "test",
13:10:25 INFO - "taskId": "IVwIcOOlSn6AlALP4iUr1g",
13:10:25 INFO - "branch": "mozilla-central",
13:10:25 INFO - "script_repo_revision": "production",
13:10:25 INFO - "revision": "7290c8fce8a1f5c4bce930d951eaf1d6326bd788",
13:10:25 INFO - "slavename": "talos-linux64-ix-051",
13:10:25 INFO - "repo_path": "mozilla-central"
13:10:25 INFO - }
13:10:25 INFO - Finding installer, test and symbols from parent task.
13:10:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IVwIcOOlSn6AlALP4iUr1g'}, attempt #1
13:10:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IVwIcOOlSn6AlALP4iUr1g'}, attempt #1
13:10:26 INFO - Task dependencies: RohSkTO3QrOrc72atryY_g
13:10:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g'}, attempt #1
13:10:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.tar.bz2
13:10:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.test_packages.json
13:10:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.crashreporter-symbols.zip
13:10:26 INFO - Running post-action listener: _resource_record_post_action
13:10:26 INFO - [mozharness: 2017-10-26 20:10:26.748916Z] Finished read-buildbot-config step (success)
13:10:26 INFO - [mozharness: 2017-10-26 20:10:26.749134Z] Running download-and-extract step.
13:10:26 INFO - Running pre-action listener: _resource_record_pre_action
13:10:26 INFO - Running main action method: download_and_extract
13:10:26 INFO - mkdir: /builds/slave/test/build/tests
13:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:26 INFO - trying https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.test_packages.json
13:10:26 INFO - Downloading https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
13:10:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
13:10:27 INFO - Downloaded 989 bytes.
13:10:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
13:10:27 INFO - Using the following test package requirements:
13:10:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
13:10:27 INFO - u'common': [u'target.common.tests.zip'],
13:10:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
13:10:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
13:10:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
13:10:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
13:10:27 INFO - u'mozbase': [u'target.common.tests.zip'],
13:10:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
13:10:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
13:10:27 INFO - u'web-platform': [u'target.common.tests.zip',
13:10:27 INFO - u'target.web-platform.tests.tar.gz'],
13:10:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
13:10:27 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
13:10:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.common.tests.zip
13:10:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.common.tests.zip'}, attempt #1
13:10:27 INFO - Fetch https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.common.tests.zip into memory
13:10:29 INFO - Content-Length response header: 56886280
13:10:29 INFO - Bytes received: 56886280
13:10:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.talos.tests.zip
13:10:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.talos.tests.zip'}, attempt #1
13:10:40 INFO - Fetch https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.talos.tests.zip into memory
13:10:41 INFO - Content-Length response header: 18768858
13:10:41 INFO - Bytes received: 18768858
13:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:42 INFO - trying https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.tar.bz2
13:10:42 INFO - Downloading https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
13:10:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
13:10:45 INFO - Downloaded 63357615 bytes.
13:10:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.tar.bz2
13:10:45 INFO - mkdir: /builds/slave/test/properties
13:10:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
13:10:45 INFO - Writing to file /builds/slave/test/properties/build_url
13:10:45 INFO - Contents:
13:10:45 INFO - build_url:https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.tar.bz2
13:10:45 INFO - Running post-action listener: _resource_record_post_action
13:10:45 INFO - Running post-action listener: find_tests_for_verification
13:10:45 INFO - Running post-action listener: set_extra_try_arguments
13:10:45 INFO - [mozharness: 2017-10-26 20:10:45.074432Z] Finished download-and-extract step (success)
13:10:45 INFO - [mozharness: 2017-10-26 20:10:45.074655Z] Running populate-webroot step.
13:10:45 INFO - Running pre-action listener: _resource_record_pre_action
13:10:45 INFO - Running main action method: populate_webroot
13:10:45 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
13:10:45 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['tresize',
13:10:45 INFO - 'tcanvasmark']},
13:10:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
13:10:45 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['dromaeo_css',
13:10:45 INFO - 'kraken']},
13:10:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
13:10:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
13:10:45 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:10:45 INFO - 'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
13:10:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
13:10:45 INFO - 'tests': ['damp', 'tps']},
13:10:45 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:10:45 INFO - 'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['damp', 'tps']},
13:10:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
13:10:45 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['dromaeo_dom']},
13:10:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
13:10:45 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['basic_compositor_video',
13:10:45 INFO - 'glvideo']},
13:10:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
13:10:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
13:10:45 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:10:45 INFO - 'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['ts_paint_webext',
13:10:45 INFO - 'tp5o_webext']},
13:10:45 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
13:10:45 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:10:45 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:10:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:10:45 INFO - 'talos_options': ['--mitmproxy',
13:10:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:10:45 INFO - '--firstNonBlankPaint'],
13:10:45 INFO - 'tests': ['tp6_google_heavy',
13:10:45 INFO - 'tp6_youtube_heavy',
13:10:45 INFO - 'tp6_amazon_heavy',
13:10:45 INFO - 'tp6_facebook_heavy']},
13:10:45 INFO - 'other-e10s': {'tests': ['a11yr',
13:10:45 INFO - 'ts_paint',
13:10:45 INFO - 'tpaint',
13:10:45 INFO - 'sessionrestore',
13:10:45 INFO - 'sessionrestore_many_windows',
13:10:45 INFO - 'sessionrestore_no_auto_restore',
13:10:45 INFO - 'tabpaint',
13:10:45 INFO - 'cpstartup']},
13:10:45 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['a11yr',
13:10:45 INFO - 'ts_paint',
13:10:45 INFO - 'tpaint',
13:10:45 INFO - 'sessionrestore',
13:10:45 INFO - 'sessionrestore_many_windows',
13:10:45 INFO - 'sessionrestore_no_auto_restore',
13:10:45 INFO - 'tabpaint',
13:10:45 INFO - 'cpstartup']},
13:10:45 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
13:10:45 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
13:10:45 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['perf_reftest_singletons']},
13:10:45 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['perf_reftest']},
13:10:45 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
13:10:45 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['speedometer']},
13:10:45 INFO - 'svgr-e10s': {'tests': ['tsvgx',
13:10:45 INFO - 'tsvgr_opacity',
13:10:45 INFO - 'tart',
13:10:45 INFO - 'tscrollx',
13:10:45 INFO - 'tsvg_static']},
13:10:45 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['tsvgx',
13:10:45 INFO - 'tsvgr_opacity',
13:10:45 INFO - 'tart',
13:10:45 INFO - 'tscrollx',
13:10:45 INFO - 'tsvg_static']},
13:10:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
13:10:45 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:10:45 INFO - 'talos_options': ['--disable-stylo'],
13:10:45 INFO - 'tests': ['tp5o']},
13:10:45 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:10:45 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:10:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:10:45 INFO - 'talos_options': ['--mitmproxy',
13:10:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:10:45 INFO - '--firstNonBlankPaint'],
13:10:45 INFO - 'tests': ['tp6_google',
13:10:45 INFO - 'tp6_youtube',
13:10:45 INFO - 'tp6_amazon',
13:10:45 INFO - 'tp6_facebook']},
13:10:45 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:10:45 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:10:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:10:45 INFO - 'talos_options': ['--disable-stylo',
13:10:45 INFO - '--mitmproxy',
13:10:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:10:45 INFO - '--firstNonBlankPaint'],
13:10:45 INFO - 'tests': ['tp6_google',
13:10:45 INFO - 'tp6_youtube',
13:10:45 INFO - 'tp6_amazon',
13:10:45 INFO - 'tp6_facebook']},
13:10:45 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:10:45 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:10:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:10:45 INFO - 'talos_options': ['--stylo-threads=1',
13:10:45 INFO - '--mitmproxy',
13:10:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:10:45 INFO - '--firstNonBlankPaint'],
13:10:45 INFO - 'tests': ['tp6_google',
13:10:45 INFO - 'tp6_youtube',
13:10:45 INFO - 'tp6_amazon',
13:10:45 INFO - 'tp6_facebook']},
13:10:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
13:10:45 INFO - 'talos_options': ['--xperf_path',
13:10:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:10:45 INFO - 'tests': ['tp5n']},
13:10:45 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:10:45 INFO - 'talos_options': ['--disable-stylo',
13:10:45 INFO - '--xperf_path',
13:10:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:10:45 INFO - 'tests': ['tp5n']}}}
13:10:45 INFO - Running post-action listener: _resource_record_post_action
13:10:45 INFO - [mozharness: 2017-10-26 20:10:45.093593Z] Finished populate-webroot step (success)
13:10:45 INFO - [mozharness: 2017-10-26 20:10:45.093681Z] Running create-virtualenv step.
13:10:45 INFO - Running pre-action listener: _resource_record_pre_action
13:10:45 INFO - Running main action method: create_virtualenv
13:10:45 INFO - Creating virtualenv /builds/slave/test/build/venv
13:10:45 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
13:10:45 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
13:10:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
13:10:45 INFO - Using real prefix '/usr'
13:10:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python
13:10:45 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
13:10:47 INFO - Installing setuptools, pip, wheel...done.
13:10:47 INFO - Return code: 0
13:10:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
13:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:10:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:10:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
13:10:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
13:10:47 INFO - Using env: {'DISPLAY': ':0',
13:10:47 INFO - 'HOME': '/home/cltbld',
13:10:47 INFO - 'LANG': 'en_US.UTF-8',
13:10:47 INFO - 'LANGUAGE': 'en_US:en',
13:10:47 INFO - 'LOGNAME': 'cltbld',
13:10:47 INFO - 'MAIL': '/var/mail/cltbld',
13:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:10:47 INFO - 'MOZ_NO_REMOTE': '1',
13:10:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:10:47 INFO - 'NO_EM_RESTART': '1',
13:10:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:10:47 INFO - 'PWD': '/builds/slave/test',
13:10:47 INFO - 'SHELL': '/bin/bash',
13:10:47 INFO - 'SHLVL': '1',
13:10:47 INFO - 'TERM': 'linux',
13:10:47 INFO - 'TMOUT': '86400',
13:10:47 INFO - 'USER': 'cltbld',
13:10:47 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:10:47 INFO - '_': '/tools/buildbot/bin/python'}
13:10:47 INFO - Ignoring indexes: https://pypi.python.org/simple
13:10:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
13:10:47 INFO - Return code: 0
13:10:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
13:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:10:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:10:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
13:10:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
13:10:47 INFO - Using env: {'DISPLAY': ':0',
13:10:47 INFO - 'HOME': '/home/cltbld',
13:10:47 INFO - 'LANG': 'en_US.UTF-8',
13:10:47 INFO - 'LANGUAGE': 'en_US:en',
13:10:47 INFO - 'LOGNAME': 'cltbld',
13:10:47 INFO - 'MAIL': '/var/mail/cltbld',
13:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:10:47 INFO - 'MOZ_NO_REMOTE': '1',
13:10:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:10:47 INFO - 'NO_EM_RESTART': '1',
13:10:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:10:47 INFO - 'PWD': '/builds/slave/test',
13:10:47 INFO - 'SHELL': '/bin/bash',
13:10:47 INFO - 'SHLVL': '1',
13:10:47 INFO - 'TERM': 'linux',
13:10:47 INFO - 'TMOUT': '86400',
13:10:47 INFO - 'USER': 'cltbld',
13:10:47 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:10:47 INFO - '_': '/tools/buildbot/bin/python'}
13:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple
13:10:48 INFO - Collecting psutil>=3.1.1
13:10:49 INFO - Installing collected packages: psutil
13:10:49 INFO - Successfully installed psutil-3.1.1
13:10:49 INFO - Return code: 0
13:10:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
13:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:10:49 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:10:49 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
13:10:49 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
13:10:49 INFO - Using env: {'DISPLAY': ':0',
13:10:49 INFO - 'HOME': '/home/cltbld',
13:10:49 INFO - 'LANG': 'en_US.UTF-8',
13:10:49 INFO - 'LANGUAGE': 'en_US:en',
13:10:49 INFO - 'LOGNAME': 'cltbld',
13:10:49 INFO - 'MAIL': '/var/mail/cltbld',
13:10:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:10:49 INFO - 'MOZ_NO_REMOTE': '1',
13:10:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:10:49 INFO - 'NO_EM_RESTART': '1',
13:10:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:10:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:10:49 INFO - 'PWD': '/builds/slave/test',
13:10:49 INFO - 'SHELL': '/bin/bash',
13:10:49 INFO - 'SHLVL': '1',
13:10:49 INFO - 'TERM': 'linux',
13:10:49 INFO - 'TMOUT': '86400',
13:10:49 INFO - 'USER': 'cltbld',
13:10:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:10:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:10:49 INFO - '_': '/tools/buildbot/bin/python'}
13:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple
13:10:49 INFO - Collecting mozsystemmonitor==0.3
13:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
13:10:50 INFO - Installing collected packages: mozsystemmonitor
13:10:50 INFO - Successfully installed mozsystemmonitor-0.3
13:10:50 INFO - Return code: 0
13:10:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
13:10:50 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')]}
13:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:10:50 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')]}
13:10:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:10:50 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:10:50 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
13:10:50 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
13:10:50 INFO - Using env: {'DISPLAY': ':0',
13:10:50 INFO - 'HOME': '/home/cltbld',
13:10:50 INFO - 'LANG': 'en_US.UTF-8',
13:10:50 INFO - 'LANGUAGE': 'en_US:en',
13:10:50 INFO - 'LOGNAME': 'cltbld',
13:10:50 INFO - 'MAIL': '/var/mail/cltbld',
13:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:10:50 INFO - 'MOZ_NO_REMOTE': '1',
13:10:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:10:50 INFO - 'NO_EM_RESTART': '1',
13:10:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:10:50 INFO - 'PWD': '/builds/slave/test',
13:10:50 INFO - 'SHELL': '/bin/bash',
13:10:50 INFO - 'SHLVL': '1',
13:10:50 INFO - 'TERM': 'linux',
13:10:50 INFO - 'TMOUT': '86400',
13:10:50 INFO - 'USER': 'cltbld',
13:10:50 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:10:50 INFO - '_': '/tools/buildbot/bin/python'}
13:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple
13:10:51 INFO - Collecting jsonschema==2.5.1
13:10:52 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
13:10:52 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
13:10:53 INFO - Installing collected packages: functools32, jsonschema
13:10:54 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
13:10:54 INFO - Return code: 0
13:10:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
13:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:10:54 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:10:54 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
13:10:54 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
13:10:54 INFO - Using env: {'DISPLAY': ':0',
13:10:54 INFO - 'HOME': '/home/cltbld',
13:10:54 INFO - 'LANG': 'en_US.UTF-8',
13:10:54 INFO - 'LANGUAGE': 'en_US:en',
13:10:54 INFO - 'LOGNAME': 'cltbld',
13:10:54 INFO - 'MAIL': '/var/mail/cltbld',
13:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:10:54 INFO - 'MOZ_NO_REMOTE': '1',
13:10:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:10:54 INFO - 'NO_EM_RESTART': '1',
13:10:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:10:54 INFO - 'PWD': '/builds/slave/test',
13:10:54 INFO - 'SHELL': '/bin/bash',
13:10:54 INFO - 'SHLVL': '1',
13:10:54 INFO - 'TERM': 'linux',
13:10:54 INFO - 'TMOUT': '86400',
13:10:54 INFO - 'USER': 'cltbld',
13:10:54 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:10:54 INFO - '_': '/tools/buildbot/bin/python'}
13:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple
13:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
13:10:54 INFO - Return code: 0
13:10:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
13:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:10:54 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:10:54 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
13:10:54 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
13:10:54 INFO - Using env: {'DISPLAY': ':0',
13:10:54 INFO - 'HOME': '/home/cltbld',
13:10:54 INFO - 'LANG': 'en_US.UTF-8',
13:10:54 INFO - 'LANGUAGE': 'en_US:en',
13:10:54 INFO - 'LOGNAME': 'cltbld',
13:10:54 INFO - 'MAIL': '/var/mail/cltbld',
13:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:10:54 INFO - 'MOZ_NO_REMOTE': '1',
13:10:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:10:54 INFO - 'NO_EM_RESTART': '1',
13:10:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:10:54 INFO - 'PWD': '/builds/slave/test',
13:10:54 INFO - 'SHELL': '/bin/bash',
13:10:54 INFO - 'SHLVL': '1',
13:10:54 INFO - 'TERM': 'linux',
13:10:54 INFO - 'TMOUT': '86400',
13:10:54 INFO - 'USER': 'cltbld',
13:10:54 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:10:54 INFO - '_': '/tools/buildbot/bin/python'}
13:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple
13:10:54 INFO - Collecting blobuploader==1.2.4
13:10:56 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
13:10:57 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
13:10:58 INFO - Installing collected packages: requests, docopt, blobuploader
13:10:58 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
13:10:58 INFO - Return code: 0
13:10:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:10:58 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')]}
13:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:10:58 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')]}
13:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:10:58 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:10:58 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
13:10:58 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
13:10:58 INFO - Using env: {'DISPLAY': ':0',
13:10:58 INFO - 'HOME': '/home/cltbld',
13:10:58 INFO - 'LANG': 'en_US.UTF-8',
13:10:58 INFO - 'LANGUAGE': 'en_US:en',
13:10:58 INFO - 'LOGNAME': 'cltbld',
13:10:58 INFO - 'MAIL': '/var/mail/cltbld',
13:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:10:58 INFO - 'MOZ_NO_REMOTE': '1',
13:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:10:58 INFO - 'NO_EM_RESTART': '1',
13:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:10:58 INFO - 'PWD': '/builds/slave/test',
13:10:58 INFO - 'SHELL': '/bin/bash',
13:10:58 INFO - 'SHLVL': '1',
13:10:58 INFO - 'TERM': 'linux',
13:10:58 INFO - 'TMOUT': '86400',
13:10:58 INFO - 'USER': 'cltbld',
13:10:58 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:10:58 INFO - '_': '/tools/buildbot/bin/python'}
13:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple
13:10:58 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
13:10:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
13:10:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
13:10:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
13:10:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
13:10:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
13:10:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
13:11:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
13:11:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
13:11:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
13:11:00 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
13:11:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
13:11:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
13:11:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
13:11:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
13:11:01 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
13:11:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
13:11:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
13:11:02 INFO - Running setup.py install for manifestparser: started
13:11:02 INFO - Running setup.py install for manifestparser: finished with status 'done'
13:11:02 INFO - Running setup.py install for mozcrash: started
13:11:02 INFO - Running setup.py install for mozcrash: finished with status 'done'
13:11:02 INFO - Running setup.py install for mozdebug: started
13:11:02 INFO - Running setup.py install for mozdebug: finished with status 'done'
13:11:02 INFO - Running setup.py install for mozdevice: started
13:11:02 INFO - Running setup.py install for mozdevice: finished with status 'done'
13:11:02 INFO - Running setup.py install for mozfile: started
13:11:03 INFO - Running setup.py install for mozfile: finished with status 'done'
13:11:03 INFO - Running setup.py install for mozhttpd: started
13:11:03 INFO - Running setup.py install for mozhttpd: finished with status 'done'
13:11:03 INFO - Running setup.py install for mozinfo: started
13:11:03 INFO - Running setup.py install for mozinfo: finished with status 'done'
13:11:03 INFO - Running setup.py install for mozInstall: started
13:11:03 INFO - Running setup.py install for mozInstall: finished with status 'done'
13:11:03 INFO - Running setup.py install for mozleak: started
13:11:03 INFO - Running setup.py install for mozleak: finished with status 'done'
13:11:03 INFO - Running setup.py install for mozlog: started
13:11:04 INFO - Running setup.py install for mozlog: finished with status 'done'
13:11:04 INFO - Running setup.py install for moznetwork: started
13:11:04 INFO - Running setup.py install for moznetwork: finished with status 'done'
13:11:04 INFO - Running setup.py install for mozprocess: started
13:11:04 INFO - Running setup.py install for mozprocess: finished with status 'done'
13:11:04 INFO - Running setup.py install for mozprofile: started
13:11:04 INFO - Running setup.py install for mozprofile: finished with status 'done'
13:11:04 INFO - Running setup.py install for mozrunner: started
13:11:05 INFO - Running setup.py install for mozrunner: finished with status 'done'
13:11:05 INFO - Running setup.py install for mozscreenshot: started
13:11:05 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
13:11:05 INFO - Running setup.py install for moztest: started
13:11:05 INFO - Running setup.py install for moztest: finished with status 'done'
13:11:05 INFO - Running setup.py install for mozversion: started
13:11:05 INFO - Running setup.py install for mozversion: finished with status 'done'
13:11:06 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
13:11:06 INFO - Return code: 0
13:11:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:11:06 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:11:06 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
13:11:06 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
13:11:06 INFO - Using env: {'DISPLAY': ':0',
13:11:06 INFO - 'HOME': '/home/cltbld',
13:11:06 INFO - 'LANG': 'en_US.UTF-8',
13:11:06 INFO - 'LANGUAGE': 'en_US:en',
13:11:06 INFO - 'LOGNAME': 'cltbld',
13:11:06 INFO - 'MAIL': '/var/mail/cltbld',
13:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:11:06 INFO - 'MOZ_NO_REMOTE': '1',
13:11:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:11:06 INFO - 'NO_EM_RESTART': '1',
13:11:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:11:06 INFO - 'PWD': '/builds/slave/test',
13:11:06 INFO - 'SHELL': '/bin/bash',
13:11:06 INFO - 'SHLVL': '1',
13:11:06 INFO - 'TERM': 'linux',
13:11:06 INFO - 'TMOUT': '86400',
13:11:06 INFO - 'USER': 'cltbld',
13:11:06 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:11:06 INFO - '_': '/tools/buildbot/bin/python'}
13:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple
13:11:06 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
13:11:06 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))
13:11:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
13:11:06 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))
13:11:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
13:11:07 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))
13:11:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
13:11:07 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))
13:11:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
13:11:07 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))
13:11:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
13:11:07 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))
13:11:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
13:11:07 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))
13:11:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
13:11:08 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))
13:11:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
13:11:08 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))
13:11:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
13:11:08 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))
13:11:08 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
13:11:08 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))
13:11:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
13:11:08 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))
13:11:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
13:11:09 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))
13:11:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
13:11:09 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))
13:11:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
13:11:09 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))
13:11:09 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
13:11:09 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))
13:11:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
13:11:09 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))
13:11:09 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
13:11:10 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
13:11:10 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))
13:11:10 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))
13:11:10 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))
13:11:11 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))
13:11:11 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))
13:11:11 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))
13:11:11 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
13:11:12 INFO - Installing collected packages: six, blessings
13:11:12 INFO - Successfully installed blessings-1.6 six-1.10.0
13:11:12 INFO - Return code: 0
13:11:12 INFO - Done creating virtualenv /builds/slave/test/build/venv.
13:11:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
13:11:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
13:11:12 INFO - Reading from file tmpfile_stdout
13:11:12 INFO - Reading from file tmpfile_stderr
13:11:12 INFO - Current package versions:
13:11:12 INFO - blessings == 1.6
13:11:12 INFO - blobuploader == 1.2.4
13:11:12 INFO - docopt == 0.6.1
13:11:12 INFO - functools32 == 3.2.3.post2
13:11:12 INFO - jsonschema == 2.5.1
13:11:12 INFO - manifestparser == 1.2
13:11:12 INFO - mozInstall == 1.14
13:11:12 INFO - mozcrash == 1.0
13:11:12 INFO - mozdebug == 0.1
13:11:12 INFO - mozdevice == 0.51
13:11:12 INFO - mozfile == 1.2
13:11:12 INFO - mozhttpd == 0.7
13:11:12 INFO - mozinfo == 0.10
13:11:12 INFO - mozleak == 0.1
13:11:12 INFO - mozlog == 3.5
13:11:12 INFO - moznetwork == 0.27
13:11:12 INFO - mozprocess == 0.25
13:11:12 INFO - mozprofile == 0.29
13:11:12 INFO - mozrunner == 6.13
13:11:12 INFO - mozscreenshot == 0.1
13:11:12 INFO - mozsystemmonitor == 0.3
13:11:12 INFO - moztest == 0.8
13:11:12 INFO - mozversion == 1.4
13:11:12 INFO - psutil == 3.1.1
13:11:12 INFO - requests == 1.2.3
13:11:12 INFO - six == 1.10.0
13:11:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:11:12 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 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:11:12 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
13:11:12 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
13:11:12 INFO - Using env: {'DISPLAY': ':0',
13:11:12 INFO - 'HOME': '/home/cltbld',
13:11:12 INFO - 'LANG': 'en_US.UTF-8',
13:11:12 INFO - 'LANGUAGE': 'en_US:en',
13:11:12 INFO - 'LOGNAME': 'cltbld',
13:11:12 INFO - 'MAIL': '/var/mail/cltbld',
13:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:11:12 INFO - 'MOZ_NO_REMOTE': '1',
13:11:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:11:12 INFO - 'NO_EM_RESTART': '1',
13:11:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:11:12 INFO - 'PWD': '/builds/slave/test',
13:11:12 INFO - 'SHELL': '/bin/bash',
13:11:12 INFO - 'SHLVL': '1',
13:11:12 INFO - 'TERM': 'linux',
13:11:12 INFO - 'TMOUT': '86400',
13:11:12 INFO - 'USER': 'cltbld',
13:11:12 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:11:12 INFO - '_': '/tools/buildbot/bin/python'}
13:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple
13:11:13 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))
13:11:13 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))
13:11:13 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))
13:11:13 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))
13:11:13 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))
13:11:13 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))
13:11:13 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))
13:11:13 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))
13:11:13 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))
13:11:13 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
13:11:14 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:11:15 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))
13:11:15 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))
13:11:15 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))
13:11:15 INFO - Installing collected packages: simplejson, requests
13:11:15 INFO - Found existing installation: requests 1.2.3
13:11:15 INFO - Uninstalling requests-1.2.3:
13:11:15 INFO - Successfully uninstalled requests-1.2.3
13:11:15 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
13:11:15 INFO - Return code: 0
13:11:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
13:11: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')]}
13:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:11: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')]}
13:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:11: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1804cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657', '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
13:11: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', 'jsonschema'] in /builds/slave/test/build
13:11: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 jsonschema
13:11:15 INFO - Using env: {'DISPLAY': ':0',
13:11:15 INFO - 'HOME': '/home/cltbld',
13:11:15 INFO - 'LANG': 'en_US.UTF-8',
13:11:15 INFO - 'LANGUAGE': 'en_US:en',
13:11:15 INFO - 'LOGNAME': 'cltbld',
13:11:15 INFO - 'MAIL': '/var/mail/cltbld',
13:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:11:15 INFO - 'MOZ_NO_REMOTE': '1',
13:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:11:15 INFO - 'NO_EM_RESTART': '1',
13:11:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:11:15 INFO - 'PWD': '/builds/slave/test',
13:11:15 INFO - 'SHELL': '/bin/bash',
13:11:15 INFO - 'SHLVL': '1',
13:11:15 INFO - 'TERM': 'linux',
13:11:15 INFO - 'TMOUT': '86400',
13:11:15 INFO - 'USER': 'cltbld',
13:11:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:11:15 INFO - '_': '/tools/buildbot/bin/python'}
13:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple
13:11:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
13:11:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
13:11:16 INFO - Return code: 0
13:11:16 INFO - Running post-action listener: _resource_record_post_action
13:11:16 INFO - Running post-action listener: _start_resource_monitoring
13:11:16 INFO - Starting resource monitoring.
13:11:16 INFO - [mozharness: 2017-10-26 20:11:16.263005Z] Finished create-virtualenv step (success)
13:11:16 INFO - [mozharness: 2017-10-26 20:11:16.263509Z] Running install step.
13:11:16 INFO - Running pre-action listener: _resource_record_pre_action
13:11:16 INFO - Running main action method: install
13:11:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
13:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
13:11:16 INFO - Reading from file tmpfile_stdout
13:11:16 INFO - Reading from file tmpfile_stderr
13:11:16 INFO - Detecting whether we're running mozinstall >=1.0...
13:11:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
13:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
13:11:16 INFO - Reading from file tmpfile_stdout
13:11:16 INFO - Output received:
13:11:16 INFO - Usage: mozinstall [options] installer
13:11:16 INFO - Options:
13:11:16 INFO - -h, --help show this help message and exit
13:11:16 INFO - -d DEST, --destination=DEST
13:11:16 INFO - Directory to install application into. [default:
13:11:16 INFO - "/builds/slave/test"]
13:11:16 INFO - --app=APP Application being installed. [default: firefox]
13:11:16 INFO - mkdir: /builds/slave/test/build/application
13:11:16 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']
13:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
13:11:34 INFO - Reading from file tmpfile_stdout
13:11:34 INFO - Output received:
13:11:34 INFO - /builds/slave/test/build/application/firefox/firefox
13:11:34 INFO - Running post-action listener: _resource_record_post_action
13:11:34 INFO - [mozharness: 2017-10-26 20:11:34.971250Z] Finished install step (success)
13:11:34 INFO - [mozharness: 2017-10-26 20:11:34.971495Z] Running setup-mitmproxy step.
13:11:34 INFO - Running pre-action listener: _resource_record_pre_action
13:11:34 INFO - Running main action method: setup_mitmproxy
13:11:34 INFO - Skipping: mitmproxy is not required
13:11:34 INFO - Running post-action listener: _resource_record_post_action
13:11:34 INFO - [mozharness: 2017-10-26 20:11:34.972677Z] Finished setup-mitmproxy step (success)
13:11:34 INFO - [mozharness: 2017-10-26 20:11:34.972893Z] Running run-tests step.
13:11:34 INFO - Running pre-action listener: _resource_record_pre_action
13:11:34 INFO - Running pre-action listener: _set_gcov_prefix
13:11:34 INFO - Running main action method: run_tests
13:11:34 WARNING - Try message not found.
13:11:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
13:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
13:11:34 INFO - Python 2.7.3
13:11:34 INFO - Return code: 0
13:11:34 INFO - grabbing minidump binary from tooltool
13:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:11:34 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 0x1801e60>, '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 0x1802d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18031f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
13:11:34 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
13:11:34 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
13:11:34 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
13:11:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
13:11:35 INFO - Return code: 0
13:11:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
13:11:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
13:11:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
13:11:35 INFO - ENV: RUST_BACKTRACE is now full
13:11:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
13:11:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
13:11:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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
13:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --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
13:11:35 INFO - Using env: {'DISPLAY': ':0',
13:11:35 INFO - 'HOME': '/home/cltbld',
13:11:35 INFO - 'LANG': 'en_US.UTF-8',
13:11:35 INFO - 'LANGUAGE': 'en_US:en',
13:11:35 INFO - 'LOGNAME': 'cltbld',
13:11:35 INFO - 'MAIL': '/var/mail/cltbld',
13:11:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:11:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:11:35 INFO - 'MOZ_NO_REMOTE': '1',
13:11:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:11:35 INFO - 'NO_EM_RESTART': '1',
13:11:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:11:35 INFO - 'PWD': '/builds/slave/test',
13:11:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:11:35 INFO - 'RUST_BACKTRACE': 'full',
13:11:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:11:35 INFO - 'SHELL': '/bin/bash',
13:11:35 INFO - 'SHLVL': '1',
13:11:35 INFO - 'TERM': 'linux',
13:11:35 INFO - 'TMOUT': '86400',
13:11:35 INFO - 'USER': 'cltbld',
13:11:35 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:11:35 INFO - '_': '/tools/buildbot/bin/python'}
13:11:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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
13:11:35 INFO - mozversion application_buildid: 20171026183307
13:11:35 INFO - mozversion application_changeset: 7290c8fce8a1f5c4bce930d951eaf1d6326bd788
13:11:35 INFO - mozversion application_display_name: Nightly
13:11:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
13:11:35 INFO - mozversion application_name: Firefox
13:11:35 INFO - mozversion application_remotingname: firefox
13:11:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
13:11:35 INFO - mozversion application_vendor: Mozilla
13:11:35 INFO - mozversion application_version: 58.0a1
13:11:35 INFO - mozversion platform_buildid: 20171026183307
13:11:35 INFO - mozversion platform_changeset: 7290c8fce8a1f5c4bce930d951eaf1d6326bd788
13:11:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
13:11:35 INFO - mozversion platform_version: 58.0a1
13:11:35 INFO - using testdate: 1509048695
13:11:35 INFO - actual date: 1509048695
13:11:35 INFO - starting webserver on 'localhost:39628'
13:11:35 INFO - SUITE-START | Running 2 tests
13:11:35 INFO - TEST-START | basic_compositor_video
13:11:35 INFO - Initialising browser for basic_compositor_video test...
13:11:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39628/getInfo.html -profile /tmp/tmpfkvTcp/profile
13:11:35 INFO - TEST-INFO | started process 5535 (/builds/slave/test/build/application/firefox/firefox http://localhost:39628/getInfo.html)
13:11:40 INFO - TEST-INFO | 5535: exit 0
13:11:40 INFO - Browser initialized.
13:11:40 INFO - Running cycle 1/1 for basic_compositor_video test...
13:11:40 INFO - Using env: {'DISPLAY': ':0',
13:11:40 INFO - 'HOME': '/home/cltbld',
13:11:40 INFO - 'JSGC_DISABLE_POISONING': '1',
13:11:40 INFO - 'LANG': 'en_US.UTF-8',
13:11:40 INFO - 'LANGUAGE': 'en_US:en',
13:11:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:11:40 INFO - 'LOGNAME': 'cltbld',
13:11:40 INFO - 'MAIL': '/var/mail/cltbld',
13:11:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:11:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:11:40 INFO - 'MOZ_CRASHREPORTER': '1',
13:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:11:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:11:40 INFO - 'MOZ_NO_REMOTE': '1',
13:11:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:11:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:11:40 INFO - 'NO_EM_RESTART': '1',
13:11:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:11:40 INFO - 'PWD': '/builds/slave/test',
13:11:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:11:40 INFO - 'RUST_BACKTRACE': 'full',
13:11:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:11:40 INFO - 'SHELL': '/bin/bash',
13:11:40 INFO - 'SHLVL': '1',
13:11:40 INFO - 'STYLO_FORCE_DISABLED': '1',
13:11:40 INFO - 'TERM': 'linux',
13:11:40 INFO - 'TMOUT': '86400',
13:11:40 INFO - 'USER': 'cltbld',
13:11:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:11:40 INFO - '_': '/tools/buildbot/bin/python'}
13:11:40 INFO - TEST-INFO | started process 5810 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfkvTcp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
13:11:41 INFO - PID 5810 |
13:11:41 INFO - PID 5810 | (/builds/slave/test/build/application/firefox/firefox:5861): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:11:41 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6888773213280812 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6671583333333335 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.748473193473194 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7628325991189415 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9852058432934934 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.892509727626459 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.4094943181818156 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3677356902356923 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.608093457943928 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.49619505494505 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 10.602226148409896 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 9.390704225352119 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.113907407407412 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.527552631578951 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39807851239669 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.981946107784434 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584942084942085 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.239325842696621 ms/frame
13:12:27 INFO - PID 5810 |
13:12:27 INFO - PID 5810 | Cycle 1(1): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:13:13 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6812913165266106 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6657160699417153 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7371163867979154 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7175836909871243 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 4.145145027624311 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.100102459016394 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.4489827586206894 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.373912310286677 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.749051724137933 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.636661971830985 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 10.310584192439865 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 9.392253521126758 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.11416666666667 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.870027173913039 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.449356846473021 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.053493975903642 ms/frame
13:13:13 INFO - PID 5810 |
13:13:13 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584536679536667 ms/frame
13:13:13 INFO - PID 5810 |
13:13:14 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.178547486033555 ms/frame
13:13:14 INFO - PID 5810 |
13:13:14 INFO - PID 5810 | Cycle 1(2): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:14:00 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6789591494124232 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685404503753132 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7869118522930312 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7580272407732875 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1510373443983415 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8556840077071297 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.4647690531177826 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.373052276559867 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715390476190479 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.6847443181818145 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 9.868305921052633 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 9.853817733990134 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.157286245353164 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.529342105263142 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.398636363636339 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97709580838325 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.767333333333326 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.052872928176795 ms/frame
13:14:00 INFO - PID 5810 |
13:14:00 INFO - PID 5810 | Cycle 1(3): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:14:46 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6810196078431372 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6672833333333332 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7264010356731874 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7337651646447152 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 4.138889655172413 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.167541666666667 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.44467278989667 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3574999999999995 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.597705223880595 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.571058495821733 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 10.0354347826087 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 10.207066326530608 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.241029962546829 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.640558510638275 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.347098765432104 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.76823529411764 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.363844696969698 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.817621621621633 ms/frame
13:14:46 INFO - PID 5810 |
13:14:46 INFO - PID 5810 | Cycle 1(4): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:15:32 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800363941769316 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6713826232247289 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7720378027170705 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7534662576687117 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 4.208464235624124 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1162860082304515 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.441467889908256 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3446070234113723 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.781666666666662 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634971830985918 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 10.753422939068093 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 10.42015625000001 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.031819852941158 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.310773195876294 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.397747933884316 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97883233532932 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.765862745098032 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.934726775956285 ms/frame
13:15:32 INFO - PID 5810 |
13:15:32 INFO - PID 5810 | Cycle 1(5): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:16:18 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6788724118634584 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6729431438127087 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7433730389308544 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7036541737649065 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 4.138351724137929 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.025000000000002 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.6070853365384608 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5342049469964674 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6512241054613925 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.542077562326866 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 10.20678571428572 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 9.572535885167463 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.2809962406015 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.699197860962567 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.662130801687775 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.769647058823551 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.451755725190841 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.874782608695648 ms/frame
13:16:18 INFO - PID 5810 |
13:16:18 INFO - PID 5810 | Cycle 1(6): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:16:41 INFO - PID 5810 |
13:16:41 INFO - PID 5810 | (/builds/slave/test/build/application/firefox/firefox:5940): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:16:41 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6837991021324352 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6703923205342237 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7341734104046242 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.729308556611927 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 4.066084010840109 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.177098121085595 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.4643764434180113 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.32872712146423 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6506873822975505 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.37662634408602 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 10.136418918918922 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 9.524476190476188 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.321679245283 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754408602150574 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.66185654008439 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.12415151515152 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.674688715953295 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.813432432432435 ms/frame
13:17:04 INFO - PID 5810 |
13:17:04 INFO - PID 5810 | Cycle 1(7): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:17:51 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6793536653609402 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6676125000000002 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.730083621683968 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6953093220338993 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 4.139524137931035 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.082979591836734 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.4809570765661264 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.419042735042734 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.726832061068704 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.481602739726027 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 10.06937919463087 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 10.473560209424088 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.15697026022303 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.531368421052665 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.555481171548125 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.054277108433746 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.498141762452105 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.988873626373618 ms/frame
13:17:51 INFO - PID 5810 |
13:17:51 INFO - PID 5810 | Cycle 1(8): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:18:37 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6887732132808104 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669561769616027 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7529293224299063 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6971925360474978 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 4.071356852103121 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.184843096234308 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.4211459521094634 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3618739495798358 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.64061090225564 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.556527777777774 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 9.838196721311467 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 9.856059113300505 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.280319548872189 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.815135135135112 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.604831932773097 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.050512048192783 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583899613899627 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.115583333333335 ms/frame
13:18:37 INFO - PID 5810 |
13:18:37 INFO - PID 5810 | Cycle 1(9): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:19:23 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6780872483221476 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6672791666666669 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.785145746579417 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7660988526037067 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 4.098709016393444 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.108634496919915 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.5304588235294134 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.460423875432526 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.52540515653775 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.60271708683474 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 10.00125 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 9.905767326732674 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.033437499999991 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.47246073298432 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39818181818182 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.048373493975896 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.407376425855539 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.869755434782588 ms/frame
13:19:23 INFO - PID 5810 |
13:19:23 INFO - PID 5810 | Cycle 1(10): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:20:09 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6808991596638656 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.66962020033389 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7763854351687391 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7174077253218882 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 3.979900530503978 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.184215481171549 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.4484022988505756 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.379307432432431 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.587029795158288 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.481821917808223 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 10.832111913357396 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 9.807156862745115 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.23764044943819 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.696497326203222 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.197032520325209 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.978053892215563 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.496647509578544 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.87114130434783 ms/frame
13:20:09 INFO - PID 5810 |
13:20:09 INFO - PID 5810 | Cycle 1(11): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:20:55 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6917051860202932 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6699749582637726 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7712573789846517 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7318744588744592 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_startup = 3.989773936170212 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.026086519114688 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_startup = 3.4289085714285714 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3561912751677867 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.694316888045538 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.667110481586406 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_startup = 9.615705128205123 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.480p.60fps.webm_scale_2_inclip = 9.615697115384627 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.238108614232198 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754166666666675 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659324894514777 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.273251533742336 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.45213740458015 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.753870967741943 ms/frame
13:20:55 INFO - PID 5810 |
13:20:55 INFO - PID 5810 | Cycle 1(12): loaded http://localhost:39628/tests/video/video_playback.html (next: http://localhost:39628/tests/video/video_playback.html)
13:20:56 INFO - PID 5810 | __start_tp_report
13:20:56 INFO - PID 5810 | _x_x_mozilla_page_load
13:20:56 INFO - PID 5810 | _x_x_mozilla_page_load_details
13:20:56 INFO - PID 5810 | |i|pagename|runs|
13:20:56 INFO - PID 5810 | |0;240p.120fps.mp4_scale_1_startup;1.6888773213280812;1.6812913165266106;1.6789591494124232;1.6810196078431372;1.6800363941769316;1.6788724118634584;1.6837991021324352;1.6793536653609402;1.6887732132808104;1.6780872483221476;1.6808991596638656;1.6917051860202932
13:20:56 INFO - PID 5810 | |1;240p.120fps.mp4_scale_1_inclip;1.6671583333333335;1.6657160699417153;1.6685404503753132;1.6672833333333332;1.6713826232247289;1.6729431438127087;1.6703923205342237;1.6676125000000002;1.669561769616027;1.6672791666666669;1.66962020033389;1.6699749582637726
13:20:56 INFO - PID 5810 | |2;240p.120fps.mp4_scale_1.1_startup;1.748473193473194;1.7371163867979154;1.7869118522930312;1.7264010356731874;1.7720378027170705;1.7433730389308544;1.7341734104046242;1.730083621683968;1.7529293224299063;1.785145746579417;1.7763854351687391;1.7712573789846517
13:20:56 INFO - PID 5810 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7628325991189415;1.7175836909871243;1.7580272407732875;1.7337651646447152;1.7534662576687117;1.7036541737649065;1.729308556611927;1.6953093220338993;1.6971925360474978;1.7660988526037067;1.7174077253218882;1.7318744588744592
13:20:56 INFO - PID 5810 | |4;240p.120fps.mp4_scale_2_startup;3.9852058432934934;4.145145027624311;4.1510373443983415;4.138889655172413;4.208464235624124;4.138351724137929;4.066084010840109;4.139524137931035;4.071356852103121;4.098709016393444;3.979900530503978;3.989773936170212
13:20:56 INFO - PID 5810 | |5;240p.120fps.mp4_scale_2_inclip;3.892509727626459;4.100102459016394;3.8556840077071297;4.167541666666667;4.1162860082304515;4.025000000000002;4.177098121085595;4.082979591836734;4.184843096234308;4.108634496919915;4.184215481171549;4.026086519114688
13:20:56 INFO - PID 5810 | |6;480p.60fps.webm_scale_1_startup;3.4094943181818156;3.4489827586206894;3.4647690531177826;3.44467278989667;3.441467889908256;3.6070853365384608;3.4643764434180113;3.4809570765661264;3.4211459521094634;3.5304588235294134;3.4484022988505756;3.4289085714285714
13:20:56 INFO - PID 5810 | |7;480p.60fps.webm_scale_1_inclip;3.3677356902356923;3.373912310286677;3.373052276559867;3.3574999999999995;3.3446070234113723;3.5342049469964674;3.32872712146423;3.419042735042734;3.3618739495798358;3.460423875432526;3.379307432432431;3.3561912751677867
13:20:56 INFO - PID 5810 | |8;480p.60fps.webm_scale_1.1_startup;5.608093457943928;5.749051724137933;5.715390476190479;5.597705223880595;5.781666666666662;5.6512241054613925;5.6506873822975505;5.726832061068704;5.64061090225564;5.52540515653775;5.587029795158288;5.694316888045538
13:20:56 INFO - PID 5810 | |9;480p.60fps.webm_scale_1.1_inclip;5.49619505494505;5.636661971830985;5.6847443181818145;5.571058495821733;5.634971830985918;5.542077562326866;5.37662634408602;5.481602739726027;5.556527777777774;5.60271708683474;5.481821917808223;5.667110481586406
13:20:56 INFO - PID 5810 | |10;480p.60fps.webm_scale_2_startup;10.602226148409896;10.310584192439865;9.868305921052633;10.0354347826087;10.753422939068093;10.20678571428572;10.136418918918922;10.06937919463087;9.838196721311467;10.00125;10.832111913357396;9.615705128205123
13:20:56 INFO - PID 5810 | |11;480p.60fps.webm_scale_2_inclip;9.390704225352119;9.392253521126758;9.853817733990134;10.207066326530608;10.42015625000001;9.572535885167463;9.524476190476188;10.473560209424088;9.856059113300505;9.905767326732674;9.807156862745115;9.615697115384627
13:20:56 INFO - PID 5810 | |12;1080p.60fps.mp4_scale_1_startup;11.113907407407412;11.11416666666667;11.157286245353164;11.241029962546829;11.031819852941158;11.2809962406015;11.321679245283;11.15697026022303;11.280319548872189;11.033437499999991;11.23764044943819;11.238108614232198
13:20:56 INFO - PID 5810 | |13;1080p.60fps.mp4_scale_1_inclip;10.527552631578951;10.870027173913039;10.529342105263142;10.640558510638275;10.310773195876294;10.699197860962567;10.754408602150574;10.531368421052665;10.815135135135112;10.47246073298432;10.696497326203222;10.754166666666675
13:20:56 INFO - PID 5810 | |14;1080p.60fps.mp4_scale_1.1_startup;12.39807851239669;12.449356846473021;12.398636363636339;12.347098765432104;12.397747933884316;12.662130801687775;12.66185654008439;12.555481171548125;12.604831932773097;12.39818181818182;12.197032520325209;12.659324894514777
13:20:56 INFO - PID 5810 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.981946107784434;12.053493975903642;11.97709580838325;11.76823529411764;11.97883233532932;11.769647058823551;12.12415151515152;12.054277108433746;12.050512048192783;12.048373493975896;11.978053892215563;12.273251533742336
13:20:56 INFO - PID 5810 | |16;1080p.60fps.mp4_scale_2_startup;11.584942084942085;11.584536679536667;11.767333333333326;11.363844696969698;11.765862745098032;11.451755725190841;11.674688715953295;11.498141762452105;11.583899613899627;11.407376425855539;11.496647509578544;11.45213740458015
13:20:56 INFO - PID 5810 | |17;1080p.60fps.mp4_scale_2_inclip;11.239325842696621;11.178547486033555;11.052872928176795;10.817621621621633;10.934726775956285;10.874782608695648;10.813432432432435;10.988873626373618;11.115583333333335;10.869755434782588;10.87114130434783;10.753870967741943
13:20:56 INFO - PID 5810 | __end_tp_report
13:20:56 INFO - PID 5810 | __start_cc_report
13:20:56 INFO - PID 5810 | _x_x_mozilla_cycle_collect,593
13:20:56 INFO - PID 5810 | __end_cc_report
13:20:56 INFO - PID 5810 | __startTimestamp1509049256089__endTimestamp
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | ------- Summary: start -------
13:20:56 INFO - PID 5810 | Number of tests: 18
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.00
13:20:56 INFO - PID 5810 | 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
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
13:20:56 INFO - PID 5810 | 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
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.76 Median:1.76 stddev:0.02 (1.2%) stddev-sans-first:0.02
13:20:56 INFO - PID 5810 | Values: 1.7 1.7 1.8 1.7 1.8 1.7 1.7 1.7 1.8 1.8 1.8 1.8
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.73 Median:1.73 stddev:0.03 (1.5%) stddev-sans-first:0.02
13:20:56 INFO - PID 5810 | Values: 1.8 1.7 1.8 1.7 1.8 1.7 1.7 1.7 1.7 1.8 1.7 1.7
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.09 Median:4.14 stddev:0.08 (1.8%) stddev-sans-first:0.07
13:20:56 INFO - PID 5810 | Values: 4.0 4.1 4.2 4.1 4.2 4.1 4.1 4.1 4.1 4.1 4.0 4.0
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.08 Median:4.11 stddev:0.11 (2.7%) stddev-sans-first:0.10
13:20:56 INFO - PID 5810 | Values: 3.9 4.1 3.9 4.2 4.1 4.0 4.2 4.1 4.2 4.1 4.2 4.0
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.47 Median:3.46 stddev:0.05 (1.6%) stddev-sans-first:0.05
13:20:56 INFO - PID 5810 | Values: 3.4 3.4 3.5 3.4 3.4 3.6 3.5 3.5 3.4 3.5 3.4 3.4
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.39 Median:3.37 stddev:0.06 (1.7%) stddev-sans-first:0.06
13:20:56 INFO - PID 5810 | Values: 3.4 3.4 3.4 3.4 3.3 3.5 3.3 3.4 3.4 3.5 3.4 3.4
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.66 Median:5.67 stddev:0.08 (1.3%) stddev-sans-first:0.08
13:20:56 INFO - PID 5810 | Values: 5.6 5.7 5.7 5.6 5.8 5.7 5.7 5.7 5.6 5.5 5.6 5.7
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.56 Median:5.59 stddev:0.09 (1.6%) stddev-sans-first:0.09
13:20:56 INFO - PID 5810 | Values: 5.5 5.6 5.7 5.6 5.6 5.5 5.4 5.5 5.6 5.6 5.5 5.7
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.19 Median:10.68 stddev:0.38 (3.5%) stddev-sans-first:0.37
13:20:56 INFO - PID 5810 | Values: 10.6 10.3 9.9 10.0 10.8 10.2 10.1 10.1 9.8 10.0 10.8 9.6
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.83 Median:9.59 stddev:0.37 (3.9%) stddev-sans-first:0.36
13:20:56 INFO - PID 5810 | Values: 9.4 9.4 9.9 10.2 10.4 9.6 9.5 10.5 9.9 9.9 9.8 9.6
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.18 Median:11.24 stddev:0.10 (0.9%) stddev-sans-first:0.10
13:20:56 INFO - PID 5810 | Values: 11.1 11.1 11.2 11.2 11.0 11.3 11.3 11.2 11.3 11.0 11.2 11.2
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.63 Median:10.70 stddev:0.16 (1.5%) stddev-sans-first:0.17
13:20:56 INFO - PID 5810 | Values: 10.5 10.9 10.5 10.6 10.3 10.7 10.8 10.5 10.8 10.5 10.7 10.8
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.48 Median:12.50 stddev:0.15 (1.2%) stddev-sans-first:0.15
13:20:56 INFO - PID 5810 | Values: 12.4 12.4 12.4 12.3 12.4 12.7 12.7 12.6 12.6 12.4 12.2 12.7
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.00 Median:12.05 stddev:0.14 (1.1%) stddev-sans-first:0.14
13:20:56 INFO - PID 5810 | Values: 12.0 12.1 12.0 11.8 12.0 11.8 12.1 12.1 12.1 12.0 12.0 12.3
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.55 Median:11.58 stddev:0.13 (1.1%) stddev-sans-first:0.14
13:20:56 INFO - PID 5810 | Values: 11.6 11.6 11.8 11.4 11.8 11.5 11.7 11.5 11.6 11.4 11.5 11.5
13:20:56 INFO - PID 5810 |
13:20:56 INFO - PID 5810 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.96 Median:10.96 stddev:0.16 (1.4%) stddev-sans-first:0.13
13:20:56 INFO - PID 5810 | Values: 11.2 11.2 11.1 10.8 10.9 10.9 10.8 11.0 11.1 10.9 10.9 10.8
13:20:56 INFO - PID 5810 | -------- Summary: end --------
13:20:56 INFO - PID 5810 |
13:20:56 INFO - TEST-INFO | 5810: exit 0
13:20:56 INFO - TEST-OK | basic_compositor_video | took 561178ms
13:20:56 INFO - TEST-START | glvideo
13:20:56 INFO - Initialising browser for glvideo test...
13:20:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39628/getInfo.html -profile /tmp/tmpEsH8hD/profile
13:20:56 INFO - TEST-INFO | started process 7102 (/builds/slave/test/build/application/firefox/firefox http://localhost:39628/getInfo.html)
13:21:02 INFO - TEST-INFO | 7102: exit 0
13:21:02 INFO - Browser initialized.
13:21:02 INFO - Running cycle 1/1 for glvideo test...
13:21:02 INFO - Using env: {'DISPLAY': ':0',
13:21:02 INFO - 'HOME': '/home/cltbld',
13:21:02 INFO - 'JSGC_DISABLE_POISONING': '1',
13:21:02 INFO - 'LANG': 'en_US.UTF-8',
13:21:02 INFO - 'LANGUAGE': 'en_US:en',
13:21:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:21:02 INFO - 'LOGNAME': 'cltbld',
13:21:02 INFO - 'MAIL': '/var/mail/cltbld',
13:21:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:21:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:21:02 INFO - 'MOZ_CRASHREPORTER': '1',
13:21:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:21:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:21:02 INFO - 'MOZ_NO_REMOTE': '1',
13:21:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:21:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:21:02 INFO - 'NO_EM_RESTART': '1',
13:21:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:21:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:21:02 INFO - 'PWD': '/builds/slave/test',
13:21:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:21:02 INFO - 'RUST_BACKTRACE': 'full',
13:21:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:21:02 INFO - 'SHELL': '/bin/bash',
13:21:02 INFO - 'SHLVL': '1',
13:21:02 INFO - 'STYLO_FORCE_DISABLED': '1',
13:21:02 INFO - 'TERM': 'linux',
13:21:02 INFO - 'TMOUT': '86400',
13:21:02 INFO - 'USER': 'cltbld',
13:21:02 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657',
13:21:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:21:02 INFO - '_': '/tools/buildbot/bin/python'}
13:21:02 INFO - TEST-INFO | started process 7377 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEsH8hD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
13:21:02 INFO - PID 7377 |
13:21:02 INFO - PID 7377 | (/builds/slave/test/build/application/firefox/firefox:7429): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:21:02 INFO - PID 7377 |
13:21:04 INFO - PID 7377 | [talos glvideo result] Mean tick time across 100 ticks: 7.622000000000001 ms
13:21:04 INFO - PID 7377 | Cycle 1(1): loaded http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html)
13:21:05 INFO - PID 7377 | [talos glvideo result] Mean tick time across 100 ticks: 6.4845500000000005 ms
13:21:05 INFO - PID 7377 | Cycle 1(2): loaded http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html)
13:21:07 INFO - PID 7377 | [talos glvideo result] Mean tick time across 100 ticks: 6.5188000000000015 ms
13:21:07 INFO - PID 7377 | Cycle 1(3): loaded http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html)
13:21:08 INFO - PID 7377 | [talos glvideo result] Mean tick time across 100 ticks: 6.6043 ms
13:21:08 INFO - PID 7377 | Cycle 1(4): loaded http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html)
13:21:09 INFO - PID 7377 | [talos glvideo result] Mean tick time across 100 ticks: 6.6287 ms
13:21:09 INFO - PID 7377 | Cycle 1(5): loaded http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39628/tests/webgl/benchmarks/video/video_upload.html)
13:21:10 INFO - PID 7377 | __start_tp_report
13:21:10 INFO - PID 7377 | _x_x_mozilla_page_load
13:21:10 INFO - PID 7377 | _x_x_mozilla_page_load_details
13:21:10 INFO - PID 7377 | |i|pagename|runs|
13:21:10 INFO - PID 7377 | |0;Mean tick time across 100 ticks: ;7.622000000000001;6.4845500000000005;6.5188000000000015;6.6043;6.6287
13:21:10 INFO - PID 7377 | __end_tp_report
13:21:10 INFO - PID 7377 | __start_cc_report
13:21:10 INFO - PID 7377 | _x_x_mozilla_cycle_collect,276
13:21:10 INFO - PID 7377 | __end_cc_report
13:21:10 INFO - PID 7377 | __startTimestamp1509049270129__endTimestamp
13:21:10 INFO - PID 7377 |
13:21:10 INFO - PID 7377 | ------- Summary: start -------
13:21:10 INFO - PID 7377 | Number of tests: 1
13:21:10 INFO - PID 7377 |
13:21:10 INFO - PID 7377 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.77 Median:6.60 stddev:0.48 (7.3%) stddev-sans-first:0.07
13:21:10 INFO - PID 7377 | Values: 7.6 6.5 6.5 6.6 6.6
13:21:10 INFO - PID 7377 | -------- Summary: end --------
13:21:10 INFO - PID 7377 |
13:21:10 INFO - PID 7377 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
13:21:10 INFO - TEST-INFO | 7377: exit 0
13:21:10 INFO - TEST-OK | glvideo | took 14160ms
13:21:10 INFO - SUITE-END | took 575s
13:21:11 INFO - Completed test suite (00:09:35)
13:21:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.582021579219034, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6888773213280812, 1.6812913165266106, 1.6789591494124232, 1.6810196078431372, 1.6800363941769316, 1.6788724118634584, 1.6837991021324352, 1.6793536653609402, 1.6887732132808104, 1.6780872483221476, 1.6808991596638656, 1.6917051860202932], "value": 1.6808991596638656, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6671583333333335, 1.6657160699417153, 1.6685404503753132, 1.6672833333333332, 1.6713826232247289, 1.6729431438127087, 1.6703923205342237, 1.6676125000000002, 1.669561769616027, 1.6672791666666669, 1.66962020033389, 1.6699749582637726], "value": 1.669561769616027, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.748473193473194, 1.7371163867979154, 1.7869118522930312, 1.7264010356731874, 1.7720378027170705, 1.7433730389308544, 1.7341734104046242, 1.730083621683968, 1.7529293224299063, 1.785145746579417, 1.7763854351687391, 1.7712573789846517], "value": 1.7529293224299063, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7628325991189415, 1.7175836909871243, 1.7580272407732875, 1.7337651646447152, 1.7534662576687117, 1.7036541737649065, 1.729308556611927, 1.6953093220338993, 1.6971925360474978, 1.7660988526037067, 1.7174077253218882, 1.7318744588744592], "value": 1.729308556611927, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.9852058432934934, 4.145145027624311, 4.1510373443983415, 4.138889655172413, 4.208464235624124, 4.138351724137929, 4.066084010840109, 4.139524137931035, 4.071356852103121, 4.098709016393444, 3.979900530503978, 3.989773936170212], "value": 4.138351724137929, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.892509727626459, 4.100102459016394, 3.8556840077071297, 4.167541666666667, 4.1162860082304515, 4.025000000000002, 4.177098121085595, 4.082979591836734, 4.184843096234308, 4.108634496919915, 4.184215481171549, 4.026086519114688], "value": 4.108634496919915, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4094943181818156, 3.4489827586206894, 3.4647690531177826, 3.44467278989667, 3.441467889908256, 3.6070853365384608, 3.4643764434180113, 3.4809570765661264, 3.4211459521094634, 3.5304588235294134, 3.4484022988505756, 3.4289085714285714], "value": 3.4489827586206894, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.3677356902356923, 3.373912310286677, 3.373052276559867, 3.3574999999999995, 3.3446070234113723, 3.5342049469964674, 3.32872712146423, 3.419042735042734, 3.3618739495798358, 3.460423875432526, 3.379307432432431, 3.3561912751677867], "value": 3.373052276559867, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.608093457943928, 5.749051724137933, 5.715390476190479, 5.597705223880595, 5.781666666666662, 5.6512241054613925, 5.6506873822975505, 5.726832061068704, 5.64061090225564, 5.52540515653775, 5.587029795158288, 5.694316888045538], "value": 5.6512241054613925, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.49619505494505, 5.636661971830985, 5.6847443181818145, 5.571058495821733, 5.634971830985918, 5.542077562326866, 5.37662634408602, 5.481602739726027, 5.556527777777774, 5.60271708683474, 5.481821917808223, 5.667110481586406], "value": 5.571058495821733, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.602226148409896, 10.310584192439865, 9.868305921052633, 10.0354347826087, 10.753422939068093, 10.20678571428572, 10.136418918918922, 10.06937919463087, 9.838196721311467, 10.00125, 10.832111913357396, 9.615705128205123], "value": 10.06937919463087, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.390704225352119, 9.392253521126758, 9.853817733990134, 10.207066326530608, 10.42015625000001, 9.572535885167463, 9.524476190476188, 10.473560209424088, 9.856059113300505, 9.905767326732674, 9.807156862745115, 9.615697115384627], "value": 9.853817733990134, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.113907407407412, 11.11416666666667, 11.157286245353164, 11.241029962546829, 11.031819852941158, 11.2809962406015, 11.321679245283, 11.15697026022303, 11.280319548872189, 11.033437499999991, 11.23764044943819, 11.238108614232198], "value": 11.23764044943819, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.527552631578951, 10.870027173913039, 10.529342105263142, 10.640558510638275, 10.310773195876294, 10.699197860962567, 10.754408602150574, 10.531368421052665, 10.815135135135112, 10.47246073298432, 10.696497326203222, 10.754166666666675], "value": 10.696497326203222, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.39807851239669, 12.449356846473021, 12.398636363636339, 12.347098765432104, 12.397747933884316, 12.662130801687775, 12.66185654008439, 12.555481171548125, 12.604831932773097, 12.39818181818182, 12.197032520325209, 12.659324894514777], "value": 12.449356846473021, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.981946107784434, 12.053493975903642, 11.97709580838325, 11.76823529411764, 11.97883233532932, 11.769647058823551, 12.12415151515152, 12.054277108433746, 12.050512048192783, 12.048373493975896, 11.978053892215563, 12.273251533742336], "value": 12.048373493975896, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.584942084942085, 11.584536679536667, 11.767333333333326, 11.363844696969698, 11.765862745098032, 11.451755725190841, 11.674688715953295, 11.498141762452105, 11.583899613899627, 11.407376425855539, 11.496647509578544, 11.45213740458015], "value": 11.498141762452105, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.239325842696621, 11.178547486033555, 11.052872928176795, 10.817621621621633, 10.934726775956285, 10.874782608695648, 10.813432432432435, 10.988873626373618, 11.115583333333335, 10.869755434782588, 10.87114130434783, 10.753870967741943], "value": 10.874782608695648, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.622000000000001, 6.4845500000000005, 6.5188000000000015, 6.6043, 6.6287], "value": 6.56155, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
13:21:11 INFO - Return code: 0
13:21:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
13:21:11 INFO - # TBPL SUCCESS #
13:21:11 INFO - Running post-action listener: _package_coverage_data
13:21:11 INFO - Running post-action listener: _resource_record_post_action
13:21:11 INFO - [mozharness: 2017-10-26 20:21:11.279776Z] Finished run-tests step (success)
13:21:11 INFO - Running post-run listener: _resource_record_post_run
13:21:11 INFO - Total resource usage - Wall time: 595s; CPU: 38.0%; Read bytes: 8912896; Write bytes: 434847744; Read time: 568; Write time: 355096
13:21:11 INFO - TinderboxPrint: CPU usage
38.4%
13:21:11 INFO - TinderboxPrint: I/O read bytes / time
8,912,896 / 568
13:21:11 INFO - TinderboxPrint: I/O write bytes / time
434,847,744 / 355,096
13:21:11 INFO - TinderboxPrint: CPU idle
2,874.7 (61.6%)
13:21:11 INFO - TinderboxPrint: CPU system
303.1 (6.5%)
13:21:11 INFO - TinderboxPrint: CPU user
1,473.5 (31.6%)
13:21:11 INFO - TinderboxPrint: Swap in / out
0 / 0
13:21:11 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 128303104; Read time: 0; Write time: 144840
13:21:11 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
13:21:11 INFO - run-tests - Wall time: 576s; CPU: 39.0%; Read bytes: 8912896; Write bytes: 306544640; Read time: 568; Write time: 210256
13:21:11 INFO - Running post-run listener: _upload_blobber_files
13:21:11 INFO - Blob upload gear active.
13:21:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
13:21:11 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
13:21:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
13:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
13:21:11 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
13:21:11 INFO - SNIMissingWarning
13:21:11 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
13:21:11 INFO - InsecurePlatformWarning
13:21:11 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.)
13:21:11 INFO - SubjectAltNameWarning
13:21:12 INFO - (blobuploader) - INFO - Open directory for files ...
13:21:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
13:21:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:21:12 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:21:12 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
13:21:12 INFO - InsecurePlatformWarning
13:21:12 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.)
13:21:12 INFO - SubjectAltNameWarning
13:21:12 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
13:21:12 INFO - InsecurePlatformWarning
13:21:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
13:21:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:21:13 INFO - (blobuploader) - INFO - Done attempting.
13:21:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
13:21:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:21:13 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:21:13 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
13:21:13 INFO - InsecurePlatformWarning
13:21:13 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.)
13:21:13 INFO - SubjectAltNameWarning
13:21:13 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
13:21:13 INFO - InsecurePlatformWarning
13:21:14 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
13:21:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:21:14 INFO - (blobuploader) - INFO - Done attempting.
13:21:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
13:21:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:21:14 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:21:14 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
13:21:14 INFO - InsecurePlatformWarning
13:21:14 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.)
13:21:14 INFO - SubjectAltNameWarning
13:21:19 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
13:21:19 INFO - InsecurePlatformWarning
13:21:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
13:21:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:21:19 INFO - (blobuploader) - INFO - Done attempting.
13:21:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
13:21:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:21:19 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:21:20 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
13:21:20 INFO - InsecurePlatformWarning
13:21:20 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.)
13:21:20 INFO - SubjectAltNameWarning
13:21:20 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
13:21:20 INFO - InsecurePlatformWarning
13:21:20 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
13:21:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:21:20 INFO - (blobuploader) - INFO - Done attempting.
13:21:20 INFO - (blobuploader) - INFO - Iteration through files over.
13:21:20 INFO - Return code: 0
13:21:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
13:21:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
13:21:20 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9db808945cc02df8c7c8abce77023e6c79bfe1b2bed805f32890b0478a181c79635a81505b20a836a786a56c0d7dcc55a78b7628f1a22c0c98cb1d19e9a5c8ea", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/444047c9aa596e954fe8f48b3b6571b38609f4d6a790f52bc6e8bcaf1dabb01b996d1b7c99b9ffcbd2ed2d440d907bf6cf00538ba576c126f21cef56791f8848", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1e9726683ebf761f1b238c4e1b28f429dd5c88f56e5d0d062efe1c8756a9cf1416a45f643929f8531065dd61fef868d43bc2629859ee1e17fe3f3439875de41", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b9ff4664f07fe1723a8916449d45426542e4dd6f45cd4dd43224a012f18c60586d650b2768cf21c54a38128447b88f940d5cce7024bf106cf0bf45ec7268dc9"}
13:21:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
13:21:20 INFO - Writing to file /builds/slave/test/properties/blobber_files
13:21:20 INFO - Contents:
13:21:20 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9db808945cc02df8c7c8abce77023e6c79bfe1b2bed805f32890b0478a181c79635a81505b20a836a786a56c0d7dcc55a78b7628f1a22c0c98cb1d19e9a5c8ea", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/444047c9aa596e954fe8f48b3b6571b38609f4d6a790f52bc6e8bcaf1dabb01b996d1b7c99b9ffcbd2ed2d440d907bf6cf00538ba576c126f21cef56791f8848", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1e9726683ebf761f1b238c4e1b28f429dd5c88f56e5d0d062efe1c8756a9cf1416a45f643929f8531065dd61fef868d43bc2629859ee1e17fe3f3439875de41", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b9ff4664f07fe1723a8916449d45426542e4dd6f45cd4dd43224a012f18c60586d650b2768cf21c54a38128447b88f940d5cce7024bf106cf0bf45ec7268dc9"}
13:21:20 INFO - Running post-run listener: copy_logs_to_upload_dir
13:21:20 INFO - Copying logs to upload dir...
13:21:20 INFO - mkdir: /builds/slave/test/build/upload/logs
13:21:20 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=657.296658
========= master_lag: 0.17 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 57 secs) (at 2017-10-26 13:21:21.157359) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-26 13:21:21.162623) =========
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=5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9db808945cc02df8c7c8abce77023e6c79bfe1b2bed805f32890b0478a181c79635a81505b20a836a786a56c0d7dcc55a78b7628f1a22c0c98cb1d19e9a5c8ea", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/444047c9aa596e954fe8f48b3b6571b38609f4d6a790f52bc6e8bcaf1dabb01b996d1b7c99b9ffcbd2ed2d440d907bf6cf00538ba576c126f21cef56791f8848", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1e9726683ebf761f1b238c4e1b28f429dd5c88f56e5d0d062efe1c8756a9cf1416a45f643929f8531065dd61fef868d43bc2629859ee1e17fe3f3439875de41", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b9ff4664f07fe1723a8916449d45426542e4dd6f45cd4dd43224a012f18c60586d650b2768cf21c54a38128447b88f940d5cce7024bf106cf0bf45ec7268dc9"}
build_url:https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011423
build_url: 'https://queue.taskcluster.net/v1/task/RohSkTO3QrOrc72atryY_g/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9db808945cc02df8c7c8abce77023e6c79bfe1b2bed805f32890b0478a181c79635a81505b20a836a786a56c0d7dcc55a78b7628f1a22c0c98cb1d19e9a5c8ea", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/444047c9aa596e954fe8f48b3b6571b38609f4d6a790f52bc6e8bcaf1dabb01b996d1b7c99b9ffcbd2ed2d440d907bf6cf00538ba576c126f21cef56791f8848", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1e9726683ebf761f1b238c4e1b28f429dd5c88f56e5d0d062efe1c8756a9cf1416a45f643929f8531065dd61fef868d43bc2629859ee1e17fe3f3439875de41", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b9ff4664f07fe1723a8916449d45426542e4dd6f45cd4dd43224a012f18c60586d650b2768cf21c54a38128447b88f940d5cce7024bf106cf0bf45ec7268dc9"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-26 13:21:21.192866) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 13:21:21.193204) =========
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=5ac5dba3e01a12f1ff77ab15000001b1-1509048621.28983-1104365657
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004953
========= master_lag: 0.05 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 13:21:21.247023) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-26 13:21:21.250455) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-26 13:21:21.250888) =========
========= Total master_lag: 0.31 =========