builder: mozilla-central_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-055
starttime: 1509881169.38
results: success (0)
revision: 849017ffe2976f7721c20c3625d8fafeb3cce8dd
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:09.382277) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:09.382875) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:09.383174) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:09.515801) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:09.516183) =========
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=bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-05 03:26:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 36.5M=0s
2017-11-05 03:26:09 (36.5 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.274582
========= master_lag: 0.07 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:09.859771) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:09.860087) =========
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=bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.074676
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:09.949006) =========
========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-11-05 03:26:09.949315) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 849017ffe2976f7721c20c3625d8fafeb3cce8dd --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 849017ffe2976f7721c20c3625d8fafeb3cce8dd --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=bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-05 03:26:10,003 truncating revision to first 12 chars
2017-11-05 03:26:10,003 Setting DEBUG logging.
2017-11-05 03:26:10,003 attempt 1/10
2017-11-05 03:26:10,003 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/849017ffe297?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-05 03:26:10,482 attempt 1/10
2017-11-05 03:26:11,341 current task status: no status available at this point. state: STARTED
2017-11-05 03:26:11,342 sleeping for 10.00s (attempt 1/10)
2017-11-05 03:26:21,352 attempt 2/10
2017-11-05 03:26:21,684 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS
2017-11-05 03:26:21,927 unpacking tar archive at: mozilla-central-849017ffe297/testing/mozharness/
program finished with exit code 0
elapsedTime=12.662011
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-11-05 03:26:22.628523) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:22.628817) =========
script_repo_revision: 849017ffe2976f7721c20c3625d8fafeb3cce8dd
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:22.629192) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:22.629449) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-05 03:26:22.644018) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 13 mins, 2 secs) (at 2017-11-05 03:26:22.644342) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
03:26:22 INFO - MultiFileLogger online at 20171105 03:26:22 in /builds/slave/test
03:26:22 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
03:26:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
03:26:22 INFO - {'append_to_log': False,
03:26:22 INFO - 'base_work_dir': '/builds/slave/test',
03:26:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
03:26:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
03:26:22 INFO - 'branch': 'Firefox-Non-PGO',
03:26:22 INFO - 'buildbot_json_path': 'buildprops.json',
03:26:22 INFO - 'code_coverage': False,
03:26:22 INFO - 'config_files': ('talos/linux_config.py',),
03:26:22 INFO - 'default_actions': ('clobber',
03:26:22 INFO - 'read-buildbot-config',
03:26:22 INFO - 'download-and-extract',
03:26:22 INFO - 'populate-webroot',
03:26:22 INFO - 'create-virtualenv',
03:26:22 INFO - 'install',
03:26:22 INFO - 'setup-mitmproxy',
03:26:22 INFO - 'run-tests'),
03:26:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:26:22 INFO - 'disable_ccov_upload': False,
03:26:22 INFO - 'disable_stylo': False,
03:26:22 INFO - 'download_minidump_stackwalk': True,
03:26:22 INFO - 'download_symbols': 'ondemand',
03:26:22 INFO - 'e10s': False,
03:26:22 INFO - 'enable_stylo': False,
03:26:22 INFO - 'enable_webrender': False,
03:26:22 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
03:26:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:26:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:26:22 INFO - 'gecko_profile': False,
03:26:22 INFO - 'gecko_profile_interval': 0,
03:26:22 INFO - 'installer_path': 'installer.exe',
03:26:22 INFO - 'jsd_code_coverage': False,
03:26:22 INFO - 'log_level': 'info',
03:26:22 INFO - 'log_name': 'talos',
03:26:22 INFO - 'log_to_console': True,
03:26:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
03:26:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
03:26:22 INFO - 'opt_config_files': (),
03:26:22 INFO - 'pip_index': False,
03:26:22 INFO - 'suite': 'g4-e10s',
03:26:22 INFO - 'system_bits': '32',
03:26:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:26:22 INFO - 'title': 'talos-linux64-ix-055',
03:26:22 INFO - 'tooltool_cache': '/builds/tooltool_cache',
03:26:22 INFO - 'use_talos_json': True,
03:26:22 INFO - 'verify': 'False',
03:26:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
03:26:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:26:22 INFO - 'work_dir': 'build'}
03:26:22 INFO - [mozharness: 2017-11-05 11:26:22.947905Z] Running clobber step.
03:26:22 INFO - Running pre-action listener: _resource_record_pre_action
03:26:22 INFO - Running main action method: clobber
03:26:22 INFO - rmtree: /builds/slave/test/build
03:26:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
03:26:28 INFO - Running post-action listener: _resource_record_post_action
03:26:28 INFO - [mozharness: 2017-11-05 11:26:28.407851Z] Finished clobber step (success)
03:26:28 INFO - [mozharness: 2017-11-05 11:26:28.407947Z] Running read-buildbot-config step.
03:26:28 INFO - Running pre-action listener: _resource_record_pre_action
03:26:28 INFO - Running main action method: read_buildbot_config
03:26:28 INFO - Using buildbot properties:
03:26:28 INFO - {
03:26:28 INFO - "project": "",
03:26:28 INFO - "product": "firefox",
03:26:28 INFO - "who": "archaeopteryx@coole-files.de",
03:26:28 INFO - "installer_path": "public/build/target.tar.bz2",
03:26:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
03:26:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
03:26:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-e10s",
03:26:28 INFO - "stage_platform": "linux64",
03:26:28 INFO - "basedir": "/builds/slave/test",
03:26:28 INFO - "buildnumber": 67,
03:26:28 INFO - "platform": "ubuntu64_hw",
03:26:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
03:26:28 INFO - "slavebuilddir": "test",
03:26:28 INFO - "taskId": "PIV0xagfSw6p2nnhPow2fg",
03:26:28 INFO - "branch": "mozilla-central",
03:26:28 INFO - "script_repo_revision": "production",
03:26:28 INFO - "revision": "849017ffe2976f7721c20c3625d8fafeb3cce8dd",
03:26:28 INFO - "slavename": "talos-linux64-ix-055",
03:26:28 INFO - "repo_path": "mozilla-central"
03:26:28 INFO - }
03:26:28 INFO - Finding installer, test and symbols from parent task.
03:26:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PIV0xagfSw6p2nnhPow2fg'}, attempt #1
03:26:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PIV0xagfSw6p2nnhPow2fg'}, attempt #1
03:26:29 INFO - Task dependencies: WiW2NpaHS2GBB6kFxJoLmQ
03:26:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ'}, attempt #1
03:26:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.tar.bz2
03:26:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.test_packages.json
03:26:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.crashreporter-symbols.zip
03:26:29 INFO - Running post-action listener: _resource_record_post_action
03:26:29 INFO - [mozharness: 2017-11-05 11:26:29.742021Z] Finished read-buildbot-config step (success)
03:26:29 INFO - [mozharness: 2017-11-05 11:26:29.742236Z] Running download-and-extract step.
03:26:29 INFO - Running pre-action listener: _resource_record_pre_action
03:26:29 INFO - Running main action method: download_and_extract
03:26:29 INFO - mkdir: /builds/slave/test/build/tests
03:26:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:29 INFO - trying https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.test_packages.json
03:26:29 INFO - Downloading https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
03:26:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
03:26:30 INFO - Downloaded 989 bytes.
03:26:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
03:26:30 INFO - Using the following test package requirements:
03:26:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
03:26:30 INFO - u'common': [u'target.common.tests.zip'],
03:26:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
03:26:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
03:26:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
03:26:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
03:26:30 INFO - u'mozbase': [u'target.common.tests.zip'],
03:26:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
03:26:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
03:26:30 INFO - u'web-platform': [u'target.common.tests.zip',
03:26:30 INFO - u'target.web-platform.tests.tar.gz'],
03:26:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
03:26:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
03:26:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.common.tests.zip
03:26:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
03:26:30 INFO - Fetch https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.common.tests.zip into memory
03:26:31 INFO - Content-Length response header: 57881701
03:26:31 INFO - Bytes received: 57881701
03:26:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.talos.tests.zip
03:26:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
03:26:42 INFO - Fetch https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.talos.tests.zip into memory
03:26:43 INFO - Content-Length response header: 18771919
03:26:43 INFO - Bytes received: 18771919
03:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:44 INFO - trying https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.tar.bz2
03:26:44 INFO - Downloading https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
03:26:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
03:26:45 INFO - Downloaded 62379098 bytes.
03:26:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.tar.bz2
03:26:45 INFO - mkdir: /builds/slave/test/properties
03:26:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
03:26:45 INFO - Writing to file /builds/slave/test/properties/build_url
03:26:45 INFO - Contents:
03:26:45 INFO - build_url:https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.tar.bz2
03:26:45 INFO - Running post-action listener: _resource_record_post_action
03:26:45 INFO - Running post-action listener: find_tests_for_verification
03:26:45 INFO - Running post-action listener: set_extra_try_arguments
03:26:45 INFO - [mozharness: 2017-11-05 11:26:45.207992Z] Finished download-and-extract step (success)
03:26:45 INFO - [mozharness: 2017-11-05 11:26:45.208087Z] Running populate-webroot step.
03:26:45 INFO - Running pre-action listener: _resource_record_pre_action
03:26:45 INFO - Running main action method: populate_webroot
03:26:45 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
03:26:45 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['tresize',
03:26:45 INFO - 'tcanvasmark']},
03:26:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
03:26:45 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['dromaeo_css',
03:26:45 INFO - 'kraken']},
03:26:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
03:26:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:26:45 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:26:45 INFO - 'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:26:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
03:26:45 INFO - 'tests': ['damp', 'tps']},
03:26:45 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:26:45 INFO - 'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['damp', 'tps']},
03:26:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
03:26:45 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['dromaeo_dom']},
03:26:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
03:26:45 INFO - 'glvideo',
03:26:45 INFO - 'displaylist_mutate']},
03:26:45 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['basic_compositor_video',
03:26:45 INFO - 'glvideo']},
03:26:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
03:26:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
03:26:45 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:26:45 INFO - 'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['ts_paint_webext',
03:26:45 INFO - 'tp5o_webext']},
03:26:45 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
03:26:45 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:26:45 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:26:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:26:45 INFO - 'talos_options': ['--mitmproxy',
03:26:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:26:45 INFO - '--firstNonBlankPaint'],
03:26:45 INFO - 'tests': ['tp6_google_heavy',
03:26:45 INFO - 'tp6_youtube_heavy',
03:26:45 INFO - 'tp6_amazon_heavy',
03:26:45 INFO - 'tp6_facebook_heavy']},
03:26:45 INFO - 'other-e10s': {'tests': ['a11yr',
03:26:45 INFO - 'ts_paint',
03:26:45 INFO - 'tpaint',
03:26:45 INFO - 'sessionrestore',
03:26:45 INFO - 'sessionrestore_many_windows',
03:26:45 INFO - 'sessionrestore_no_auto_restore',
03:26:45 INFO - 'tabpaint',
03:26:45 INFO - 'cpstartup']},
03:26:45 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['a11yr',
03:26:45 INFO - 'ts_paint',
03:26:45 INFO - 'tpaint',
03:26:45 INFO - 'sessionrestore',
03:26:45 INFO - 'sessionrestore_many_windows',
03:26:45 INFO - 'sessionrestore_no_auto_restore',
03:26:45 INFO - 'tabpaint',
03:26:45 INFO - 'cpstartup']},
03:26:45 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
03:26:45 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
03:26:45 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['perf_reftest_singletons']},
03:26:45 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['perf_reftest']},
03:26:45 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
03:26:45 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['speedometer']},
03:26:45 INFO - 'svgr-e10s': {'tests': ['tsvgx',
03:26:45 INFO - 'tsvgr_opacity',
03:26:45 INFO - 'tart',
03:26:45 INFO - 'tscrollx',
03:26:45 INFO - 'tsvg_static']},
03:26:45 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['tsvgx',
03:26:45 INFO - 'tsvgr_opacity',
03:26:45 INFO - 'tart',
03:26:45 INFO - 'tscrollx',
03:26:45 INFO - 'tsvg_static']},
03:26:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
03:26:45 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:26:45 INFO - 'talos_options': ['--disable-stylo'],
03:26:45 INFO - 'tests': ['tp5o']},
03:26:45 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:26:45 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:26:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:26:45 INFO - 'talos_options': ['--mitmproxy',
03:26:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:26:45 INFO - '--firstNonBlankPaint'],
03:26:45 INFO - 'tests': ['tp6_google',
03:26:45 INFO - 'tp6_youtube',
03:26:45 INFO - 'tp6_amazon',
03:26:45 INFO - 'tp6_facebook']},
03:26:45 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:26:45 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:26:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:26:45 INFO - 'talos_options': ['--disable-stylo',
03:26:45 INFO - '--mitmproxy',
03:26:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:26:45 INFO - '--firstNonBlankPaint'],
03:26:45 INFO - 'tests': ['tp6_google',
03:26:45 INFO - 'tp6_youtube',
03:26:45 INFO - 'tp6_amazon',
03:26:45 INFO - 'tp6_facebook']},
03:26:45 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:26:45 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:26:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:26:45 INFO - 'talos_options': ['--stylo-threads=1',
03:26:45 INFO - '--mitmproxy',
03:26:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:26:45 INFO - '--firstNonBlankPaint'],
03:26:45 INFO - 'tests': ['tp6_google',
03:26:45 INFO - 'tp6_youtube',
03:26:45 INFO - 'tp6_amazon',
03:26:45 INFO - 'tp6_facebook']},
03:26:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
03:26:45 INFO - 'talos_options': ['--xperf_path',
03:26:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:26:45 INFO - 'tests': ['tp5n']},
03:26:45 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:26:45 INFO - 'talos_options': ['--disable-stylo',
03:26:45 INFO - '--xperf_path',
03:26:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:26:45 INFO - 'tests': ['tp5n']}}}
03:26:45 INFO - Running post-action listener: _resource_record_post_action
03:26:45 INFO - [mozharness: 2017-11-05 11:26:45.226662Z] Finished populate-webroot step (success)
03:26:45 INFO - [mozharness: 2017-11-05 11:26:45.226760Z] Running create-virtualenv step.
03:26:45 INFO - Running pre-action listener: _resource_record_pre_action
03:26:45 INFO - Running main action method: create_virtualenv
03:26:45 INFO - Creating virtualenv /builds/slave/test/build/venv
03:26: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
03:26:45 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
03:26:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
03:26:48 INFO - Using real prefix '/usr'
03:26:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python
03:26:48 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
03:26:50 INFO - Installing setuptools, pip, wheel...done.
03:26:50 INFO - Return code: 0
03:26:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
03:26: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')]}
03:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:26: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')]}
03:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:26: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:26: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', 'pip>=1.5'] in /builds/slave/test/build
03:26: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 pip>=1.5
03:26:50 INFO - Using env: {'DISPLAY': ':0',
03:26:50 INFO - 'HOME': '/home/cltbld',
03:26:50 INFO - 'LANG': 'en_US.UTF-8',
03:26:50 INFO - 'LANGUAGE': 'en_US:en',
03:26:50 INFO - 'LOGNAME': 'cltbld',
03:26:50 INFO - 'MAIL': '/var/mail/cltbld',
03:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:26:50 INFO - 'MOZ_NO_REMOTE': '1',
03:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:26:50 INFO - 'NO_EM_RESTART': '1',
03:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:26:50 INFO - 'PWD': '/builds/slave/test',
03:26:50 INFO - 'SHELL': '/bin/bash',
03:26:50 INFO - 'SHLVL': '1',
03:26:50 INFO - 'TERM': 'linux',
03:26:50 INFO - 'TMOUT': '86400',
03:26:50 INFO - 'USER': 'cltbld',
03:26:50 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:26:50 INFO - '_': '/tools/buildbot/bin/python'}
03:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple
03:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
03:26:51 INFO - Return code: 0
03:26:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
03:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:26:51 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 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:26:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
03:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
03:26:51 INFO - Using env: {'DISPLAY': ':0',
03:26:51 INFO - 'HOME': '/home/cltbld',
03:26:51 INFO - 'LANG': 'en_US.UTF-8',
03:26:51 INFO - 'LANGUAGE': 'en_US:en',
03:26:51 INFO - 'LOGNAME': 'cltbld',
03:26:51 INFO - 'MAIL': '/var/mail/cltbld',
03:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:26:51 INFO - 'MOZ_NO_REMOTE': '1',
03:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:26:51 INFO - 'NO_EM_RESTART': '1',
03:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:26:51 INFO - 'PWD': '/builds/slave/test',
03:26:51 INFO - 'SHELL': '/bin/bash',
03:26:51 INFO - 'SHLVL': '1',
03:26:51 INFO - 'TERM': 'linux',
03:26:51 INFO - 'TMOUT': '86400',
03:26:51 INFO - 'USER': 'cltbld',
03:26:51 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:26:51 INFO - '_': '/tools/buildbot/bin/python'}
03:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple
03:26:51 INFO - Collecting psutil>=3.1.1
03:26:52 INFO - Installing collected packages: psutil
03:26:52 INFO - Successfully installed psutil-3.1.1
03:26:52 INFO - Return code: 0
03:26:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
03:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:26:52 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 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:26:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
03:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
03:26:52 INFO - Using env: {'DISPLAY': ':0',
03:26:52 INFO - 'HOME': '/home/cltbld',
03:26:52 INFO - 'LANG': 'en_US.UTF-8',
03:26:52 INFO - 'LANGUAGE': 'en_US:en',
03:26:52 INFO - 'LOGNAME': 'cltbld',
03:26:52 INFO - 'MAIL': '/var/mail/cltbld',
03:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:26:52 INFO - 'MOZ_NO_REMOTE': '1',
03:26:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:26:52 INFO - 'NO_EM_RESTART': '1',
03:26:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:26:52 INFO - 'PWD': '/builds/slave/test',
03:26:52 INFO - 'SHELL': '/bin/bash',
03:26:52 INFO - 'SHLVL': '1',
03:26:52 INFO - 'TERM': 'linux',
03:26:52 INFO - 'TMOUT': '86400',
03:26:52 INFO - 'USER': 'cltbld',
03:26:52 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:26:52 INFO - '_': '/tools/buildbot/bin/python'}
03:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple
03:26:53 INFO - Collecting mozsystemmonitor==0.3
03:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
03:26:54 INFO - Installing collected packages: mozsystemmonitor
03:26:54 INFO - Successfully installed mozsystemmonitor-0.3
03:26:54 INFO - Return code: 0
03:26:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
03:26: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')]}
03:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:26: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')]}
03:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:26: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', '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 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:26: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', 'jsonschema==2.5.1'] in /builds/slave/test/build
03:26: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 jsonschema==2.5.1
03:26:54 INFO - Using env: {'DISPLAY': ':0',
03:26:54 INFO - 'HOME': '/home/cltbld',
03:26:54 INFO - 'LANG': 'en_US.UTF-8',
03:26:54 INFO - 'LANGUAGE': 'en_US:en',
03:26:54 INFO - 'LOGNAME': 'cltbld',
03:26:54 INFO - 'MAIL': '/var/mail/cltbld',
03:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:26:54 INFO - 'MOZ_NO_REMOTE': '1',
03:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:26:54 INFO - 'NO_EM_RESTART': '1',
03:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:26:54 INFO - 'PWD': '/builds/slave/test',
03:26:54 INFO - 'SHELL': '/bin/bash',
03:26:54 INFO - 'SHLVL': '1',
03:26:54 INFO - 'TERM': 'linux',
03:26:54 INFO - 'TMOUT': '86400',
03:26:54 INFO - 'USER': 'cltbld',
03:26:54 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:26:54 INFO - '_': '/tools/buildbot/bin/python'}
03:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple
03:26:54 INFO - Collecting jsonschema==2.5.1
03:26:55 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
03:26:55 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
03:26:56 INFO - Installing collected packages: functools32, jsonschema
03:26:56 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
03:26:56 INFO - Return code: 0
03:26:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
03:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:26:56 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 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:26:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
03:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
03:26:56 INFO - Using env: {'DISPLAY': ':0',
03:26:56 INFO - 'HOME': '/home/cltbld',
03:26:56 INFO - 'LANG': 'en_US.UTF-8',
03:26:56 INFO - 'LANGUAGE': 'en_US:en',
03:26:56 INFO - 'LOGNAME': 'cltbld',
03:26:56 INFO - 'MAIL': '/var/mail/cltbld',
03:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:26:56 INFO - 'MOZ_NO_REMOTE': '1',
03:26:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:26:56 INFO - 'NO_EM_RESTART': '1',
03:26:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:26:56 INFO - 'PWD': '/builds/slave/test',
03:26:56 INFO - 'SHELL': '/bin/bash',
03:26:56 INFO - 'SHLVL': '1',
03:26:56 INFO - 'TERM': 'linux',
03:26:56 INFO - 'TMOUT': '86400',
03:26:56 INFO - 'USER': 'cltbld',
03:26:56 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:26:56 INFO - '_': '/tools/buildbot/bin/python'}
03:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple
03:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
03:26:57 INFO - Return code: 0
03:26:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
03:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:26:57 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 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:26:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
03:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
03:26:57 INFO - Using env: {'DISPLAY': ':0',
03:26:57 INFO - 'HOME': '/home/cltbld',
03:26:57 INFO - 'LANG': 'en_US.UTF-8',
03:26:57 INFO - 'LANGUAGE': 'en_US:en',
03:26:57 INFO - 'LOGNAME': 'cltbld',
03:26:57 INFO - 'MAIL': '/var/mail/cltbld',
03:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:26:57 INFO - 'MOZ_NO_REMOTE': '1',
03:26:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:26:57 INFO - 'NO_EM_RESTART': '1',
03:26:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:26:57 INFO - 'PWD': '/builds/slave/test',
03:26:57 INFO - 'SHELL': '/bin/bash',
03:26:57 INFO - 'SHLVL': '1',
03:26:57 INFO - 'TERM': 'linux',
03:26:57 INFO - 'TMOUT': '86400',
03:26:57 INFO - 'USER': 'cltbld',
03:26:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:26:57 INFO - '_': '/tools/buildbot/bin/python'}
03:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple
03:26:57 INFO - Collecting blobuploader==1.2.4
03:26:58 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
03:26:59 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
03:27:00 INFO - Installing collected packages: requests, docopt, blobuploader
03:27:01 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
03:27:01 INFO - Return code: 0
03:27:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:27:01 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 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:27:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
03:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
03:27:01 INFO - Using env: {'DISPLAY': ':0',
03:27:01 INFO - 'HOME': '/home/cltbld',
03:27:01 INFO - 'LANG': 'en_US.UTF-8',
03:27:01 INFO - 'LANGUAGE': 'en_US:en',
03:27:01 INFO - 'LOGNAME': 'cltbld',
03:27:01 INFO - 'MAIL': '/var/mail/cltbld',
03:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:27:01 INFO - 'MOZ_NO_REMOTE': '1',
03:27:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:27:01 INFO - 'NO_EM_RESTART': '1',
03:27:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:27:01 INFO - 'PWD': '/builds/slave/test',
03:27:01 INFO - 'SHELL': '/bin/bash',
03:27:01 INFO - 'SHLVL': '1',
03:27:01 INFO - 'TERM': 'linux',
03:27:01 INFO - 'TMOUT': '86400',
03:27:01 INFO - 'USER': 'cltbld',
03:27:01 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:27:01 INFO - '_': '/tools/buildbot/bin/python'}
03:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple
03:27:01 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:27:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:27:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:27:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:27:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:27:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:27:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:27:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:27:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:27:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:27:03 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:27:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:27:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:27:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:27:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:27:04 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:27:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:27:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:27:04 INFO - Running setup.py install for manifestparser: started
03:27:05 INFO - Running setup.py install for manifestparser: finished with status 'done'
03:27:05 INFO - Running setup.py install for mozcrash: started
03:27:05 INFO - Running setup.py install for mozcrash: finished with status 'done'
03:27:05 INFO - Running setup.py install for mozdebug: started
03:27:05 INFO - Running setup.py install for mozdebug: finished with status 'done'
03:27:05 INFO - Running setup.py install for mozdevice: started
03:27:05 INFO - Running setup.py install for mozdevice: finished with status 'done'
03:27:05 INFO - Running setup.py install for mozfile: started
03:27:06 INFO - Running setup.py install for mozfile: finished with status 'done'
03:27:06 INFO - Running setup.py install for mozhttpd: started
03:27:06 INFO - Running setup.py install for mozhttpd: finished with status 'done'
03:27:06 INFO - Running setup.py install for mozinfo: started
03:27:06 INFO - Running setup.py install for mozinfo: finished with status 'done'
03:27:06 INFO - Running setup.py install for mozInstall: started
03:27:06 INFO - Running setup.py install for mozInstall: finished with status 'done'
03:27:06 INFO - Running setup.py install for mozleak: started
03:27:06 INFO - Running setup.py install for mozleak: finished with status 'done'
03:27:06 INFO - Running setup.py install for mozlog: started
03:27:07 INFO - Running setup.py install for mozlog: finished with status 'done'
03:27:07 INFO - Running setup.py install for moznetwork: started
03:27:07 INFO - Running setup.py install for moznetwork: finished with status 'done'
03:27:07 INFO - Running setup.py install for mozprocess: started
03:27:07 INFO - Running setup.py install for mozprocess: finished with status 'done'
03:27:07 INFO - Running setup.py install for mozprofile: started
03:27:07 INFO - Running setup.py install for mozprofile: finished with status 'done'
03:27:07 INFO - Running setup.py install for mozrunner: started
03:27:08 INFO - Running setup.py install for mozrunner: finished with status 'done'
03:27:08 INFO - Running setup.py install for mozscreenshot: started
03:27:08 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
03:27:08 INFO - Running setup.py install for moztest: started
03:27:08 INFO - Running setup.py install for moztest: finished with status 'done'
03:27:08 INFO - Running setup.py install for mozversion: started
03:27:08 INFO - Running setup.py install for mozversion: finished with status 'done'
03:27:08 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
03:27:09 INFO - Return code: 0
03:27:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:27:09 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 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:27:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
03:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
03:27:09 INFO - Using env: {'DISPLAY': ':0',
03:27:09 INFO - 'HOME': '/home/cltbld',
03:27:09 INFO - 'LANG': 'en_US.UTF-8',
03:27:09 INFO - 'LANGUAGE': 'en_US:en',
03:27:09 INFO - 'LOGNAME': 'cltbld',
03:27:09 INFO - 'MAIL': '/var/mail/cltbld',
03:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:27:09 INFO - 'MOZ_NO_REMOTE': '1',
03:27:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:27:09 INFO - 'NO_EM_RESTART': '1',
03:27:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:27:09 INFO - 'PWD': '/builds/slave/test',
03:27:09 INFO - 'SHELL': '/bin/bash',
03:27:09 INFO - 'SHLVL': '1',
03:27:09 INFO - 'TERM': 'linux',
03:27:09 INFO - 'TMOUT': '86400',
03:27:09 INFO - 'USER': 'cltbld',
03:27:09 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:27:09 INFO - '_': '/tools/buildbot/bin/python'}
03:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple
03:27:09 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:27:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
03:27:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
03:27:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:27:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
03:27:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
03:27:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
03:27:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
03:27:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
03:27:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:27:11 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
03:27:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
03:27:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
03:27:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
03:27:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
03:27:12 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
03:27:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
03:27:12 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:27:14 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
03:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
03:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
03:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
03:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
03:27:14 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:27:15 INFO - Installing collected packages: six, blessings
03:27:15 INFO - Successfully installed blessings-1.6 six-1.10.0
03:27:15 INFO - Return code: 0
03:27:15 INFO - Done creating virtualenv /builds/slave/test/build/venv.
03:27:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:27:15 INFO - Reading from file tmpfile_stdout
03:27:15 INFO - Reading from file tmpfile_stderr
03:27:15 INFO - Current package versions:
03:27:15 INFO - blessings == 1.6
03:27:15 INFO - blobuploader == 1.2.4
03:27:15 INFO - docopt == 0.6.1
03:27:15 INFO - functools32 == 3.2.3.post2
03:27:15 INFO - jsonschema == 2.5.1
03:27:15 INFO - manifestparser == 1.2
03:27:15 INFO - mozInstall == 1.14
03:27:15 INFO - mozcrash == 1.0
03:27:15 INFO - mozdebug == 0.1
03:27:15 INFO - mozdevice == 0.51
03:27:15 INFO - mozfile == 1.2
03:27:15 INFO - mozhttpd == 0.7
03:27:15 INFO - mozinfo == 0.10
03:27:15 INFO - mozleak == 0.1
03:27:15 INFO - mozlog == 3.5
03:27:15 INFO - moznetwork == 0.27
03:27:15 INFO - mozprocess == 0.25
03:27:15 INFO - mozprofile == 0.29
03:27:15 INFO - mozrunner == 6.13
03:27:15 INFO - mozscreenshot == 0.1
03:27:15 INFO - mozsystemmonitor == 0.3
03:27:15 INFO - moztest == 0.8
03:27:15 INFO - mozversion == 1.4
03:27:15 INFO - psutil == 3.1.1
03:27:15 INFO - requests == 1.2.3
03:27:15 INFO - six == 1.10.0
03:27:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:27:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:27:15 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 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:27:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
03:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
03:27:15 INFO - Using env: {'DISPLAY': ':0',
03:27:15 INFO - 'HOME': '/home/cltbld',
03:27:15 INFO - 'LANG': 'en_US.UTF-8',
03:27:15 INFO - 'LANGUAGE': 'en_US:en',
03:27:15 INFO - 'LOGNAME': 'cltbld',
03:27:15 INFO - 'MAIL': '/var/mail/cltbld',
03:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:27:15 INFO - 'MOZ_NO_REMOTE': '1',
03:27:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:27:15 INFO - 'NO_EM_RESTART': '1',
03:27:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:27:15 INFO - 'PWD': '/builds/slave/test',
03:27:15 INFO - 'SHELL': '/bin/bash',
03:27:15 INFO - 'SHLVL': '1',
03:27:15 INFO - 'TERM': 'linux',
03:27:15 INFO - 'TMOUT': '86400',
03:27:15 INFO - 'USER': 'cltbld',
03:27:15 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:27:15 INFO - '_': '/tools/buildbot/bin/python'}
03:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple
03:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
03:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
03:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
03:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
03:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
03:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
03:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
03:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
03:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
03:27:16 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
03:27:17 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:27:18 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
03:27:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
03:27:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
03:27:18 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
03:27:18 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:27:19 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
03:27:19 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:27:20 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
03:27:20 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:27:21 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
03:27:21 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:27:22 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
03:27:22 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
03:27:23 INFO - Found existing installation: requests 1.2.3
03:27:23 INFO - Uninstalling requests-1.2.3:
03:27:23 INFO - Successfully uninstalled requests-1.2.3
03:27:23 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
03:27:23 INFO - Return code: 0
03:27:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
03:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:27:23 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 0x276f2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2947a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aea930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, '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': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:27:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
03:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
03:27:23 INFO - Using env: {'DISPLAY': ':0',
03:27:23 INFO - 'HOME': '/home/cltbld',
03:27:23 INFO - 'LANG': 'en_US.UTF-8',
03:27:23 INFO - 'LANGUAGE': 'en_US:en',
03:27:23 INFO - 'LOGNAME': 'cltbld',
03:27:23 INFO - 'MAIL': '/var/mail/cltbld',
03:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:27:23 INFO - 'MOZ_NO_REMOTE': '1',
03:27:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:27:23 INFO - 'NO_EM_RESTART': '1',
03:27:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:27:23 INFO - 'PWD': '/builds/slave/test',
03:27:23 INFO - 'SHELL': '/bin/bash',
03:27:23 INFO - 'SHLVL': '1',
03:27:23 INFO - 'TERM': 'linux',
03:27:23 INFO - 'TMOUT': '86400',
03:27:23 INFO - 'USER': 'cltbld',
03:27:23 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:27:23 INFO - '_': '/tools/buildbot/bin/python'}
03:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple
03:27:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
03:27:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
03:27:23 INFO - Return code: 0
03:27:23 INFO - Running post-action listener: _resource_record_post_action
03:27:23 INFO - Running post-action listener: _start_resource_monitoring
03:27:23 INFO - Starting resource monitoring.
03:27:23 INFO - [mozharness: 2017-11-05 11:27:23.769664Z] Finished create-virtualenv step (success)
03:27:23 INFO - [mozharness: 2017-11-05 11:27:23.770223Z] Running install step.
03:27:23 INFO - Running pre-action listener: _resource_record_pre_action
03:27:23 INFO - Running main action method: install
03:27:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:27:24 INFO - Reading from file tmpfile_stdout
03:27:24 INFO - Reading from file tmpfile_stderr
03:27:24 INFO - Detecting whether we're running mozinstall >=1.0...
03:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
03:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
03:27:24 INFO - Reading from file tmpfile_stdout
03:27:24 INFO - Output received:
03:27:24 INFO - Usage: mozinstall [options] installer
03:27:24 INFO - Options:
03:27:24 INFO - -h, --help show this help message and exit
03:27:24 INFO - -d DEST, --destination=DEST
03:27:24 INFO - Directory to install application into. [default:
03:27:24 INFO - "/builds/slave/test"]
03:27:24 INFO - --app=APP Application being installed. [default: firefox]
03:27:24 INFO - mkdir: /builds/slave/test/build/application
03:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
03:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
03:27:42 INFO - Reading from file tmpfile_stdout
03:27:42 INFO - Output received:
03:27:42 INFO - /builds/slave/test/build/application/firefox/firefox
03:27:42 INFO - Running post-action listener: _resource_record_post_action
03:27:42 INFO - [mozharness: 2017-11-05 11:27:42.686566Z] Finished install step (success)
03:27:42 INFO - [mozharness: 2017-11-05 11:27:42.686806Z] Running setup-mitmproxy step.
03:27:42 INFO - Running pre-action listener: _resource_record_pre_action
03:27:42 INFO - Running main action method: setup_mitmproxy
03:27:42 INFO - Skipping: mitmproxy is not required
03:27:42 INFO - Running post-action listener: _resource_record_post_action
03:27:42 INFO - [mozharness: 2017-11-05 11:27:42.687891Z] Finished setup-mitmproxy step (success)
03:27:42 INFO - [mozharness: 2017-11-05 11:27:42.688104Z] Running run-tests step.
03:27:42 INFO - Running pre-action listener: _resource_record_pre_action
03:27:42 INFO - Running pre-action listener: _set_gcov_prefix
03:27:42 INFO - Running main action method: run_tests
03:27:42 WARNING - Try message not found.
03:27:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
03:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
03:27:42 INFO - Python 2.7.3
03:27:42 INFO - Return code: 0
03:27:42 INFO - grabbing minidump binary from tooltool
03:27:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:27:42 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 0x2ae7ad0>, '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 0x2ae89d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae8e60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
03:27:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
03:27:42 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
03:27:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
03:27:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
03:27:42 INFO - Return code: 0
03:27:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
03:27:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
03:27:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
03:27:42 INFO - ENV: RUST_BACKTRACE is now full
03:27:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
03:27:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
03:27:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
03:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-055 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
03:27:42 INFO - Using env: {'DISPLAY': ':0',
03:27:42 INFO - 'HOME': '/home/cltbld',
03:27:42 INFO - 'LANG': 'en_US.UTF-8',
03:27:42 INFO - 'LANGUAGE': 'en_US:en',
03:27:42 INFO - 'LOGNAME': 'cltbld',
03:27:42 INFO - 'MAIL': '/var/mail/cltbld',
03:27:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:27:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:27:42 INFO - 'MOZ_NO_REMOTE': '1',
03:27:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:27:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:27:42 INFO - 'NO_EM_RESTART': '1',
03:27:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:27:42 INFO - 'PWD': '/builds/slave/test',
03:27:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:27:42 INFO - 'RUST_BACKTRACE': 'full',
03:27:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:27:42 INFO - 'SHELL': '/bin/bash',
03:27:42 INFO - 'SHLVL': '1',
03:27:42 INFO - 'TERM': 'linux',
03:27:42 INFO - 'TMOUT': '86400',
03:27:42 INFO - 'USER': 'cltbld',
03:27:42 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:27:42 INFO - '_': '/tools/buildbot/bin/python'}
03:27:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
03:27:43 INFO - mozversion application_buildid: 20171105103236
03:27:43 INFO - mozversion application_changeset: 849017ffe2976f7721c20c3625d8fafeb3cce8dd
03:27:43 INFO - mozversion application_display_name: Nightly
03:27:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:27:43 INFO - mozversion application_name: Firefox
03:27:43 INFO - mozversion application_remotingname: firefox
03:27:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
03:27:43 INFO - mozversion application_vendor: Mozilla
03:27:43 INFO - mozversion application_version: 58.0a1
03:27:43 INFO - mozversion platform_buildid: 20171105103236
03:27:43 INFO - mozversion platform_changeset: 849017ffe2976f7721c20c3625d8fafeb3cce8dd
03:27:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
03:27:43 INFO - mozversion platform_version: 58.0a1
03:27:43 INFO - using testdate: 1509881263
03:27:43 INFO - actual date: 1509881263
03:27:43 INFO - starting webserver on 'localhost:36264'
03:27:43 INFO - SUITE-START | Running 3 tests
03:27:43 INFO - TEST-START | basic_compositor_video
03:27:43 INFO - Initialising browser for basic_compositor_video test...
03:27:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36264/getInfo.html -profile /tmp/tmpi9wkvR/profile
03:27:43 INFO - TEST-INFO | started process 4023 (/builds/slave/test/build/application/firefox/firefox http://localhost:36264/getInfo.html)
03:27:52 INFO - TEST-INFO | 4023: exit 0
03:27:52 INFO - Browser initialized.
03:27:52 INFO - Running cycle 1/1 for basic_compositor_video test...
03:27:52 INFO - Using env: {'DISPLAY': ':0',
03:27:52 INFO - 'HOME': '/home/cltbld',
03:27:52 INFO - 'JSGC_DISABLE_POISONING': '1',
03:27:52 INFO - 'LANG': 'en_US.UTF-8',
03:27:52 INFO - 'LANGUAGE': 'en_US:en',
03:27:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:27:52 INFO - 'LOGNAME': 'cltbld',
03:27:52 INFO - 'MAIL': '/var/mail/cltbld',
03:27:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:27:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:27:52 INFO - 'MOZ_CRASHREPORTER': '1',
03:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:27:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:27:52 INFO - 'MOZ_NO_REMOTE': '1',
03:27:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:27:52 INFO - 'NO_EM_RESTART': '1',
03:27:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:27:52 INFO - 'PWD': '/builds/slave/test',
03:27:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:27:52 INFO - 'RUST_BACKTRACE': 'full',
03:27:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:27:52 INFO - 'SHELL': '/bin/bash',
03:27:52 INFO - 'SHLVL': '1',
03:27:52 INFO - 'TERM': 'linux',
03:27:52 INFO - 'TMOUT': '86400',
03:27:52 INFO - 'USER': 'cltbld',
03:27:52 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:27:52 INFO - '_': '/tools/buildbot/bin/python'}
03:27:52 INFO - TEST-INFO | started process 4254 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi9wkvR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
03:27:53 INFO - PID 4254 |
03:27:53 INFO - PID 4254 | (/builds/slave/test/build/application/firefox/firefox:4306): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:27:53 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6793340794627867 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6716624895572265 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7932994620442322 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7844603033006237 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.028348993288589 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.026086519114688 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.509403508771929 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4019897959183716 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.639652255639101 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.603599439775912 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 10.068506711409398 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 10.002849999999999 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.496264367816098 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.11438888888887 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.715868644067795 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.054487951807218 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.675700389105053 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.114000000000022 ms/frame
03:28:39 INFO - PID 4254 |
03:28:39 INFO - PID 4254 | Cycle 1(1): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:29:25 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6895326576576577 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.665995004163197 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7455148342059337 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7383449174630756 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.160991678224689 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9390354330708637 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.4615109573241054 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4316123499142384 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650998116760829 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.618469101123593 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 10.034113712374571 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 10.053366834170868 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.410228136882113 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81624324324327 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.397809917355358 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.977964071856286 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.495785440613032 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.054558011049723 ms/frame
03:29:25 INFO - PID 4254 |
03:29:25 INFO - PID 4254 | Cycle 1(2): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:30:11 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6819170403587445 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6696452420701167 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.80874321880651 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7781333333333338 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.2031302521008405 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.092004089979554 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.5094736842105245 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.432204116638083 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.815300387596898 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.733510028653303 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 9.741899350649343 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 9.95435323383085 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.539057692307688 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.814459459459476 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.556924686192483 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.905416666666671 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.365795454545454 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.811270270270304 ms/frame
03:30:11 INFO - PID 4254 |
03:30:11 INFO - PID 4254 | Cycle 1(3): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:30:58 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6783417225950783 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683527939949958 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7785951393005333 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.753234005258545 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 3.906640624999999 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.193721174004194 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.5508816568047346 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.467928942807624 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.66234905660377 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.732134670487104 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 10.003616666666664 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 9.805931372549018 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.28426691729324 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.929371584699437 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.145668016194344 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.628459302325588 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.768745098039211 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.174050279329643 ms/frame
03:30:58 INFO - PID 4254 |
03:30:58 INFO - PID 4254 | Cycle 1(4): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:31:44 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6818834080717489 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669858096828047 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7860803571428576 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7794217081850523 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.115994513031549 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.13314049586777 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.5721726190476213 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4981381118881076 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6832196969697 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.684318181818185 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 9.901155115511548 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 10.051507537688442 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.365530303030303 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.989697802197803 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.299467213114752 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.906607142857172 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540230769230766 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.99442307692308 ms/frame
03:31:44 INFO - PID 4254 |
03:31:44 INFO - PID 4254 | Cycle 1(5): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:32:30 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6837149270482605 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6662905911740213 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8129365558912385 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7593887423043097 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.268207681365578 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9697718253968244 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.4527560414269294 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.401624149659862 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7593857965451045 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.527582872928178 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 9.554140127388536 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 9.569354066985658 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.410722433460078 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.991950549450568 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39787190082646 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.126696969696962 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.278627819548865 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.754865591397843 ms/frame
03:32:30 INFO - PID 4254 |
03:32:30 INFO - PID 4254 | Cycle 1(6): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:32:53 INFO - PID 4254 |
03:32:53 INFO - PID 4254 | (/builds/slave/test/build/application/firefox/firefox:4384): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:32:53 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.686495222034851 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6731730769230768 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7616764533176748 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7497900262467185 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.215126404494382 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.125061855670101 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.444638346727898 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4088415672913146 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683579545454546 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.350414438502675 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 9.901633663366336 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 9.758536585365853 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.540019230769227 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.930081967213125 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.660970464135028 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90830357142858 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.719785156249998 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.054116022099478 ms/frame
03:33:16 INFO - PID 4254 |
03:33:16 INFO - PID 4254 | Cycle 1(7): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:34:02 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682135650224215 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6711403508771934 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7742430514488468 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7350346921075455 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.256695035460992 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.202216386554624 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.511192982456142 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4274486301369818 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.694326375711575 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.764971181556195 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 9.649742765273311 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 9.095704545454547 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.454141221374034 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.871956521739143 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.45035269709545 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.70248538011696 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.367670454545463 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.055193370165735 ms/frame
03:34:02 INFO - PID 4254 |
03:34:02 INFO - PID 4254 | Cycle 1(8): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:34:48 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6913754227733935 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6668124999999998 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.757125292740047 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7812466607301871 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.054621621621622 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.124628865979381 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.5262455934195076 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4322727272727263 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6931499051233345 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.666501416430599 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 9.935877483443702 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 10.258410256410272 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.495019157088125 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.86970108695653 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.660590717299568 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.05231927710842 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540269230769235 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.178994413407827 ms/frame
03:34:48 INFO - PID 4254 |
03:34:48 INFO - PID 4254 | Cycle 1(9): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:35:35 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6821692825112107 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685029190992495 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8030048076923075 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7817898486197692 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.231678420310296 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.185041841004182 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.5984172661870524 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5532149200710466 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.781368015414263 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.405878378378376 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 9.46548895899054 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 9.306279069767434 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.320905660377347 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.873885869565248 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.348477366255144 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.836834319526602 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586969111969118 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.052209944751365 ms/frame
03:35:35 INFO - PID 4254 |
03:35:35 INFO - PID 4254 | Cycle 1(10): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:36:21 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6904450704225351 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6716123642439433 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7870667063728407 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.759164467897978 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.202443977591036 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.175657620041752 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.563361045130642 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5041330998248696 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.725486641221372 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.717271428571434 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 10.171237288135591 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 9.713883495145637 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.3659090909091 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.756182795698933 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.609138655462178 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.91175595238097 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.585868725868718 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05099447513814 ms/frame
03:36:21 INFO - PID 4254 |
03:36:21 INFO - PID 4254 | Cycle 1(11): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:37:07 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6840628507295174 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6728678929765886 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.753591466978375 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7993345323741003 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_startup = 4.167166666666668 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.008216432865733 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_startup = 3.6587195121951215 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1_inclip = 3.528703703703706 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.758848368522074 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.652641242937853 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_startup = 9.436053459119488 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.480p.60fps.webm_scale_2_inclip = 9.708810679611648 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.409904942965781 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.875489130434763 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.398739669421468 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.7009941520468 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.631434108527117 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113472222222239 ms/frame
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | Cycle 1(12): loaded http://localhost:36264/tests/video/video_playback.html (next: http://localhost:36264/tests/video/video_playback.html)
03:37:07 INFO - PID 4254 | __start_tp_report
03:37:07 INFO - PID 4254 | _x_x_mozilla_page_load
03:37:07 INFO - PID 4254 | _x_x_mozilla_page_load_details
03:37:07 INFO - PID 4254 | |i|pagename|runs|
03:37:07 INFO - PID 4254 | |0;240p.120fps.mp4_scale_1_startup;1.6793340794627867;1.6895326576576577;1.6819170403587445;1.6783417225950783;1.6818834080717489;1.6837149270482605;1.686495222034851;1.682135650224215;1.6913754227733935;1.6821692825112107;1.6904450704225351;1.6840628507295174
03:37:07 INFO - PID 4254 | |1;240p.120fps.mp4_scale_1_inclip;1.6716624895572265;1.665995004163197;1.6696452420701167;1.6683527939949958;1.669858096828047;1.6662905911740213;1.6731730769230768;1.6711403508771934;1.6668124999999998;1.6685029190992495;1.6716123642439433;1.6728678929765886
03:37:07 INFO - PID 4254 | |2;240p.120fps.mp4_scale_1.1_startup;1.7932994620442322;1.7455148342059337;1.80874321880651;1.7785951393005333;1.7860803571428576;1.8129365558912385;1.7616764533176748;1.7742430514488468;1.757125292740047;1.8030048076923075;1.7870667063728407;1.753591466978375
03:37:07 INFO - PID 4254 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7844603033006237;1.7383449174630756;1.7781333333333338;1.753234005258545;1.7794217081850523;1.7593887423043097;1.7497900262467185;1.7350346921075455;1.7812466607301871;1.7817898486197692;1.759164467897978;1.7993345323741003
03:37:07 INFO - PID 4254 | |4;240p.120fps.mp4_scale_2_startup;4.028348993288589;4.160991678224689;4.2031302521008405;3.906640624999999;4.115994513031549;4.268207681365578;4.215126404494382;4.256695035460992;4.054621621621622;4.231678420310296;4.202443977591036;4.167166666666668
03:37:07 INFO - PID 4254 | |5;240p.120fps.mp4_scale_2_inclip;4.026086519114688;3.9390354330708637;4.092004089979554;4.193721174004194;4.13314049586777;3.9697718253968244;4.125061855670101;4.202216386554624;4.124628865979381;4.185041841004182;4.175657620041752;4.008216432865733
03:37:07 INFO - PID 4254 | |6;480p.60fps.webm_scale_1_startup;3.509403508771929;3.4615109573241054;3.5094736842105245;3.5508816568047346;3.5721726190476213;3.4527560414269294;3.444638346727898;3.511192982456142;3.5262455934195076;3.5984172661870524;3.563361045130642;3.6587195121951215
03:37:07 INFO - PID 4254 | |7;480p.60fps.webm_scale_1_inclip;3.4019897959183716;3.4316123499142384;3.432204116638083;3.467928942807624;3.4981381118881076;3.401624149659862;3.4088415672913146;3.4274486301369818;3.4322727272727263;3.5532149200710466;3.5041330998248696;3.528703703703706
03:37:07 INFO - PID 4254 | |8;480p.60fps.webm_scale_1.1_startup;5.639652255639101;5.650998116760829;5.815300387596898;5.66234905660377;5.6832196969697;5.7593857965451045;5.683579545454546;5.694326375711575;5.6931499051233345;5.781368015414263;5.725486641221372;5.758848368522074
03:37:07 INFO - PID 4254 | |9;480p.60fps.webm_scale_1.1_inclip;5.603599439775912;5.618469101123593;5.733510028653303;5.732134670487104;5.684318181818185;5.527582872928178;5.350414438502675;5.764971181556195;5.666501416430599;5.405878378378376;5.717271428571434;5.652641242937853
03:37:07 INFO - PID 4254 | |10;480p.60fps.webm_scale_2_startup;10.068506711409398;10.034113712374571;9.741899350649343;10.003616666666664;9.901155115511548;9.554140127388536;9.901633663366336;9.649742765273311;9.935877483443702;9.46548895899054;10.171237288135591;9.436053459119488
03:37:07 INFO - PID 4254 | |11;480p.60fps.webm_scale_2_inclip;10.002849999999999;10.053366834170868;9.95435323383085;9.805931372549018;10.051507537688442;9.569354066985658;9.758536585365853;9.095704545454547;10.258410256410272;9.306279069767434;9.713883495145637;9.708810679611648
03:37:07 INFO - PID 4254 | |12;1080p.60fps.mp4_scale_1_startup;11.496264367816098;11.410228136882113;11.539057692307688;11.28426691729324;11.365530303030303;11.410722433460078;11.540019230769227;11.454141221374034;11.495019157088125;11.320905660377347;11.3659090909091;11.409904942965781
03:37:07 INFO - PID 4254 | |13;1080p.60fps.mp4_scale_1_inclip;11.11438888888887;10.81624324324327;10.814459459459476;10.929371584699437;10.989697802197803;10.991950549450568;10.930081967213125;10.871956521739143;10.86970108695653;10.873885869565248;10.756182795698933;10.875489130434763
03:37:07 INFO - PID 4254 | |14;1080p.60fps.mp4_scale_1.1_startup;12.715868644067795;12.397809917355358;12.556924686192483;12.145668016194344;12.299467213114752;12.39787190082646;12.660970464135028;12.45035269709545;12.660590717299568;12.348477366255144;12.609138655462178;12.398739669421468
03:37:07 INFO - PID 4254 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.054487951807218;11.977964071856286;11.905416666666671;11.628459302325588;11.906607142857172;12.126696969696962;11.90830357142858;11.70248538011696;12.05231927710842;11.836834319526602;11.91175595238097;11.7009941520468
03:37:07 INFO - PID 4254 | |16;1080p.60fps.mp4_scale_2_startup;11.675700389105053;11.495785440613032;11.365795454545454;11.768745098039211;11.540230769230766;11.278627819548865;11.719785156249998;11.367670454545463;11.540269230769235;11.586969111969118;11.585868725868718;11.631434108527117
03:37:07 INFO - PID 4254 | |17;1080p.60fps.mp4_scale_2_inclip;11.114000000000022;11.054558011049723;10.811270270270304;11.174050279329643;10.99442307692308;10.754865591397843;11.054116022099478;11.055193370165735;11.178994413407827;11.052209944751365;11.05099447513814;11.113472222222239
03:37:07 INFO - PID 4254 | __end_tp_report
03:37:07 INFO - PID 4254 | __start_cc_report
03:37:07 INFO - PID 4254 | _x_x_mozilla_cycle_collect,597
03:37:07 INFO - PID 4254 | __end_cc_report
03:37:07 INFO - PID 4254 | __startTimestamp1509881827664__endTimestamp
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | ------- Summary: start -------
03:37:07 INFO - PID 4254 | Number of tests: 18
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.00
03:37:07 INFO - PID 4254 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
03:37:07 INFO - PID 4254 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.78 Median:1.79 stddev:0.02 (1.2%) stddev-sans-first:0.02
03:37:07 INFO - PID 4254 | Values: 1.8 1.7 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.77 Median:1.78 stddev:0.02 (1.1%) stddev-sans-first:0.02
03:37:07 INFO - PID 4254 | Values: 1.8 1.7 1.8 1.8 1.8 1.8 1.7 1.7 1.8 1.8 1.8 1.8
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.15 Median:4.20 stddev:0.11 (2.6%) stddev-sans-first:0.10
03:37:07 INFO - PID 4254 | Values: 4.0 4.2 4.2 3.9 4.1 4.3 4.2 4.3 4.1 4.2 4.2 4.2
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.10 Median:4.13 stddev:0.09 (2.2%) stddev-sans-first:0.09
03:37:07 INFO - PID 4254 | Values: 4.0 3.9 4.1 4.2 4.1 4.0 4.1 4.2 4.1 4.2 4.2 4.0
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.53 Median:3.54 stddev:0.06 (1.8%) stddev-sans-first:0.07
03:37:07 INFO - PID 4254 | Values: 3.5 3.5 3.5 3.6 3.6 3.5 3.4 3.5 3.5 3.6 3.6 3.7
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.46 Median:3.45 stddev:0.05 (1.5%) stddev-sans-first:0.05
03:37:07 INFO - PID 4254 | Values: 3.4 3.4 3.4 3.5 3.5 3.4 3.4 3.4 3.4 3.6 3.5 3.5
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.71 Median:5.71 stddev:0.06 (1.0%) stddev-sans-first:0.05
03:37:07 INFO - PID 4254 | Values: 5.6 5.7 5.8 5.7 5.7 5.8 5.7 5.7 5.7 5.8 5.7 5.8
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.62 Median:5.68 stddev:0.13 (2.3%) stddev-sans-first:0.14
03:37:07 INFO - PID 4254 | Values: 5.6 5.6 5.7 5.7 5.7 5.5 5.4 5.8 5.7 5.4 5.7 5.7
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.82 Median:9.60 stddev:0.25 (2.6%) stddev-sans-first:0.25
03:37:07 INFO - PID 4254 | Values: 10.1 10.0 9.7 10.0 9.9 9.6 9.9 9.6 9.9 9.5 10.2 9.4
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.77 Median:9.64 stddev:0.33 (3.4%) stddev-sans-first:0.34
03:37:07 INFO - PID 4254 | Values: 10.0 10.1 10.0 9.8 10.1 9.6 9.8 9.1 10.3 9.3 9.7 9.7
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.42 Median:11.43 stddev:0.08 (0.7%) stddev-sans-first:0.08
03:37:07 INFO - PID 4254 | Values: 11.5 11.4 11.5 11.3 11.4 11.4 11.5 11.5 11.5 11.3 11.4 11.4
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.90 Median:10.90 stddev:0.10 (0.9%) stddev-sans-first:0.07
03:37:07 INFO - PID 4254 | Values: 11.1 10.8 10.8 10.9 11.0 11.0 10.9 10.9 10.9 10.9 10.8 10.9
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.47 Median:12.50 stddev:0.17 (1.4%) stddev-sans-first:0.16
03:37:07 INFO - PID 4254 | Values: 12.7 12.4 12.6 12.1 12.3 12.4 12.7 12.5 12.7 12.3 12.6 12.4
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.89 Median:11.91 stddev:0.15 (1.3%) stddev-sans-first:0.15
03:37:07 INFO - PID 4254 | Values: 12.1 12.0 11.9 11.6 11.9 12.1 11.9 11.7 12.1 11.8 11.9 11.7
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.55 Median:11.59 stddev:0.15 (1.3%) stddev-sans-first:0.15
03:37:07 INFO - PID 4254 | Values: 11.7 11.5 11.4 11.8 11.5 11.3 11.7 11.4 11.5 11.6 11.6 11.6
03:37:07 INFO - PID 4254 |
03:37:07 INFO - PID 4254 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.03 Median:11.05 stddev:0.13 (1.2%) stddev-sans-first:0.13
03:37:07 INFO - PID 4254 | Values: 11.1 11.1 10.8 11.2 11.0 10.8 11.1 11.1 11.2 11.1 11.1 11.1
03:37:07 INFO - PID 4254 | -------- Summary: end --------
03:37:07 INFO - PID 4254 |
03:37:08 INFO - TEST-INFO | 4254: exit 0
03:37:08 INFO - TEST-OK | basic_compositor_video | took 565031ms
03:37:08 INFO - TEST-START | glvideo
03:37:08 INFO - Initialising browser for glvideo test...
03:37:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36264/getInfo.html -profile /tmp/tmpeqpDWh/profile
03:37:08 INFO - TEST-INFO | started process 5572 (/builds/slave/test/build/application/firefox/firefox http://localhost:36264/getInfo.html)
03:37:13 INFO - TEST-INFO | 5572: exit 0
03:37:13 INFO - Browser initialized.
03:37:13 INFO - Running cycle 1/1 for glvideo test...
03:37:13 INFO - Using env: {'DISPLAY': ':0',
03:37:13 INFO - 'HOME': '/home/cltbld',
03:37:13 INFO - 'JSGC_DISABLE_POISONING': '1',
03:37:13 INFO - 'LANG': 'en_US.UTF-8',
03:37:13 INFO - 'LANGUAGE': 'en_US:en',
03:37:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:37:13 INFO - 'LOGNAME': 'cltbld',
03:37:13 INFO - 'MAIL': '/var/mail/cltbld',
03:37:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:37:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:37:13 INFO - 'MOZ_CRASHREPORTER': '1',
03:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:37:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:37:13 INFO - 'MOZ_NO_REMOTE': '1',
03:37:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:37:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:37:13 INFO - 'NO_EM_RESTART': '1',
03:37:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:37:13 INFO - 'PWD': '/builds/slave/test',
03:37:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:37:13 INFO - 'RUST_BACKTRACE': 'full',
03:37:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:37:13 INFO - 'SHELL': '/bin/bash',
03:37:13 INFO - 'SHLVL': '1',
03:37:13 INFO - 'TERM': 'linux',
03:37:13 INFO - 'TMOUT': '86400',
03:37:13 INFO - 'USER': 'cltbld',
03:37:13 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:37:13 INFO - '_': '/tools/buildbot/bin/python'}
03:37:13 INFO - TEST-INFO | started process 5844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeqpDWh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:37:14 INFO - PID 5844 |
03:37:14 INFO - PID 5844 | (/builds/slave/test/build/application/firefox/firefox:5895): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:37:14 INFO - PID 5844 |
03:37:16 INFO - PID 5844 | [talos glvideo result] Mean tick time across 100 ticks: 10.85025 ms
03:37:16 INFO - PID 5844 | Cycle 1(1): loaded http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html)
03:37:17 INFO - PID 5844 | [talos glvideo result] Mean tick time across 100 ticks: 6.49165 ms
03:37:17 INFO - PID 5844 | Cycle 1(2): loaded http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html)
03:37:18 INFO - PID 5844 | [talos glvideo result] Mean tick time across 100 ticks: 6.6665 ms
03:37:18 INFO - PID 5844 | Cycle 1(3): loaded http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html)
03:37:20 INFO - PID 5844 | [talos glvideo result] Mean tick time across 100 ticks: 6.5642000000000005 ms
03:37:20 INFO - PID 5844 | Cycle 1(4): loaded http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html)
03:37:21 INFO - PID 5844 | [talos glvideo result] Mean tick time across 100 ticks: 6.5908500000000005 ms
03:37:21 INFO - PID 5844 | Cycle 1(5): loaded http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36264/tests/webgl/benchmarks/video/video_upload.html)
03:37:21 INFO - PID 5844 | __start_tp_report
03:37:21 INFO - PID 5844 | _x_x_mozilla_page_load
03:37:21 INFO - PID 5844 | _x_x_mozilla_page_load_details
03:37:21 INFO - PID 5844 | |i|pagename|runs|
03:37:21 INFO - PID 5844 | |0;Mean tick time across 100 ticks: ;10.85025;6.49165;6.6665;6.5642000000000005;6.5908500000000005
03:37:21 INFO - PID 5844 | __end_tp_report
03:37:21 INFO - PID 5844 | __start_cc_report
03:37:21 INFO - PID 5844 | _x_x_mozilla_cycle_collect,282
03:37:21 INFO - PID 5844 | __end_cc_report
03:37:21 INFO - PID 5844 | __startTimestamp1509881841818__endTimestamp
03:37:21 INFO - PID 5844 |
03:37:21 INFO - PID 5844 | ------- Summary: start -------
03:37:21 INFO - PID 5844 | Number of tests: 1
03:37:21 INFO - PID 5844 |
03:37:21 INFO - PID 5844 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:7.43 Median:6.56 stddev:1.91 (29.1%) stddev-sans-first:0.07
03:37:21 INFO - PID 5844 | Values: 10.9 6.5 6.7 6.6 6.6
03:37:21 INFO - PID 5844 | -------- Summary: end --------
03:37:21 INFO - PID 5844 |
03:37:22 INFO - PID 5844 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:37:22 INFO - TEST-INFO | 5844: exit 0
03:37:22 INFO - TEST-OK | glvideo | took 14241ms
03:37:22 INFO - TEST-START | displaylist_mutate
03:37:22 INFO - Initialising browser for displaylist_mutate test...
03:37:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36264/getInfo.html -profile /tmp/tmpY5BeP7/profile
03:37:22 INFO - TEST-INFO | started process 6068 (/builds/slave/test/build/application/firefox/firefox http://localhost:36264/getInfo.html)
03:37:27 INFO - TEST-INFO | 6068: exit 0
03:37:27 INFO - Browser initialized.
03:37:27 INFO - Running cycle 1/1 for displaylist_mutate test...
03:37:27 INFO - Using env: {'DISPLAY': ':0',
03:37:27 INFO - 'HOME': '/home/cltbld',
03:37:27 INFO - 'JSGC_DISABLE_POISONING': '1',
03:37:27 INFO - 'LANG': 'en_US.UTF-8',
03:37:27 INFO - 'LANGUAGE': 'en_US:en',
03:37:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:37:27 INFO - 'LOGNAME': 'cltbld',
03:37:27 INFO - 'MAIL': '/var/mail/cltbld',
03:37:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:37:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:37:27 INFO - 'MOZ_CRASHREPORTER': '1',
03:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:37:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:37:27 INFO - 'MOZ_NO_REMOTE': '1',
03:37:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:37:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:37:27 INFO - 'NO_EM_RESTART': '1',
03:37:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:37:27 INFO - 'PWD': '/builds/slave/test',
03:37:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:37:27 INFO - 'RUST_BACKTRACE': 'full',
03:37:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:37:27 INFO - 'SHELL': '/bin/bash',
03:37:27 INFO - 'SHLVL': '1',
03:37:27 INFO - 'TERM': 'linux',
03:37:27 INFO - 'TMOUT': '86400',
03:37:27 INFO - 'USER': 'cltbld',
03:37:27 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317',
03:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:37:27 INFO - '_': '/tools/buildbot/bin/python'}
03:37:27 INFO - TEST-INFO | started process 6335 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY5BeP7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:37:28 INFO - PID 6335 |
03:37:28 INFO - PID 6335 | (/builds/slave/test/build/application/firefox/firefox:6386): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:37:28 INFO - PID 6335 |
03:37:49 INFO - PID 6335 | Cycle 1(1): loaded http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html)
03:37:49 INFO - PID 6335 | Waiting for idle-callback
03:38:11 INFO - PID 6335 | Cycle 1(2): loaded http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html)
03:38:32 INFO - PID 6335 | Cycle 1(3): loaded http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html)
03:38:53 INFO - PID 6335 | Cycle 1(4): loaded http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html)
03:39:15 INFO - PID 6335 | Cycle 1(5): loaded http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36264/tests/layout/benchmarks/displaylist_mutate.html)
03:39:15 INFO - PID 6335 | __start_tp_report
03:39:15 INFO - PID 6335 | _x_x_mozilla_page_load
03:39:15 INFO - PID 6335 | _x_x_mozilla_page_load_details
03:39:15 INFO - PID 6335 | |i|pagename|runs|
03:39:15 INFO - PID 6335 | |0;/displaylist_mutate.html;20201.350000000002;20355.185;20636.165;20368.08;20431.595
03:39:15 INFO - PID 6335 | __end_tp_report
03:39:15 INFO - PID 6335 | __start_cc_report
03:39:15 INFO - PID 6335 | _x_x_mozilla_cycle_collect,233
03:39:15 INFO - PID 6335 | __end_cc_report
03:39:15 INFO - PID 6335 | __startTimestamp1509881955373__endTimestamp
03:39:15 INFO - PID 6335 |
03:39:15 INFO - PID 6335 | ------- Summary: start -------
03:39:15 INFO - PID 6335 | Number of tests: 1
03:39:15 INFO - PID 6335 |
03:39:15 INFO - PID 6335 | [#0] /displaylist_mutate.html Cycles:5 Average:20398.47 Median:20368.08 stddev:157.52 (0.8%) stddev-sans-first:129.97
03:39:15 INFO - PID 6335 | Values: 20201.4 20355.2 20636.2 20368.1 20431.6
03:39:15 INFO - PID 6335 | -------- Summary: end --------
03:39:15 INFO - PID 6335 |
03:39:15 INFO - TEST-INFO | 6335: exit 0
03:39:15 INFO - TEST-OK | displaylist_mutate | took 113470ms
03:39:15 INFO - SUITE-END | took 692s
03:39:16 INFO - Completed test suite (00:11:32)
03:39:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.617609702758834, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6793340794627867, 1.6895326576576577, 1.6819170403587445, 1.6783417225950783, 1.6818834080717489, 1.6837149270482605, 1.686495222034851, 1.682135650224215, 1.6913754227733935, 1.6821692825112107, 1.6904450704225351, 1.6840628507295174], "value": 1.6837149270482605, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6716624895572265, 1.665995004163197, 1.6696452420701167, 1.6683527939949958, 1.669858096828047, 1.6662905911740213, 1.6731730769230768, 1.6711403508771934, 1.6668124999999998, 1.6685029190992495, 1.6716123642439433, 1.6728678929765886], "value": 1.6696452420701167, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7932994620442322, 1.7455148342059337, 1.80874321880651, 1.7785951393005333, 1.7860803571428576, 1.8129365558912385, 1.7616764533176748, 1.7742430514488468, 1.757125292740047, 1.8030048076923075, 1.7870667063728407, 1.753591466978375], "value": 1.7785951393005333, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7844603033006237, 1.7383449174630756, 1.7781333333333338, 1.753234005258545, 1.7794217081850523, 1.7593887423043097, 1.7497900262467185, 1.7350346921075455, 1.7812466607301871, 1.7817898486197692, 1.759164467897978, 1.7993345323741003], "value": 1.7593887423043097, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.028348993288589, 4.160991678224689, 4.2031302521008405, 3.906640624999999, 4.115994513031549, 4.268207681365578, 4.215126404494382, 4.256695035460992, 4.054621621621622, 4.231678420310296, 4.202443977591036, 4.167166666666668], "value": 4.202443977591036, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.026086519114688, 3.9390354330708637, 4.092004089979554, 4.193721174004194, 4.13314049586777, 3.9697718253968244, 4.125061855670101, 4.202216386554624, 4.124628865979381, 4.185041841004182, 4.175657620041752, 4.008216432865733], "value": 4.125061855670101, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.509403508771929, 3.4615109573241054, 3.5094736842105245, 3.5508816568047346, 3.5721726190476213, 3.4527560414269294, 3.444638346727898, 3.511192982456142, 3.5262455934195076, 3.5984172661870524, 3.563361045130642, 3.6587195121951215], "value": 3.5262455934195076, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4019897959183716, 3.4316123499142384, 3.432204116638083, 3.467928942807624, 3.4981381118881076, 3.401624149659862, 3.4088415672913146, 3.4274486301369818, 3.4322727272727263, 3.5532149200710466, 3.5041330998248696, 3.528703703703706], "value": 3.4322727272727263, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.639652255639101, 5.650998116760829, 5.815300387596898, 5.66234905660377, 5.6832196969697, 5.7593857965451045, 5.683579545454546, 5.694326375711575, 5.6931499051233345, 5.781368015414263, 5.725486641221372, 5.758848368522074], "value": 5.694326375711575, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.603599439775912, 5.618469101123593, 5.733510028653303, 5.732134670487104, 5.684318181818185, 5.527582872928178, 5.350414438502675, 5.764971181556195, 5.666501416430599, 5.405878378378376, 5.717271428571434, 5.652641242937853], "value": 5.666501416430599, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.068506711409398, 10.034113712374571, 9.741899350649343, 10.003616666666664, 9.901155115511548, 9.554140127388536, 9.901633663366336, 9.649742765273311, 9.935877483443702, 9.46548895899054, 10.171237288135591, 9.436053459119488], "value": 9.901155115511548, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.002849999999999, 10.053366834170868, 9.95435323383085, 9.805931372549018, 10.051507537688442, 9.569354066985658, 9.758536585365853, 9.095704545454547, 10.258410256410272, 9.306279069767434, 9.713883495145637, 9.708810679611648], "value": 9.758536585365853, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.496264367816098, 11.410228136882113, 11.539057692307688, 11.28426691729324, 11.365530303030303, 11.410722433460078, 11.540019230769227, 11.454141221374034, 11.495019157088125, 11.320905660377347, 11.3659090909091, 11.409904942965781], "value": 11.410228136882113, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.11438888888887, 10.81624324324327, 10.814459459459476, 10.929371584699437, 10.989697802197803, 10.991950549450568, 10.930081967213125, 10.871956521739143, 10.86970108695653, 10.873885869565248, 10.756182795698933, 10.875489130434763], "value": 10.873885869565248, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.715868644067795, 12.397809917355358, 12.556924686192483, 12.145668016194344, 12.299467213114752, 12.39787190082646, 12.660970464135028, 12.45035269709545, 12.660590717299568, 12.348477366255144, 12.609138655462178, 12.398739669421468], "value": 12.398739669421468, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.054487951807218, 11.977964071856286, 11.905416666666671, 11.628459302325588, 11.906607142857172, 12.126696969696962, 11.90830357142858, 11.70248538011696, 12.05231927710842, 11.836834319526602, 11.91175595238097, 11.7009941520468], "value": 11.906607142857172, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.675700389105053, 11.495785440613032, 11.365795454545454, 11.768745098039211, 11.540230769230766, 11.278627819548865, 11.719785156249998, 11.367670454545463, 11.540269230769235, 11.586969111969118, 11.585868725868718, 11.631434108527117], "value": 11.540269230769235, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.114000000000022, 11.054558011049723, 10.811270270270304, 11.174050279329643, 10.99442307692308, 10.754865591397843, 11.054116022099478, 11.055193370165735, 11.178994413407827, 11.052209944751365, 11.05099447513814, 11.113472222222239], "value": 11.054116022099478, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.85025, 6.49165, 6.6665, 6.5642000000000005, 6.5908500000000005], "value": 6.5775250000000005, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [20201.350000000002, 20355.185, 20636.165, 20368.08, 20431.595], "value": 20399.8375, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
03:39:16 INFO - Return code: 0
03:39:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:39:16 INFO - # TBPL SUCCESS #
03:39:16 INFO - Running post-action listener: _package_coverage_data
03:39:16 INFO - Running post-action listener: _resource_record_post_action
03:39:16 INFO - [mozharness: 2017-11-05 11:39:16.183662Z] Finished run-tests step (success)
03:39:16 INFO - Running post-run listener: _resource_record_post_run
03:39:16 INFO - Total resource usage - Wall time: 712s; CPU: 35.0%; Read bytes: 19734528; Write bytes: 355352576; Read time: 5012; Write time: 235628
03:39:16 INFO - TinderboxPrint: CPU usage
34.9%
03:39:16 INFO - TinderboxPrint: I/O read bytes / time
19,734,528 / 5,012
03:39:16 INFO - TinderboxPrint: I/O write bytes / time
355,352,576 / 235,628
03:39:16 INFO - TinderboxPrint: CPU idle
3,649.3 (65.2%)
03:39:16 INFO - TinderboxPrint: CPU system
320.7 (5.7%)
03:39:16 INFO - TinderboxPrint: CPU user
1,606.6 (28.7%)
03:39:16 INFO - TinderboxPrint: Swap in / out
0 / 0
03:39:16 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 5689344; Read time: 0; Write time: 772
03:39:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:39:16 INFO - run-tests - Wall time: 693s; CPU: 35.0%; Read bytes: 18173952; Write bytes: 338784256; Read time: 4944; Write time: 233916
03:39:16 INFO - Running post-run listener: _upload_blobber_files
03:39:16 INFO - Blob upload gear active.
03:39:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:39:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
03:39:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
03:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
03:39:16 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:16 INFO - SNIMissingWarning
03:39:16 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:16 INFO - InsecurePlatformWarning
03:39:16 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:39:16 INFO - SubjectAltNameWarning
03:39:16 INFO - (blobuploader) - INFO - Open directory for files ...
03:39:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:39:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:39:16 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:39:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:17 INFO - InsecurePlatformWarning
03:39:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:39:17 INFO - SubjectAltNameWarning
03:39:22 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:22 INFO - InsecurePlatformWarning
03:39:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:39:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:39:22 INFO - (blobuploader) - INFO - Done attempting.
03:39:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:39:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:39:22 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:39:22 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:22 INFO - InsecurePlatformWarning
03:39:22 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:39:22 INFO - SubjectAltNameWarning
03:39:23 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:23 INFO - InsecurePlatformWarning
03:39:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:39:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:39:23 INFO - (blobuploader) - INFO - Done attempting.
03:39:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
03:39:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:39:23 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:39:23 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:23 INFO - InsecurePlatformWarning
03:39:23 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:39:23 INFO - SubjectAltNameWarning
03:39:23 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:23 INFO - InsecurePlatformWarning
03:39:24 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
03:39:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:39:24 INFO - (blobuploader) - INFO - Done attempting.
03:39:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
03:39:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:39:24 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:39:24 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:24 INFO - InsecurePlatformWarning
03:39:24 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:39:24 INFO - SubjectAltNameWarning
03:39:24 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:39:24 INFO - InsecurePlatformWarning
03:39:25 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
03:39:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:39:25 INFO - (blobuploader) - INFO - Done attempting.
03:39:25 INFO - (blobuploader) - INFO - Iteration through files over.
03:39:25 INFO - Return code: 0
03:39:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:39:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:39:25 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f94e3a42111a012c42e32b7433bd712d245bc99f6e89f3646e3125ee4ec04c71f3187e5a266f4296c07c8506bc6193f9617ccd9623cf78fa005ff90f20b6d30c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9b7942d2ce61fadca0fc17ecf56671b162b3caabba1c6a8e98081198fb460d24f75158680787c52f1553dc865cf9a6b79fd122455c6c25c9ac2e5c88beb9f59", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec2e2804c3da324ee1fb0128444a8cd1465ab86513e2cce1440744d77d5cdf743bea2647a91d573ab356309678ee86985b9a5d2ee90feff3aac7f9e1b537e1a1", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/016357964346c111840afb64b9eea587a082a4281fb7defe51c328d0131d92dd9277f9a4e4ca8148caef4673a7f73992db19af5aa908de0c3f133948603fb071"}
03:39:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:39:25 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:39:25 INFO - Contents:
03:39:25 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f94e3a42111a012c42e32b7433bd712d245bc99f6e89f3646e3125ee4ec04c71f3187e5a266f4296c07c8506bc6193f9617ccd9623cf78fa005ff90f20b6d30c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9b7942d2ce61fadca0fc17ecf56671b162b3caabba1c6a8e98081198fb460d24f75158680787c52f1553dc865cf9a6b79fd122455c6c25c9ac2e5c88beb9f59", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec2e2804c3da324ee1fb0128444a8cd1465ab86513e2cce1440744d77d5cdf743bea2647a91d573ab356309678ee86985b9a5d2ee90feff3aac7f9e1b537e1a1", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/016357964346c111840afb64b9eea587a082a4281fb7defe51c328d0131d92dd9277f9a4e4ca8148caef4673a7f73992db19af5aa908de0c3f133948603fb071"}
03:39:25 INFO - Running post-run listener: copy_logs_to_upload_dir
03:39:25 INFO - Copying logs to upload dir...
03:39:25 INFO - mkdir: /builds/slave/test/build/upload/logs
03:39:25 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=782.563109
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 13 mins, 2 secs) (at 2017-11-05 03:39:25.238796) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-05 03:39:25.242353) =========
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=bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f94e3a42111a012c42e32b7433bd712d245bc99f6e89f3646e3125ee4ec04c71f3187e5a266f4296c07c8506bc6193f9617ccd9623cf78fa005ff90f20b6d30c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9b7942d2ce61fadca0fc17ecf56671b162b3caabba1c6a8e98081198fb460d24f75158680787c52f1553dc865cf9a6b79fd122455c6c25c9ac2e5c88beb9f59", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec2e2804c3da324ee1fb0128444a8cd1465ab86513e2cce1440744d77d5cdf743bea2647a91d573ab356309678ee86985b9a5d2ee90feff3aac7f9e1b537e1a1", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/016357964346c111840afb64b9eea587a082a4281fb7defe51c328d0131d92dd9277f9a4e4ca8148caef4673a7f73992db19af5aa908de0c3f133948603fb071"}
build_url:https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011262
build_url: 'https://queue.taskcluster.net/v1/task/WiW2NpaHS2GBB6kFxJoLmQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f94e3a42111a012c42e32b7433bd712d245bc99f6e89f3646e3125ee4ec04c71f3187e5a266f4296c07c8506bc6193f9617ccd9623cf78fa005ff90f20b6d30c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9b7942d2ce61fadca0fc17ecf56671b162b3caabba1c6a8e98081198fb460d24f75158680787c52f1553dc865cf9a6b79fd122455c6c25c9ac2e5c88beb9f59", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec2e2804c3da324ee1fb0128444a8cd1465ab86513e2cce1440744d77d5cdf743bea2647a91d573ab356309678ee86985b9a5d2ee90feff3aac7f9e1b537e1a1", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/016357964346c111840afb64b9eea587a082a4281fb7defe51c328d0131d92dd9277f9a4e4ca8148caef4673a7f73992db19af5aa908de0c3f133948603fb071"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-05 03:39:25.271983) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-05 03:39:25.272293) =========
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=bb9b54edd4a927dc735d112f000001d9-1509864046.714926-1476033317
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004634
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-05 03:39:25.354838) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-05 03:39:25.355151) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-05 03:39:25.355433) =========
========= Total master_lag: 0.23 =========