builder: b2g-inbound_ubuntu64_hw_test-dromaeojs
slave: talos-linux64-ix-078
starttime: 1445639062.57
results: success (0)
buildid: 20151023144132
builduid: 29395a078bd44b32afd153b9a2871bd5
revision: dc2b138240dd6a23b5cedd55f41e508af1137836
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.568452) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.568905) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.569207) =========
bash -c pwd
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'pwd']
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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
/builds/slave/test
program finished with exit code 0
elapsedTime=0.004518
basedir: '/builds/slave/test'
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.652063) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.652371) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.673997) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.674298) =========
rm -rf properties
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', '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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004623
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.723121) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.723392) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.723730) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.724040) =========
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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2015-10-23 15:24:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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: 12141 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 47.3M=0s
2015-10-23 15:24:23 (47.3 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.229273
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.964756) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:22.965038) =========
rm -rf scripts
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts']
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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.014282
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.014392) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.014701) =========
bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev dc2b138240dd6a23b5cedd55f41e508af1137836 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev dc2b138240dd6a23b5cedd55f41e508af1137836 --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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2015-10-23 15:24:23,170 truncating revision to first 12 chars
2015-10-23 15:24:23,170 Setting DEBUG logging.
2015-10-23 15:24:23,170 attempt 1/10
2015-10-23 15:24:23,170 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/dc2b138240dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-10-23 15:24:23,430 unpacking tar archive at: b2g-inbound-dc2b138240dd/testing/mozharness/
program finished with exit code 0
elapsedTime=0.548573
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.577829) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.578326) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.596812) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.597376) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 15:24:23.598071) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2015-10-23 15:24:23.598558) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound-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', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'B2G-Inbound-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound-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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
15:24:23 INFO - MultiFileLogger online at 20151023 15:24:23 in /builds/slave/test
15:24:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound-Non-PGO
15:24:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
15:24:23 INFO - {'append_to_log': False,
15:24:23 INFO - 'base_work_dir': '/builds/slave/test',
15:24:23 INFO - 'blob_upload_branch': 'B2G-Inbound-Non-PGO',
15:24:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
15:24:23 INFO - 'branch': 'B2G-Inbound-Non-PGO',
15:24:23 INFO - 'buildbot_json_path': 'buildprops.json',
15:24:23 INFO - 'config_files': ('talos/linux_config.py',),
15:24:23 INFO - 'default_actions': ('clobber',
15:24:23 INFO - 'read-buildbot-config',
15:24:23 INFO - 'download-and-extract',
15:24:23 INFO - 'populate-webroot',
15:24:23 INFO - 'create-virtualenv',
15:24:23 INFO - 'install',
15:24:23 INFO - 'run-tests'),
15:24:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
15:24:23 INFO - 'download_minidump_stackwalk': True,
15:24:23 INFO - 'download_symbols': 'ondemand',
15:24:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python',
15:24:23 INFO - 'tooltool.py': '/tools/tooltool.py',
15:24:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python',
15:24:23 INFO - '/tools/misc-python/virtualenv.py')},
15:24:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
15:24:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
15:24:23 INFO - 'installer_path': 'installer.exe',
15:24:23 INFO - 'log_level': 'info',
15:24:23 INFO - 'log_name': 'talos',
15:24:23 INFO - 'log_to_console': True,
15:24:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
15:24:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
15:24:23 INFO - 'opt_config_files': (),
15:24:23 INFO - 'pip_index': False,
15:24:23 INFO - 'sps_profile': False,
15:24:23 INFO - 'sps_profile_interval': 0,
15:24:23 INFO - 'suite': 'dromaeojs',
15:24:23 INFO - 'system_bits': '64',
15:24:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
15:24:23 INFO - 'title': 'talos-linux64-ix-078',
15:24:23 INFO - 'tooltool_cache': '/builds/tooltool_cache',
15:24:23 INFO - 'use_talos_json': True,
15:24:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
15:24:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
15:24:23 INFO - 'work_dir': 'build'}
15:24:23 INFO - #####
15:24:23 INFO - ##### Running clobber step.
15:24:23 INFO - #####
15:24:23 INFO - Running pre-action listener: _resource_record_pre_action
15:24:23 INFO - Running main action method: clobber
15:24:23 INFO - rmtree: /builds/slave/test/build
15:24:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
15:24:24 INFO - Running post-action listener: _resource_record_post_action
15:24:24 INFO - #####
15:24:24 INFO - ##### Running read-buildbot-config step.
15:24:24 INFO - #####
15:24:24 INFO - Running pre-action listener: _resource_record_pre_action
15:24:24 INFO - Running main action method: read_buildbot_config
15:24:24 INFO - Using buildbot properties:
15:24:24 INFO - {
15:24:24 INFO - "properties": {
15:24:24 INFO - "buildnumber": 251,
15:24:24 INFO - "product": "firefox",
15:24:24 INFO - "script_repo_revision": "production",
15:24:24 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs",
15:24:24 INFO - "repository": "",
15:24:24 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound talos dromaeojs",
15:24:24 INFO - "buildid": "20151023144132",
15:24:24 INFO - "slavename": "talos-linux64-ix-078",
15:24:24 INFO - "pgo_build": "False",
15:24:24 INFO - "basedir": "/builds/slave/test",
15:24:24 INFO - "project": "",
15:24:24 INFO - "platform": "ubuntu64_hw",
15:24:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
15:24:24 INFO - "slavebuilddir": "test",
15:24:24 INFO - "scheduler": "tests-b2g-inbound-linux64-talos",
15:24:24 INFO - "branch": "b2g-inbound",
15:24:24 INFO - "repo_path": "integration/b2g-inbound",
15:24:24 INFO - "stage_platform": "linux64",
15:24:24 INFO - "builduid": "29395a078bd44b32afd153b9a2871bd5",
15:24:24 INFO - "revision": "dc2b138240dd6a23b5cedd55f41e508af1137836"
15:24:24 INFO - },
15:24:24 INFO - "sourcestamp": {
15:24:24 INFO - "repository": "",
15:24:24 INFO - "hasPatch": false,
15:24:24 INFO - "project": "",
15:24:24 INFO - "branch": "b2g-inbound-linux64-talos",
15:24:24 INFO - "changes": [
15:24:24 INFO - {
15:24:24 INFO - "category": null,
15:24:24 INFO - "files": [
15:24:24 INFO - {
15:24:24 INFO - "url": null,
15:24:24 INFO - "name": "https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2"
15:24:24 INFO - }
15:24:24 INFO - ],
15:24:24 INFO - "repository": "",
15:24:24 INFO - "rev": "dc2b138240dd6a23b5cedd55f41e508af1137836",
15:24:24 INFO - "who": "fdesre@mozilla.com",
15:24:24 INFO - "when": 1445639022,
15:24:24 INFO - "number": 6568383,
15:24:24 INFO - "comments": "Bug 1201685 - Limit the number of indexedDB open() calls in IndexedDBHelper r=gwagner",
15:24:24 INFO - "project": "",
15:24:24 INFO - "at": "Fri 23 Oct 2015 15:23:42",
15:24:24 INFO - "branch": "b2g-inbound-linux64-talos",
15:24:24 INFO - "revlink": "",
15:24:24 INFO - "properties": [
15:24:24 INFO - [
15:24:24 INFO - "buildid",
15:24:24 INFO - "20151023144132",
15:24:24 INFO - "Change"
15:24:24 INFO - ],
15:24:24 INFO - [
15:24:24 INFO - "builduid",
15:24:24 INFO - "29395a078bd44b32afd153b9a2871bd5",
15:24:24 INFO - "Change"
15:24:24 INFO - ],
15:24:24 INFO - [
15:24:24 INFO - "pgo_build",
15:24:24 INFO - "False",
15:24:24 INFO - "Change"
15:24:24 INFO - ]
15:24:24 INFO - ],
15:24:24 INFO - "revision": "dc2b138240dd6a23b5cedd55f41e508af1137836"
15:24:24 INFO - }
15:24:24 INFO - ],
15:24:24 INFO - "revision": "dc2b138240dd6a23b5cedd55f41e508af1137836"
15:24:24 INFO - }
15:24:24 INFO - }
15:24:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2.
15:24:24 INFO - Running post-action listener: _resource_record_post_action
15:24:24 INFO - #####
15:24:24 INFO - ##### Running download-and-extract step.
15:24:24 INFO - #####
15:24:24 INFO - Running pre-action listener: _resource_record_pre_action
15:24:24 INFO - Running main action method: download_and_extract
15:24:24 INFO - mkdir: /builds/slave/test/build/tests
15:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:24 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
15:24:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json
15:24:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json
15:24:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json
15:24:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1
15:24:25 INFO - Downloaded 1302 bytes.
15:24:25 INFO - Reading from file /builds/slave/test/build/test_packages.json
15:24:25 INFO - Using the following test package requirements:
15:24:25 INFO - {u'common': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'],
15:24:25 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip',
15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.cppunittest.tests.zip'],
15:24:25 INFO - u'jittest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip',
15:24:25 INFO - u'jsshell-linux-x86_64.zip'],
15:24:25 INFO - u'mochitest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip',
15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.mochitest.tests.zip'],
15:24:25 INFO - u'mozbase': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'],
15:24:25 INFO - u'reftest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip',
15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.reftest.tests.zip'],
15:24:25 INFO - u'talos': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip',
15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'],
15:24:25 INFO - u'web-platform': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip',
15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.web-platform.tests.zip'],
15:24:25 INFO - u'webapprt': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'],
15:24:25 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip',
15:24:25 INFO - u'firefox-44.0a1.en-US.linux-x86_64.xpcshell.tests.zip']}
15:24:25 INFO - Downloading packages: [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common
15:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:25 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net
15:24:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip
15:24:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip
15:24:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip
15:24:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1
15:24:29 INFO - Downloaded 22678713 bytes.
15:24:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests
15:24:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip
15:24:29 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760
15:24:31 INFO - Return code: 0
15:24:31 INFO - Downloading packages: [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos
15:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:31 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net
15:24:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip
15:24:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip
15:24:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip
15:24:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1
15:24:33 INFO - Downloaded 22678713 bytes.
15:24:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests
15:24:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip
15:24:33 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760
15:24:35 INFO - Return code: 0
15:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:35 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net
15:24:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip
15:24:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip
15:24:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip
15:24:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1
15:24:37 INFO - Downloaded 11131307 bytes.
15:24:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests
15:24:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip
15:24:37 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760
15:24:37 INFO - Return code: 0
15:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:37 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net
15:24:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2
15:24:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2
15:24:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2
15:24:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1
15:24:38 INFO - Downloaded 55661359 bytes.
15:24:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2
15:24:38 INFO - mkdir: /builds/slave/test/properties
15:24:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
15:24:38 INFO - Writing to file /builds/slave/test/properties/build_url
15:24:38 INFO - Contents:
15:24:38 INFO - build_url:https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2
15:24:38 INFO - Running post-action listener: _resource_record_post_action
15:24:38 INFO - Running post-action listener: set_extra_try_arguments
15:24:38 INFO - #####
15:24:38 INFO - ##### Running populate-webroot step.
15:24:38 INFO - #####
15:24:38 INFO - Running pre-action listener: _resource_record_pre_action
15:24:38 INFO - Running main action method: populate_webroot
15:24:38 INFO - Guessing talos json url...
15:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:38 INFO - https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt matches https://queue.taskcluster.net
15:24:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt
15:24:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt
15:24:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt
15:24:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt', 'file_name': '/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt'}, attempt #1
15:24:39 INFO - Downloaded 107 bytes.
15:24:39 INFO - Reading from file /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt
15:24:39 INFO - Contents:
15:24:39 INFO - 20151023144132
15:24:39 INFO - https://hg.mozilla.org/integration/b2g-inbound/rev/dc2b138240dd6a23b5cedd55f41e508af1137836
15:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:39 INFO - trying https://hg.mozilla.org/integration/b2g-inbound/raw-file/dc2b138240/testing/talos/talos.json
15:24:39 INFO - Downloading https://hg.mozilla.org/integration/b2g-inbound/raw-file/dc2b138240/testing/talos/talos.json to /builds/slave/test/build/talos.json
15:24:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/b2g-inbound/raw-file/dc2b138240/testing/talos/talos.json', 'file_name': '/builds/slave/test/build/talos.json'}, attempt #1
15:24:40 INFO - Downloaded 4264 bytes.
15:24:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']},
15:24:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']},
15:24:40 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs',
15:24:40 INFO - '../fennec_ids.txt'],
15:24:40 INFO - 'tests': ['tcheck2']},
15:24:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome',
15:24:40 INFO - '--tppagecycles',
15:24:40 INFO - '7'],
15:24:40 INFO - 'tests': ['tsvgm']}},
15:24:40 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']},
15:24:40 INFO - 'chromez-e10s': {'talos_options': ['--e10s'],
15:24:40 INFO - 'tests': ['tresize', 'tcanvasmark']},
15:24:40 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']},
15:24:40 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'],
15:24:40 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']},
15:24:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:24:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
15:24:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:24:40 INFO - 'talos_options': ['--e10s'],
15:24:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
15:24:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:24:40 INFO - 'tests': ['damp', 'tps']},
15:24:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:24:40 INFO - 'talos_options': ['--e10s'],
15:24:40 INFO - 'tests': ['damp', 'tps']},
15:24:40 INFO - 'g3': {'tests': ['dromaeo_dom']},
15:24:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
15:24:40 INFO - 'other': {'tests': ['a11yr',
15:24:40 INFO - 'ts_paint',
15:24:40 INFO - 'tpaint',
15:24:40 INFO - 'sessionrestore',
15:24:40 INFO - 'sessionrestore_no_auto_restore']},
15:24:40 INFO - 'other-e10s': {'talos_options': ['--e10s'],
15:24:40 INFO - 'tests': ['a11yr',
15:24:40 INFO - 'ts_paint',
15:24:40 INFO - 'tpaint',
15:24:40 INFO - 'sessionrestore',
15:24:40 INFO - 'sessionrestore_no_auto_restore']},
15:24:40 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'],
15:24:40 INFO - 'tests': ['a11yr',
15:24:40 INFO - 'ts_paint',
15:24:40 INFO - 'tpaint',
15:24:40 INFO - 'sessionrestore',
15:24:40 INFO - 'sessionrestore_no_auto_restore']},
15:24:40 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'],
15:24:40 INFO - 'tests': ['a11yr',
15:24:40 INFO - 'ts_paint',
15:24:40 INFO - 'tpaint',
15:24:40 INFO - 'sessionrestore',
15:24:40 INFO - 'sessionrestore_no_auto_restore']},
15:24:40 INFO - 'other_l64': {'tests': ['a11yr',
15:24:40 INFO - 'ts_paint',
15:24:40 INFO - 'tpaint',
15:24:40 INFO - 'sessionrestore',
15:24:40 INFO - 'sessionrestore_no_auto_restore']},
15:24:40 INFO - 'other_nol64': {'tests': ['a11yr',
15:24:40 INFO - 'ts_paint',
15:24:40 INFO - 'tpaint',
15:24:40 INFO - 'sessionrestore',
15:24:40 INFO - 'sessionrestore_no_auto_restore']},
15:24:40 INFO - 'svgr': {'tests': ['tsvgx',
15:24:40 INFO - 'tsvgr_opacity',
15:24:40 INFO - 'tart',
15:24:40 INFO - 'tscrollx',
15:24:40 INFO - 'cart']},
15:24:40 INFO - 'svgr-e10s': {'talos_options': ['--e10s'],
15:24:40 INFO - 'tests': ['tsvgx',
15:24:40 INFO - 'tsvgr_opacity',
15:24:40 INFO - 'tart',
15:24:40 INFO - 'tscrollx',
15:24:40 INFO - 'cart']},
15:24:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:24:40 INFO - 'tests': ['tp5o']},
15:24:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:24:40 INFO - 'talos_options': ['--e10s'],
15:24:40 INFO - 'tests': ['tp5o']},
15:24:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:24:40 INFO - 'talos_options': ['--xperf_path',
15:24:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:24:40 INFO - 'tests': ['tp5n']},
15:24:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:24:40 INFO - 'talos_options': ['--e10s',
15:24:40 INFO - '--xperf_path',
15:24:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
15:24:40 INFO - 'C:/slave/talos-data/talos/xperf.config'],
15:24:40 INFO - 'tests': ['tp5n']}},
15:24:40 INFO - 'talos.zip': {'path': '',
15:24:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
15:24:40 INFO - Running post-action listener: _resource_record_post_action
15:24:40 INFO - #####
15:24:40 INFO - ##### Running create-virtualenv step.
15:24:40 INFO - #####
15:24:40 INFO - Running pre-action listener: _resource_record_pre_action
15:24:40 INFO - Running main action method: create_virtualenv
15:24:40 INFO - Creating virtualenv /builds/slave/test/build/venv
15:24:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build
15:24:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv
15:24:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
15:24:40 INFO - Using real prefix '/usr'
15:24:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python
15:24:41 INFO - Installing distribute.............................................................................................................................................................................................done.
15:24:43 INFO - Installing pip.................done.
15:24:43 INFO - Return code: 0
15:24:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
15:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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
15:24:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build
15:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
15:24:43 INFO - Using env: {'DISPLAY': ':0',
15:24:43 INFO - 'HOME': '/home/cltbld',
15:24:43 INFO - 'LANG': 'en_US.UTF-8',
15:24:43 INFO - 'LANGUAGE': 'en_US:en',
15:24:43 INFO - 'LOGNAME': 'cltbld',
15:24:43 INFO - 'MAIL': '/var/mail/cltbld',
15:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:24:43 INFO - 'MOZ_NO_REMOTE': '1',
15:24:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:24:43 INFO - 'NO_EM_RESTART': '1',
15:24:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:24:43 INFO - 'PWD': '/builds/slave/test',
15:24:43 INFO - 'SHELL': '/bin/bash',
15:24:43 INFO - 'SHLVL': '1',
15:24:43 INFO - 'TERM': 'linux',
15:24:43 INFO - 'TMOUT': '86400',
15:24:43 INFO - 'USER': 'cltbld',
15:24:43 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536',
15:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:24:43 INFO - '_': '/tools/buildbot/bin/python'}
15:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg
15:24:44 INFO - Cleaning up...
15:24:44 INFO - Return code: 0
15:24:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv
15:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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
15:24:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build
15:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1
15:24:44 INFO - Using env: {'DISPLAY': ':0',
15:24:44 INFO - 'HOME': '/home/cltbld',
15:24:44 INFO - 'LANG': 'en_US.UTF-8',
15:24:44 INFO - 'LANGUAGE': 'en_US:en',
15:24:44 INFO - 'LOGNAME': 'cltbld',
15:24:44 INFO - 'MAIL': '/var/mail/cltbld',
15:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:24:44 INFO - 'MOZ_NO_REMOTE': '1',
15:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:24:44 INFO - 'NO_EM_RESTART': '1',
15:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:24:44 INFO - 'PWD': '/builds/slave/test',
15:24:44 INFO - 'SHELL': '/bin/bash',
15:24:44 INFO - 'SHLVL': '1',
15:24:44 INFO - 'TERM': 'linux',
15:24:44 INFO - 'TMOUT': '86400',
15:24:44 INFO - 'USER': 'cltbld',
15:24:44 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536',
15:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:24:44 INFO - '_': '/tools/buildbot/bin/python'}
15:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:24:44 INFO - Downloading/unpacking psutil>=0.7.1
15:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:46 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache
15:24:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz
15:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
15:24:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
15:24:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
15:24:46 INFO - Installing collected packages: psutil
15:24:46 INFO - Running setup.py install for psutil
15:24:46 INFO - building 'psutil._psutil_linux' extension
15:24:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o
15:24:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so
15:24:48 INFO - building 'psutil._psutil_posix' extension
15:24:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o
15:24:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so
15:24:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
15:24:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
15:24:48 INFO - Successfully installed psutil
15:24:48 INFO - Cleaning up...
15:24:48 INFO - Return code: 0
15:24:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv
15:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:24:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:24:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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
15:24:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build
15:24:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0
15:24:48 INFO - Using env: {'DISPLAY': ':0',
15:24:48 INFO - 'HOME': '/home/cltbld',
15:24:48 INFO - 'LANG': 'en_US.UTF-8',
15:24:48 INFO - 'LANGUAGE': 'en_US:en',
15:24:48 INFO - 'LOGNAME': 'cltbld',
15:24:48 INFO - 'MAIL': '/var/mail/cltbld',
15:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:24:48 INFO - 'MOZ_NO_REMOTE': '1',
15:24:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:24:48 INFO - 'NO_EM_RESTART': '1',
15:24:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:24:48 INFO - 'PWD': '/builds/slave/test',
15:24:48 INFO - 'SHELL': '/bin/bash',
15:24:48 INFO - 'SHLVL': '1',
15:24:48 INFO - 'TERM': 'linux',
15:24:48 INFO - 'TMOUT': '86400',
15:24:48 INFO - 'USER': 'cltbld',
15:24:48 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536',
15:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:24:48 INFO - '_': '/tools/buildbot/bin/python'}
15:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:24:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
15:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz
15:24:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz
15:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
15:24:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0)
15:24:50 INFO - Installing collected packages: mozsystemmonitor
15:24:50 INFO - Running setup.py install for mozsystemmonitor
15:24:50 INFO - Successfully installed mozsystemmonitor
15:24:50 INFO - Cleaning up...
15:24:50 INFO - Return code: 0
15:24:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
15:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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
15:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build
15:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
15:24:50 INFO - Using env: {'DISPLAY': ':0',
15:24:50 INFO - 'HOME': '/home/cltbld',
15:24:50 INFO - 'LANG': 'en_US.UTF-8',
15:24:50 INFO - 'LANGUAGE': 'en_US:en',
15:24:50 INFO - 'LOGNAME': 'cltbld',
15:24:50 INFO - 'MAIL': '/var/mail/cltbld',
15:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:24:50 INFO - 'MOZ_NO_REMOTE': '1',
15:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:24:50 INFO - 'NO_EM_RESTART': '1',
15:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:24:50 INFO - 'PWD': '/builds/slave/test',
15:24:50 INFO - 'SHELL': '/bin/bash',
15:24:50 INFO - 'SHLVL': '1',
15:24:50 INFO - 'TERM': 'linux',
15:24:50 INFO - 'TMOUT': '86400',
15:24:50 INFO - 'USER': 'cltbld',
15:24:50 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536',
15:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:24:50 INFO - '_': '/tools/buildbot/bin/python'}
15:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:24:50 INFO - Downloading/unpacking blobuploader==1.2.4
15:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:52 INFO - Downloading blobuploader-1.2.4.tar.gz
15:24:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz
15:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
15:24:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
15:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz
15:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
15:24:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
15:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:53 INFO - Downloading docopt-0.6.1.tar.gz
15:24:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz
15:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
15:24:53 INFO - Installing collected packages: blobuploader, requests, docopt
15:24:53 INFO - Running setup.py install for blobuploader
15:24:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
15:24:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
15:24:53 INFO - Running setup.py install for requests
15:24:53 INFO - Running setup.py install for docopt
15:24:53 INFO - Successfully installed blobuploader requests docopt
15:24:53 INFO - Cleaning up...
15:24:53 INFO - Return code: 0
15:24:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv
15:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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
15:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config
15:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
15:24:53 INFO - Using env: {'DISPLAY': ':0',
15:24:53 INFO - 'HOME': '/home/cltbld',
15:24:53 INFO - 'LANG': 'en_US.UTF-8',
15:24:53 INFO - 'LANGUAGE': 'en_US:en',
15:24:53 INFO - 'LOGNAME': 'cltbld',
15:24:53 INFO - 'MAIL': '/var/mail/cltbld',
15:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:24:53 INFO - 'MOZ_NO_REMOTE': '1',
15:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:24:53 INFO - 'NO_EM_RESTART': '1',
15:24:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:24:53 INFO - 'PWD': '/builds/slave/test',
15:24:53 INFO - 'SHELL': '/bin/bash',
15:24:53 INFO - 'SHLVL': '1',
15:24:53 INFO - 'TERM': 'linux',
15:24:53 INFO - 'TMOUT': '86400',
15:24:53 INFO - 'USER': 'cltbld',
15:24:53 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536',
15:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:24:53 INFO - '_': '/tools/buildbot/bin/python'}
15:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
15:24:54 INFO - Running setup.py (path:/tmp/pip-uDpksA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
15:24:54 INFO - Running setup.py (path:/tmp/pip-ECoLtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
15:24:54 INFO - Running setup.py (path:/tmp/pip-0Qvmt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
15:24:54 INFO - Running setup.py (path:/tmp/pip-xXjdDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
15:24:54 INFO - Running setup.py (path:/tmp/pip-pSy2Gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
15:24:54 INFO - Running setup.py (path:/tmp/pip-NYxM9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
15:24:54 INFO - Running setup.py (path:/tmp/pip-Jf8P2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
15:24:54 INFO - Running setup.py (path:/tmp/pip-KpWUmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
15:24:54 INFO - Running setup.py (path:/tmp/pip-A99iFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
15:24:54 INFO - Running setup.py (path:/tmp/pip-wycHXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
15:24:54 INFO - Running setup.py (path:/tmp/pip-Qb7im9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
15:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
15:24:54 INFO - Running setup.py (path:/tmp/pip-i7N_ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
15:24:55 INFO - Running setup.py (path:/tmp/pip-yeSzVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
15:24:55 INFO - Running setup.py (path:/tmp/pip-gifgOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
15:24:55 INFO - Running setup.py (path:/tmp/pip-acwWSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
15:24:55 INFO - Running setup.py (path:/tmp/pip-fIcZBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
15:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
15:24:55 INFO - Running setup.py (path:/tmp/pip-CB3Pvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
15:24:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
15:24:55 INFO - Running setup.py install for manifestparser
15:24:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
15:24:55 INFO - Running setup.py install for mozcrash
15:24:55 INFO - Running setup.py install for mozdebug
15:24:55 INFO - Running setup.py install for mozdevice
15:24:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin
15:24:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin
15:24:55 INFO - Running setup.py install for mozfile
15:24:55 INFO - Running setup.py install for mozhttpd
15:24:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Running setup.py install for mozinfo
15:24:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Running setup.py install for mozInstall
15:24:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Running setup.py install for mozleak
15:24:56 INFO - Running setup.py install for mozlog
15:24:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Running setup.py install for moznetwork
15:24:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Running setup.py install for mozprocess
15:24:56 INFO - Running setup.py install for mozprofile
15:24:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Running setup.py install for mozrunner
15:24:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
15:24:56 INFO - Running setup.py install for mozscreenshot
15:24:56 INFO - Running setup.py install for moztest
15:24:57 INFO - Running setup.py install for mozversion
15:24:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
15:24:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
15:24:57 INFO - Cleaning up...
15:24:57 INFO - Return code: 0
15:24:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv
15:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:24:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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
15:24:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config
15:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
15:24:57 INFO - Using env: {'DISPLAY': ':0',
15:24:57 INFO - 'HOME': '/home/cltbld',
15:24:57 INFO - 'LANG': 'en_US.UTF-8',
15:24:57 INFO - 'LANGUAGE': 'en_US:en',
15:24:57 INFO - 'LOGNAME': 'cltbld',
15:24:57 INFO - 'MAIL': '/var/mail/cltbld',
15:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:24:57 INFO - 'MOZ_NO_REMOTE': '1',
15:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:24:57 INFO - 'NO_EM_RESTART': '1',
15:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:24:57 INFO - 'PWD': '/builds/slave/test',
15:24:57 INFO - 'SHELL': '/bin/bash',
15:24:57 INFO - 'SHLVL': '1',
15:24:57 INFO - 'TERM': 'linux',
15:24:57 INFO - 'TMOUT': '86400',
15:24:57 INFO - 'USER': 'cltbld',
15:24:57 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536',
15:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:24:57 INFO - '_': '/tools/buildbot/bin/python'}
15:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
15:24:57 INFO - Running setup.py (path:/tmp/pip-1nnJIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
15:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 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))
15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
15:24:57 INFO - Running setup.py (path:/tmp/pip-NO2VbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
15:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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))
15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
15:24:57 INFO - Running setup.py (path:/tmp/pip-ytmq2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
15:24:57 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))
15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
15:24:57 INFO - Running setup.py (path:/tmp/pip-8u4yR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
15:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 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))
15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
15:24:57 INFO - Running setup.py (path:/tmp/pip-XE_LAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
15:24:57 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))
15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
15:24:57 INFO - Running setup.py (path:/tmp/pip-Ynmwls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
15:24:57 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))
15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
15:24:57 INFO - Running setup.py (path:/tmp/pip-_7u5ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
15:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 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))
15:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
15:24:57 INFO - Running setup.py (path:/tmp/pip-r33UPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
15:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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))
15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
15:24:58 INFO - Running setup.py (path:/tmp/pip-SEGP_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
15:24:58 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))
15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
15:24:58 INFO - Running setup.py (path:/tmp/pip-rrqpE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
15:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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))
15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
15:24:58 INFO - Running setup.py (path:/tmp/pip-oSXhwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
15:24:58 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))
15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
15:24:58 INFO - Running setup.py (path:/tmp/pip-YMnW_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
15:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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))
15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
15:24:58 INFO - Running setup.py (path:/tmp/pip-r1TEJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
15:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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))
15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
15:24:58 INFO - Running setup.py (path:/tmp/pip-JPGQUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
15:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 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))
15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
15:24:58 INFO - Running setup.py (path:/tmp/pip-PaZUiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
15:24:58 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))
15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
15:24:58 INFO - Running setup.py (path:/tmp/pip-Ejisy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
15:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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))
15:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
15:24:58 INFO - Running setup.py (path:/tmp/pip-Keuej5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
15:24:58 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))
15:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
15:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
15:24:58 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))
15:24:58 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.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
15:24:58 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.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
15:24:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
15:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:24:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
15:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:25:00 INFO - Downloading blessings-1.5.1.tar.gz
15:25:00 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz
15:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
15:25:00 INFO - Installing collected packages: blessings
15:25:00 INFO - Running setup.py install for blessings
15:25:00 INFO - Successfully installed blessings
15:25:00 INFO - Cleaning up...
15:25:00 INFO - Return code: 0
15:25:00 INFO - Done creating virtualenv /builds/slave/test/build/venv.
15:25:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
15:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
15:25:00 INFO - Reading from file tmpfile_stdout
15:25:00 INFO - Current package versions:
15:25:00 INFO - argparse == 1.2.1
15:25:00 INFO - blessings == 1.5.1
15:25:00 INFO - blobuploader == 1.2.4
15:25:00 INFO - docopt == 0.6.1
15:25:00 INFO - manifestparser == 1.1
15:25:00 INFO - mozInstall == 1.12
15:25:00 INFO - mozcrash == 0.16
15:25:00 INFO - mozdebug == 0.1
15:25:00 INFO - mozdevice == 0.46
15:25:00 INFO - mozfile == 1.2
15:25:00 INFO - mozhttpd == 0.7
15:25:00 INFO - mozinfo == 0.8
15:25:00 INFO - mozleak == 0.1
15:25:00 INFO - mozlog == 3.0
15:25:00 INFO - moznetwork == 0.27
15:25:00 INFO - mozprocess == 0.22
15:25:00 INFO - mozprofile == 0.27
15:25:00 INFO - mozrunner == 6.10
15:25:00 INFO - mozscreenshot == 0.1
15:25:00 INFO - mozsystemmonitor == 0.0
15:25:00 INFO - moztest == 0.7
15:25:00 INFO - mozversion == 1.4
15:25:00 INFO - psutil == 3.1.1
15:25:00 INFO - requests == 1.2.3
15:25:00 INFO - wsgiref == 0.1.2
15:25:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv
15:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
15:25:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f962a143c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23947b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2466ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, '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': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536', '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
15:25:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos
15:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
15:25:00 INFO - Using env: {'DISPLAY': ':0',
15:25:00 INFO - 'HOME': '/home/cltbld',
15:25:00 INFO - 'LANG': 'en_US.UTF-8',
15:25:00 INFO - 'LANGUAGE': 'en_US:en',
15:25:00 INFO - 'LOGNAME': 'cltbld',
15:25:00 INFO - 'MAIL': '/var/mail/cltbld',
15:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:25:00 INFO - 'MOZ_NO_REMOTE': '1',
15:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:25:00 INFO - 'NO_EM_RESTART': '1',
15:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:25:00 INFO - 'PWD': '/builds/slave/test',
15:25:00 INFO - 'SHELL': '/bin/bash',
15:25:00 INFO - 'SHLVL': '1',
15:25:00 INFO - 'TERM': 'linux',
15:25:00 INFO - 'TMOUT': '86400',
15:25:00 INFO - 'USER': 'cltbld',
15:25:00 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536',
15:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:25:00 INFO - '_': '/tools/buildbot/bin/python'}
15:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:25:01 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 1))
15:25:01 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 2))
15:25:01 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 3))
15:25:01 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 4))
15:25:01 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 5))
15:25:01 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 6))
15:25:01 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 7))
15:25:01 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 8))
15:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
15:25:01 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 3))
15:25:01 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
15:25:01 INFO - Cleaning up...
15:25:01 INFO - Return code: 0
15:25:01 INFO - Running post-action listener: _resource_record_post_action
15:25:01 INFO - Running post-action listener: _start_resource_monitoring
15:25:01 INFO - Starting resource monitoring.
15:25:01 INFO - #####
15:25:01 INFO - ##### Running install step.
15:25:01 INFO - #####
15:25:01 INFO - Running pre-action listener: _resource_record_pre_action
15:25:01 INFO - Running main action method: install
15:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
15:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
15:25:01 INFO - Reading from file tmpfile_stdout
15:25:01 INFO - Detecting whether we're running mozinstall >=1.0...
15:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
15:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
15:25:01 INFO - Reading from file tmpfile_stdout
15:25:01 INFO - Output received:
15:25:01 INFO - Usage: mozinstall [options] installer
15:25:01 INFO - Options:
15:25:01 INFO - -h, --help show this help message and exit
15:25:01 INFO - -d DEST, --destination=DEST
15:25:01 INFO - Directory to install application into. [default:
15:25:01 INFO - "/builds/slave/test"]
15:25:01 INFO - --app=APP Application being installed. [default: firefox]
15:25:01 INFO - mkdir: /builds/slave/test/build/application
15:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application']
15:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application
15:25:15 INFO - Reading from file tmpfile_stdout
15:25:15 INFO - Output received:
15:25:15 INFO - /builds/slave/test/build/application/firefox/firefox
15:25:15 INFO - Running post-action listener: _resource_record_post_action
15:25:15 INFO - #####
15:25:15 INFO - ##### Running run-tests step.
15:25:15 INFO - #####
15:25:15 INFO - Running pre-action listener: _resource_record_pre_action
15:25:15 INFO - Running main action method: run_tests
15:25:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
15:25:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
15:25:15 INFO - Python 2.7.3
15:25:15 INFO - Return code: 0
15:25:15 INFO - grabbing minidump binary from tooltool
15:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:25:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fba30>, '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 0x24650b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2465540>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
15:25:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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
15:25:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
15:25:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
15:25:15 INFO - Return code: 0
15:25:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
15:25:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
15:25:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
15:25:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
15:25:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
15:25:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--webServer', 'localhost'] in /builds/slave/test/build
15:25:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName B2G-Inbound-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-078 --webServer localhost
15:25:15 INFO - Using env: {'DISPLAY': ':0',
15:25:15 INFO - 'HOME': '/home/cltbld',
15:25:15 INFO - 'LANG': 'en_US.UTF-8',
15:25:15 INFO - 'LANGUAGE': 'en_US:en',
15:25:15 INFO - 'LOGNAME': 'cltbld',
15:25:15 INFO - 'MAIL': '/var/mail/cltbld',
15:25:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
15:25:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
15:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:25:15 INFO - 'MOZ_NO_REMOTE': '1',
15:25:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
15:25:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:25:15 INFO - 'NO_EM_RESTART': '1',
15:25:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:25:15 INFO - 'PWD': '/builds/slave/test',
15:25:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
15:25:15 INFO - 'SHELL': '/bin/bash',
15:25:15 INFO - 'SHLVL': '1',
15:25:15 INFO - 'TERM': 'linux',
15:25:15 INFO - 'TMOUT': '86400',
15:25:15 INFO - 'USER': 'cltbld',
15:25:15 INFO - 'XDG_SESSION_COOKIE': '0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536',
15:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:25:15 INFO - '_': '/tools/buildbot/bin/python'}
15:25:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--webServer', 'localhost'] with output_timeout 3600
15:25:15 INFO - mozversion INFO | application_buildid: 20151023144132
15:25:15 INFO - mozversion INFO | application_changeset: dc2b138240dd6a23b5cedd55f41e508af1137836
15:25:15 INFO - mozversion INFO | application_display_name: Nightly
15:25:15 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
15:25:15 INFO - mozversion INFO | application_name: Firefox
15:25:15 INFO - mozversion INFO | application_remotingname: firefox
15:25:15 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound
15:25:15 INFO - mozversion INFO | application_vendor: Mozilla
15:25:15 INFO - mozversion INFO | application_version: 44.0a1
15:25:15 INFO - mozversion INFO | platform_buildid: 20151023144132
15:25:15 INFO - mozversion INFO | platform_changeset: dc2b138240dd6a23b5cedd55f41e508af1137836
15:25:15 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound
15:25:15 INFO - mozversion INFO | platform_version: 44.0a1
15:25:15 INFO - 2015-10-23 15:25:15,839 DEBUG : using testdate: 1445639115
15:25:15 INFO - 2015-10-23 15:25:15,839 DEBUG : actual date: 1445639115
15:25:15 INFO - 2015-10-23 15:25:15,920 INFO : starting webserver on 'localhost:56946'
15:25:15 INFO - 2015-10-23 15:25:15,921 INFO : Starting test suite talos-linux64-ix-078
15:25:15 INFO - 2015-10-23 15:25:15,921 INFO : Starting test dromaeo_css
15:25:15 INFO - 2015-10-23 15:25:15,922 DEBUG : operating with platform_type : linux_
15:25:15 INFO - 2015-10-23 15:25:15,922 INFO : Initialising browser for dromaeo_css test...
15:25:15 INFO - 2015-10-23 15:25:15,951 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa3QdOr/profile http://localhost:56946/getInfo.html
15:25:23 INFO - 2015-10-23 15:25:23,268 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
15:25:23 INFO - 2015-10-23 15:25:23,268 DEBUG : BROWSER_OUTPUT: colorDepth:24
15:25:23 INFO - 2015-10-23 15:25:23,268 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697
15:25:23 INFO - 2015-10-23 15:25:23,268 DEBUG : BROWSER_OUTPUT: __metrics
15:25:23 INFO - 2015-10-23 15:25:23,409 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:25:24 INFO - 2015-10-23 15:25:24,773 INFO : Browser initialized.
15:25:24 INFO - 2015-10-23 15:25:24,773 INFO : Running cycle 1/1 for dromaeo_css test...
15:25:24 INFO - 2015-10-23 15:25:24,773 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa3QdOr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
15:25:36 INFO - 2015-10-23 15:25:36,705 DEBUG : BROWSER_OUTPUT: RSS: Main: 141819904
15:25:36 INFO - 2015-10-23 15:25:36,706 DEBUG : BROWSER_OUTPUT:
15:26:28 INFO - 2015-10-23 15:26:28,492 DEBUG : BROWSER_OUTPUT: 1445639188491 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no]
15:28:40 INFO - 2015-10-23 15:28:40,800 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56946/tests/dromaeo/cssquery-ext.html)
15:28:41 INFO - 2015-10-23 15:28:41,971 DEBUG : BROWSER_OUTPUT: RSS: Main: 240898048
15:28:41 INFO - 2015-10-23 15:28:41,971 DEBUG : BROWSER_OUTPUT:
15:31:44 INFO - 2015-10-23 15:31:44,350 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-ext.html (next: http://localhost:56946/tests/dromaeo/cssquery-jquery.html)
15:31:44 INFO - 2015-10-23 15:31:44,931 DEBUG : BROWSER_OUTPUT: RSS: Main: 215420928
15:31:44 INFO - 2015-10-23 15:31:44,931 DEBUG : BROWSER_OUTPUT:
15:34:47 INFO - 2015-10-23 15:34:47,052 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56946/tests/dromaeo/cssquery-mootools.html)
15:34:47 INFO - 2015-10-23 15:34:47,618 DEBUG : BROWSER_OUTPUT: RSS: Main: 213344256
15:34:47 INFO - 2015-10-23 15:34:47,618 DEBUG : BROWSER_OUTPUT:
15:37:49 INFO - 2015-10-23 15:37:49,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56946/tests/dromaeo/cssquery-prototype.html)
15:37:50 INFO - 2015-10-23 15:37:50,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 199266304
15:37:50 INFO - 2015-10-23 15:37:50,155 DEBUG : BROWSER_OUTPUT:
15:40:53 INFO - 2015-10-23 15:40:53,040 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56946/tests/dromaeo/cssquery-yui.html)
15:40:53 INFO - 2015-10-23 15:40:53,613 DEBUG : BROWSER_OUTPUT: RSS: Main: 201940992
15:40:53 INFO - 2015-10-23 15:40:53,614 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,008 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/dromaeo/cssquery-yui.html (next: http://localhost:56946/tests/dromaeo/cssquery-dojo.html)
15:43:56 INFO - 2015-10-23 15:43:56,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 246972416
15:43:56 INFO - 2015-10-23 15:43:56,259 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,261 DEBUG : BROWSER_OUTPUT: __start_tp_report
15:43:56 INFO - 2015-10-23 15:43:56,261 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
15:43:56 INFO - 2015-10-23 15:43:56,261 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
15:43:56 INFO - 2015-10-23 15:43:56,261 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
15:43:56 INFO - 2015-10-23 15:43:56,262 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2648.8808003639833;2732.6857411397696;2759.6964333923265;2764.8341099534027;2765.7649099826513;4749.7387643679585;4845.394325709286;4956.727379994103;4988.975055124728;5008.549230569252;4849.975750121253;4862.708237505753;4948;4958.801647934079;4988.825391111312;4477.171723231197;4486.439195100605;4518.277075667894;4581.4960354361165;4627.629789616823;4349.347597860315;4559.589636932676;4567.931481027787;4589.977050114745;4612.677112602119;4436.977815110904;4449.221386257392;4478.686491945565;4572.611328037121;4599.149157405891;4532.161550113239;4532.297493888453;4537.931931021004;4539.6368290536675;4577.33628623851;4375.2124617568825;4520.434945631796;4531.274996000624;4539.160255352737;4577;4733.148033353995;4804;4841.709497430165;4851.126797176506;4859.684120532154;5172.922406163873;5175.068487672255;5177.119889618773;5178.71517066565;5179.378474583036;5034.672746271481;5192.55863251624;5195.974020129913;5196.58427325813;5250.7637156328055;4698.647601429906;4787.976060119747;4803.351547541072;4806.8798280043275;4825.662203645781;5364.651297665718;5372.704501252469;5383.623146379716;5386.757595908193;5391.083515802323;4788;5133.691978481228;5146.716930568779;5148.871278218;5197.506236907488;5149.716765577854;5163.535281824654;5168.9224661630105;5261.421243663194;5320.946790532123;5178.197379406198;5192.221166825007;5194.3247377840635;5204.895902082013;5258.447862974409;4867.1969125093965;5172.870678233075;5176.715280659525;5183.81856635016;5187.584993200535;4251.659867210616;4286.721363111388;4314.741115533078;4315.6763242756915;4351.108022855307;3751.6060813614176;3755.2301778135416;3755.455458958436;3782.224643947984;3804.353259945816;4181.435506206684;4185.707000509996;4195.16096780639;4197.538270790211;4234.513031001413;4911;5013.49865013503;5039.84880453587;5086.949130508721;5195.19474481456;4139.668826493873;4148.149629326042;4173.1862286853775;4182.435371224907;4197.496300443905;4622.375979242826;4628.861134165981;4628.97685511577;4641.419822522184;4669.626429885601;4691.7654117294;4796.2805579163405;4796.5203479651755;4802.615790736802;4872.074305881871;5219.269302297757;5251.947480525146;5259.8422047338645;5260.605454590845;5263.5525980292105;4936.654434189572;4953.702777833341;4970.155073637418;4970.8757281068265;4997.175466048061;4940.6788558743565;5095.566876815364;5097.719625420637;5111.156659151347;5121.667091639031;5091.414487334004;5147.099257629975;5156.2523434102595;5200.947990520046;5214.895702085862;4906.533879281463;4959.156943319572;4984.675996060245;4984.75076246179;5026.170681837602;5012.248162775612;5028.145215313479;5033.974830125972;5036.194208926554;5036.647434679538;4614.815407383667;4737.360456338351;4741.478437371955;4756.476787553297;4883;5055.545000949932;5057.519535644108;5068.49315068505;5071.898562028812;5079.238114282739;4892.241702536113;4894.314795928644;4905.607551395951;4909.828156014522;4935.753212339297
15:43:56 INFO - 2015-10-23 15:43:56,265 DEBUG : BROWSER_OUTPUT: |1;ext.html;502.7008929686836;506.53398873036826;509.0709455244178;537.938137114232;546.8113500842211;28764.424711505784;37176.25647487052;37650.43524347134;37664.81167594165;37716.43425348622;40749.388759168636;41369;41385.37921931173;41407.378889316686;41458.79270603643;7139.1433028036445;7246.71013159473;7951.443398962089;8125.187481251886;8126.349892008625;10181.694549163536;10455.634052808153;10457.895421045769;10459.738506537322;10468.214883883702;10044.497775111251;10140.340877842917;10156.644517441891;10168.796624067514;10174.898251017468;7329.670164842568;7530.28462296081;10216.795664086676;10221.080102790747;10528.789424211549;10110.797784044351;10271.33236339643;10279.640212592523;10291.125254353388;10292.536835842406;38631;39217.60782392168;39270.21459570821;39271.21457570861;39272.21455570901;2733.72662733727;2752.862356882148;2769.3353595137023;2789.2050765531726;2794.930126746827;28599.14202573926;28790.856045719847;28811.42377152466;28816.718554016912;29454.98409913378;2607.895684172631;2621.6722909636296;2630.184642760731;2671.17193669961;2759.9586006209925;235516;237022;237239;239819;240078;53126.468735312155;54216.45783542193;57024.14463783047;57521.71239143861;62884;58764.412355875895;58787;59159.40840591539;59205.70397147987;59217.40782592119;14299.85700143006;14487.275636218146;14499.347529361205;14554.636134096732;14588.854111458963;10839.620613278497;10970.396628185526;10990.945045274722;10991;11025.724356890982;19132.23471061142;19171.424857254304;19172.424827255203;19176.424707258804;19304.71042934357;8138.104808471064;8363.954505686788;8414.11651776567;8446.059055452506;8446.535440550828;756.6141267953384;839.7186942374251;854.2098558833054;877.1842186766369;939.3143005605946;26344.209673709818;26364.340891477485;26454.074107406534;26493.602595961074;26583.867080664473;876.5573385440312;894.217559635319;910.1035480052143;915.4781774388667;1071.8124328242527;13783.175888439167;13885.444582216598;14086;14108.15351078918;14124.646883827987;285938;291920;292001;292418;292939;47012.29481557779;47533.76233118882;47656;47659;47670.761646192244;1085.1156307609274;1094.7755710079414;1096.703889949725;1105.1766434006556;1134.6993046842592;25213;25706.485870282115;26183.869080655237;26221.475570488867;26264.21207363782;36561.26877462489;37634.8118259407;41256.79371603123;41686.791566041975;41698.374524382765;348224;353920;353923;356532;356782;29549;29957.550636740903;29986.550201746555;30042.24894377658;30047.399052019275;39305;39764;40576.59423405728;40625.79687101545;40809.79595102005;34168.31663366669;34774.47838282377;34794.478082829286;35145;38621.227575448895;34478.80699341765;34684.47973280352;35099.649003509636;35229;35365.646343537264;34717.82641086778;34887.476687849194;38505;38508;38655.22689546137;29939.850300748356;31294.37411251808;31905.8404707975;31929.68070319267;32012.359752805278;24803.87598061998;25033.499330013663;25065.87467062653;26660.333491662863;27145.86427067852
15:43:56 INFO - 2015-10-23 15:43:56,267 DEBUG : BROWSER_OUTPUT: |2;jquery.html;353.4450912068053;353.9115221194701;354.63118356908814;354.8420952676059;358.603742864135;12184.329861857594;12581.24512529249;15415.306311215994;15443.305051272691;15953.760693589606;14949.850501494982;15521.379144834222;15522.146281954516;15525.611859703513;15643.92178039111;4901.534354236351;4928.704277743329;5080.110980578403;5080.872978175538;5087.338645976018;3549.077239917627;3685.244524872408;5004.874878128039;5613.775448982035;5860.824175274749;5734.713264336788;5763.020286551276;5774.422557744234;5809.331926828406;5818.447247511501;5648.491635752782;5806.854828629318;5823.534117270629;5825.766969321222;5826.300843898759;5103.2855400244;5111.05445492585;5135.204043373283;5153.871153221199;5158.819441319536;18987.145578448864;19323.710144347846;19581.804181958283;19793.10931008108;19831.504212394662;1641.2122181352897;1651.5045486354165;1718.510224585987;1744.9563760906078;2491.314888405685;11970.700732481671;12402.25586464815;12607.117501774788;12670.366481675972;13180.53868114621;1822.1162736072995;1864.9440516784518;1905.557544655283;1932.9710054349196;1954.4332143678316;58306.12540811849;60955.69522152405;61102.388976110116;61185.082223767124;61592;14709.117452952643;15275.694486110215;15285.159316237714;15292.235388230543;15345.386184552493;22500.437489062904;22630.981605827776;22700;22740.772592274196;22748.545029099325;8087.069986951458;8089.878651820228;8144.511329320259;8163.510189388656;8246.422750407533;6966.512344135862;7022.754203602849;7025.367716905503;7040.823979400556;7075.186353569303;7359.411247100219;7450.590217538087;7453.180150183475;7467.7012919483695;7475.5888426136;1051.784384201237;1056.8837427882927;1071.59815069349;2057.5494276535005;3157.936841263208;387.58915549517604;388.97860617665737;394.15847166300154;408.4812288393783;446.06994416641663;9379.030403615181;9427.198688111588;10211.285210035374;10380.896191038144;10454.790904181873;413.30358346186773;419.38141241668905;420.7685772824934;422.1261987685523;444.50659767657874;5554.694491802989;5647.350554686182;5820.854478638068;5832.24180856486;5896.675682837399;440636;444413;445337;447658;472061.8381448562;66289.6685516579;67002.3299766996;67069.32930670728;67519.3248067513;68028.31971680319;472.8085125524168;480.74760750605515;480.9182438985381;481.8000529780172;482.01909114950905;9680.322377433513;9755.782977731178;9835.459049752333;10208.642697505551;10230;32136.999999999065;33200.66799332073;33229.50155747617;34015.829920850236;34017.31965360729;39513.802430987664;39889.2022159561;41048.79475602722;41731.7913410431;42950.14099717926;6047.848803779941;6088.391160883876;6149.508039356751;6165.599236049642;6172.105044768394;9165.17513423795;11232.213745038096;11351.829722554334;11456.427178640868;11479.942600286946;8337.95831020841;9331.906680933103;9453.149216570542;9589.616415343307;9651.469169195761;9308.162265396148;9494.335396522178;9575.329726919328;9624.470654113811;9741.123298903132;9598.376105553116;9622.422654640744;9777;9851.950740246253;10033.046860548237;5879.970600146972;5949.167116603592;5967.8209653710455;5969.283685957713;5992.340842507234;9500.144986951207;9616.855747163938;9761.902380976384;9795.118439340493;10024.949875250577
15:43:56 INFO - 2015-10-23 15:43:56,268 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1295.9352032398378;1446.8625480579349;1610.903345799252;1655.7516372544117;1825.9087045647723;7275.090613673291;7893.171217022209;8052.91947080529;8060.83878322434;8072.757817265491;7793.337566306856;7911.287984081431;7966.522008679483;7977.960110199441;7993.200679932019;2025.8480613953975;2050.4361300642367;2056.475598722324;2058.5471196336857;2058.886761228132;2039.5614942787297;2050.3541384463847;2061.5052387426986;2100.390886642872;2103.032605001701;2028.8072633099832;2077.83377329814;2084.801943815343;2091.6130515854616;2092.2677063027895;2043.5810658814908;2050.477128332273;2059.1763294682096;2060.4951786812176;2060.8763474191446;2162.362103179566;2178.237616834095;2206.5366273082673;2210.303754317385;2210.701555290031;6774.424173945221;7864.016997875266;7912.960435197845;7931.286184243446;8096.554689492075;3993.5407428145845;4448.5773851484055;4530.207213737583;4535.342375355555;4537.047220083787;2753.9586906196423;2813.4232482341276;2855.8857645694147;2855.9718501339503;2936.6182396288345;828.1015098618007;838.5849004742691;847.1316900177306;848.9702860399918;853.4964371021127;11970.22193557416;12046.09654275924;12057.879421205764;12079.758404831855;12088.093392995472;4305.935410968838;4353.172897149531;4356.716813407118;4364.62900653448;4451.597746709776;10164.898351016544;10214.540345684314;10223.897761022443;10249.38503689781;10261.897381026094;4588.242939914943;4589.426321709786;4591.380163677927;4599;4600.631949444037;4032.4556184915245;4037.232925744158;4043.3126368516832;4050.1292222172374;4050.7569545827346;3514.648535146465;3575.2670702505925;3589.892303230907;3611.0791748104066;3618.7696183297803;1157.7279339355246;1185.7035741064733;1192.2905871006737;1194.4386138514883;1194.4863708605383;1164.1443538998828;1178.8762179971152;1191.1780871199019;1205.614203454886;1208.6615747590688;7963.8407231855035;8098.028236611644;8117.228863258099;8127.674893004214;8133.7153199638915;1134.9432528373547;1136.1195073817692;1143.790849673194;1166.5683697032152;1168.351564881499;5571.4428557143965;6031.336552979169;6104.481119104926;6112.847178820476;6125.173101631222;9790.21678265737;10366.688999330032;10366.792664146826;10423;10547.789044219073;10007.199424046059;10250;10277.841653252752;10279.383237005804;10287.022732840369;7570.356519695777;7643.159252482223;7664.731734389381;7665.61671916402;7669.041369828771;7735;7867.095284042409;7921.881171782543;7926.978620451748;7944;8856.778580535538;9036.548172591212;9056.230220431074;9062.365634405529;9133.771655708395;9036.593353298986;9084.500352480676;9105.317101217302;9226.40028398152;9234.122758337948;7039;7080.362767350757;7169.4981351307;7181.82045448847;7185.568865868065;8137.511749295061;8210.794730131794;8213.425060245725;8216.507009579444;8221.917780822116;7587.658555365132;7631.3513351365755;7642.1593624702355;7704.499207551491;7764.34003109742;7472.178060413459;7489.812754681177;7644.694212231449;7667.233276672511;7712;7516.774496765106;7558.093028836574;7565.356944659546;7568.735094271674;7577.128630207597;6780.186377634518;7021.332973367522;7030.3321184487395;7041.155061392666;7043.471739619446;7133.17968433616;7208.098987626547;7223.1693355264815;7226.74706385274;7228.746993855189
15:43:56 INFO - 2015-10-23 15:43:56,269 DEBUG : BROWSER_OUTPUT: |4;prototype.html;305.91128572713905;314.6680252333788;318.79915653138517;322.591921219657;329.60282859154705;5199.272101905736;5218.165093585022;5225.372955245366;5227.947720522794;5229.60777941654;5115.641905066647;5129.204973229155;5129.9227162295965;5130.538251557359;5135.024345374386;3900.7854567998747;3966.246413181501;4006.298897692907;4085.5301640311445;4087.9182416351655;4048.554658987524;4075.7554546727292;4079.4084857695616;4084.8570300039505;4086.6730661547003;4036.0717035081802;4036.091879327157;4037.8586749463775;4042.757434553936;4043.130726893717;3849.3840985442334;3916.725829191958;3972.6226008529143;4042.6765858731524;4047.413125096874;3600.225951420431;3605.278944211142;3605.891823245307;3608.5128507651657;3615.981920090409;4936.48166942473;4936.48166942473;4937.456879743261;4939.728314942677;4946;4518.774061296955;4544.681872268942;4545.977270113662;4548.044910568785;4551.613112885409;5131.076406246912;5258.50044245796;5326.280952071459;5344.331958505187;5348.866278343034;3904.472896159039;3907.257621051991;3907.687385009221;3907.8632247871474;3907.902302442433;5627.915581266243;5633.887322253573;5635.8591035224035;5636.2954630671165;5637.8590535236945;4027.718059735849;4032.072623296658;4033.1328764315813;4033.4756481657196;4035.1727895781296;3997.200559888034;4088.100617864065;4100.507939047334;4109.815058322382;4117.711760176759;4585.633149348044;4587.449506059294;4590.036092420629;4590.288505281686;4591.79336929839;5326.227696983993;5342.1452567589;5343.091674415358;5343.545798607084;5345.679259244458;1465.8534146585257;1487.2415068315238;1488.1963739580722;1488.3600051977537;1488.5385530485585;1020.78563501664;1034.8551202831609;1049.6693541534391;1050.8791488978713;1052.752603138262;4431.40176076232;4433.7561434121435;4434.11317736454;4434.889127771831;4435.112977404532;5537.141743029837;5538.695371754511;5540.085885828793;5545;5545.251391062155;4412.051408947028;4422.248217802981;4426.601605855489;4430.047539778963;4434.711743736647;2851.8431486268455;2902.1873875314523;2972.212363723615;2991.536311871663;3026.8789248430257;5121.027004869063;5204.34945631796;5205.817796377184;5214.504622060898;5216.37403511581;5642.181883626851;5648.8870222595315;5655.943440565542;5657.490825825613;5659.830205093854;5193.818216362561;5200.6879587224885;5201.115810312181;5208.765605547759;5209.661372010807;5330.600204984564;5469.425710300361;5473.28847249855;5546.057170280981;5568.415316391721;5383.353997520323;5393.9730301348245;5401.4868587484125;5404.108322126805;5404.459554044564;5242.606804489602;5257.053730328577;5258.2375555544995;5275.155975043975;5283.2075188722;4011.9598804012753;4032.3145065338376;4033.0320723025675;4034.4351790748733;4038.818253178673;5437.864053398538;5561.138023606348;5573.6098473106495;5581.051221292471;5582.134769110794;5261.763220655156;5265;5271.367435907562;5273.551748101454;5274.498922602346;5290.444503327095;5290.550303224269;5293.841184764463;5294.100002999577;5296.179092240709;4976.651634385558;5066.36670416198;5115.5396014358885;5123.180291153398;5124.256982737556;5305.151175811851;5311.282976798083;5312.681239125664;5312.920306195488;5319.946800531945;4911.386076740408;5014.34813474246;5018.397792264952;5098.082345177755;5100.183970564692
15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: |5;yui.html;601.6239850093691;693.9867793022187;750.2197714377048;763.1805150474755;770.060526158087;10600.257981941268;10775.191860610446;10818.459077046156;10829.945850270757;10832.350058996446;9256.953715231415;10045.447500387476;10127.13919316859;10131.392116473016;10148;620.5190976992833;794.400227827989;816.9387295952828;819.4100247821559;819.7991492084448;802.3621221129216;803.1606970715616;803.3813963248296;808.3331251717327;809.8947136872227;656.6815094679076;666.6333516565894;829.5396055189367;838.8448137094626;839.3117643532306;830.7756905635475;844.1642773654099;847.9703210387669;848.7835601921502;848.859938110211;629.5907660020977;631.8104568629393;632.2097378277153;632.4371309534517;632.5824955529374;10923.508442120044;11030.338179709242;11055.11559075272;11068.557257709763;11078.501467433904;9584.616615335379;9985.550650220757;10237;10390.584376624925;10678.145748340192;8278.089410164877;8299.709510167175;8329.50022998622;8414.705485307984;8473;2656.0836511403536;2702.90539831105;2726.8091233613654;2752.119321817019;2782.6382570265937;31811.363772724417;32214.194645133826;32233.51649725256;32672.6732732672;32755.50866737001;8377.748667539983;8378.622961966727;8631.654733810577;8726.12738726135;8753.47479151253;14721.55835324942;14725.705485890436;14731.263436828116;14732;14764.26178691061;5088.389393272831;5122.948770512247;5148.382194136652;5281.973590132025;5288.9735551322;2933.897313594014;2947.64628244612;2948.7051294870344;3013.74383177428;3071.2014876132366;2420.152946468722;2475.5296493666146;2478.1450399612104;2514.396544829228;2558.1046633678066;441.90057237122033;445.5054889073126;448.53576548272383;458.16385097197747;459.15515451568604;375.63000444562084;407.52319785850625;506.7162389062137;524.0828550037435;537.3766430940091;7065.258147894499;7349.375303099297;7558.319751222306;7802.453828232083;7939.126696063428;430.9030468144705;456.2608574109963;523.9580833533308;526.0058489454933;559.3371854352524;3265.2000259935994;3301.108700650811;3304.553885225464;3400.1159698478077;3649.835757390924;41250.79374603108;41390.1721965565;41441;41473;41483.7925810375;34699.13252168716;35172;35429.82285088558;35435.468467973;35497.46753798695;29580;30183.245418864703;30228.24429389239;30466.695333046828;31376.058718238488;7139.286071392819;7277.963610181915;7308.123025237006;7330.6701198445135;7384;22858.085676573417;23131.653025204298;23505.29484115479;23553.41116472102;23579.882100589388;30820.691793081784;31208.68791312149;31219.37561248808;31254.531182032744;31293.530597040608;6373.458256048102;6593.670316484061;6617.272100069084;6628.900566491603;6648.202215734143;9937.354071985297;9992.60029598837;10145.442000689738;10235.078842904175;10243.846342304721;9495.525223738647;9751.512424378894;9782.461964592016;9806.75483112928;9808.65669701557;9006.864897026418;9077.319201059814;9275.62897484093;9297;9480.099390557885;8850.601722922354;9118.635254589743;9174.220191283815;9202;9262.756147029284;8560.186782255676;8570.302325697416;8702.608382622924;8704.477731336141;8734.388592798443;8205.548694821842;8251.174882511701;8320.417570769989;8400.747977560683;8464.873026904725
15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: __end_tp_report
15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: __start_cc_report
15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,899
15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: __end_cc_report
15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: __startTimestamp1445640236259__endTimestamp
15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,271 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: Number of tests: 6
15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4765.05 Median:4894.31 stddev:513.33 (10.5%) stddev-sans-first:487.33
15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: Values: 2648.9 2732.7 2759.7 2764.8 2765.8 4749.7 4845.4 4956.7 4989.0 5008.5 4850.0 4862.7 4948.0 4958.8 4988.8 4477.2 4486.4 4518.3 4581.5 4627.6 4349.3 4559.6 4567.9 4590.0 4612.7 4437.0 4449.2 4478.7 4572.6 4599.1 4532.2 4532.3 4537.9 4539.6 4577.3 4375.2 4520.4 4531.3 4539.2 4577.0 4733.1 4804.0 4841.7 4851.1 4859.7 5172.9 5175.1 5177.1 5178.7 5179.4 5034.7 5192.6 5196.0 5196.6 5250.8 4698.6 4788.0 4803.4 4806.9 4825.7 5364.7 5372.7 5383.6 5386.8 5391.1 4788.0 5133.7 5146.7 5148.9 5197.5 5149.7 5163.5 5168.9 5261.4 5320.9 5178.2 5192.2 5194.3 5204.9 5258.4 4867.2 5172.9 5176.7 5183.8 5187.6 4251.7 4286.7 4314.7 4315.7 4351.1 3751.6 3755.2 3755.5 3782.2 3804.4 4181.4 4185.7 4195.2 4197.5 4234.5 4911.0 5013.5 5039.8 5086.9 5195.2 4139.7 4148.1 4173.2 4182.4 4197.5 4622.4 4628.9 4629.0 4641.4 4669.6 4691.8 4796.3 4796.5 4802.6 4872.1 5219.3 5251.9 5259.8 5260.6 5263.6 4936.7 4953.7 4970.2 4970.9 4997.2 4940.7 5095.6 5097.7 5111.2 5121.7 5091.4 5147.1 5156.3 5200.9 5214.9 4906.5 4959.2 4984.7 4984.8 5026.2 5012.2 5028.1 5034.0 5036.2 5036.6 4614.8 4737.4 4741.5 4756.5 4883.0 5055.5 5057.5 5068.5 5071.9 5079.2 4892.2 4894.3 4905.6 4909.8 4935.8
15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,272 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45608.34 Median:30670.89 stddev:78118.67 (254.7%) stddev-sans-first:78264.40
15:43:56 INFO - 2015-10-23 15:43:56,273 DEBUG : BROWSER_OUTPUT: Values: 502.7 506.5 509.1 537.9 546.8 28764.4 37176.3 37650.4 37664.8 37716.4 40749.4 41369.0 41385.4 41407.4 41458.8 7139.1 7246.7 7951.4 8125.2 8126.3 10181.7 10455.6 10457.9 10459.7 10468.2 10044.5 10140.3 10156.6 10168.8 10174.9 7329.7 7530.3 10216.8 10221.1 10528.8 10110.8 10271.3 10279.6 10291.1 10292.5 38631.0 39217.6 39270.2 39271.2 39272.2 2733.7 2752.9 2769.3 2789.2 2794.9 28599.1 28790.9 28811.4 28816.7 29455.0 2607.9 2621.7 2630.2 2671.2 2760.0 235516.0 237022.0 237239.0 239819.0 240078.0 53126.5 54216.5 57024.1 57521.7 62884.0 58764.4 58787.0 59159.4 59205.7 59217.4 14299.9 14487.3 14499.3 14554.6 14588.9 10839.6 10970.4 10990.9 10991.0 11025.7 19132.2 19171.4 19172.4 19176.4 19304.7 8138.1 8364.0 8414.1 8446.1 8446.5 756.6 839.7 854.2 877.2 939.3 26344.2 26364.3 26454.1 26493.6 26583.9 876.6 894.2 910.1 915.5 1071.8 13783.2 13885.4 14086.0 14108.2 14124.6 285938.0 291920.0 292001.0 292418.0 292939.0 47012.3 47533.8 47656.0 47659.0 47670.8 1085.1 1094.8 1096.7 1105.2 1134.7 25213.0 25706.5 26183.9 26221.5 26264.2 36561.3 37634.8 41256.8 41686.8 41698.4 348224.0 353920.0 353923.0 356532.0 356782.0 29549.0 29957.6 29986.6 30042.2 30047.4 39305.0 39764.0 40576.6 40625.8 40809.8 34168.3 34774.5 34794.5 35145.0 38621.2 34478.8 34684.5 35099.6 35229.0 35365.6 34717.8 34887.5 38505.0 38508.0 38655.2 29939.9 31294.4 31905.8 31929.7 32012.4 24803.9 25033.5 25065.9 26660.3 27145.9
15:43:56 INFO - 2015-10-23 15:43:56,274 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,274 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:24861.53 Median:481.36 stddev:73656.71 (15301.8%) stddev-sans-first:73840.35
15:43:56 INFO - 2015-10-23 15:43:56,274 DEBUG : BROWSER_OUTPUT: Values: 353.4 353.9 354.6 354.8 358.6 12184.3 12581.2 15415.3 15443.3 15953.8 14949.9 15521.4 15522.1 15525.6 15643.9 4901.5 4928.7 5080.1 5080.9 5087.3 3549.1 3685.2 5004.9 5613.8 5860.8 5734.7 5763.0 5774.4 5809.3 5818.4 5648.5 5806.9 5823.5 5825.8 5826.3 5103.3 5111.1 5135.2 5153.9 5158.8 18987.1 19323.7 19581.8 19793.1 19831.5 1641.2 1651.5 1718.5 1745.0 2491.3 11970.7 12402.3 12607.1 12670.4 13180.5 1822.1 1864.9 1905.6 1933.0 1954.4 58306.1 60955.7 61102.4 61185.1 61592.0 14709.1 15275.7 15285.2 15292.2 15345.4 22500.4 22631.0 22700.0 22740.8 22748.5 8087.1 8089.9 8144.5 8163.5 8246.4 6966.5 7022.8 7025.4 7040.8 7075.2 7359.4 7450.6 7453.2 7467.7 7475.6 1051.8 1056.9 1071.6 2057.5 3157.9 387.6 389.0 394.2 408.5 446.1 9379.0 9427.2 10211.3 10380.9 10454.8 413.3 419.4 420.8 422.1 444.5 5554.7 5647.4 5820.9 5832.2 5896.7 440636.0 444413.0 445337.0 447658.0 472061.8 66289.7 67002.3 67069.3 67519.3 68028.3 472.8 480.7 480.9 481.8 482.0 9680.3 9755.8 9835.5 10208.6 10230.0 32137.0 33200.7 33229.5 34015.8 34017.3 39513.8 39889.2 41048.8 41731.8 42950.1 6047.8 6088.4 6149.5 6165.6 6172.1 9165.2 11232.2 11351.8 11456.4 11479.9 8338.0 9331.9 9453.1 9589.6 9651.5 9308.2 9494.3 9575.3 9624.5 9741.1 9598.4 9622.4 9777.0 9852.0 10033.0 5880.0 5949.2 5967.8 5969.3 5992.3 9500.1 9616.9 9761.9 9795.1 10024.9
15:43:56 INFO - 2015-10-23 15:43:56,274 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,275 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5739.63 Median:4590.40 stddev:3190.12 (69.5%) stddev-sans-first:3181.58
15:43:56 INFO - 2015-10-23 15:43:56,275 DEBUG : BROWSER_OUTPUT: Values: 1295.9 1446.9 1610.9 1655.8 1825.9 7275.1 7893.2 8052.9 8060.8 8072.8 7793.3 7911.3 7966.5 7978.0 7993.2 2025.8 2050.4 2056.5 2058.5 2058.9 2039.6 2050.4 2061.5 2100.4 2103.0 2028.8 2077.8 2084.8 2091.6 2092.3 2043.6 2050.5 2059.2 2060.5 2060.9 2162.4 2178.2 2206.5 2210.3 2210.7 6774.4 7864.0 7913.0 7931.3 8096.6 3993.5 4448.6 4530.2 4535.3 4537.0 2754.0 2813.4 2855.9 2856.0 2936.6 828.1 838.6 847.1 849.0 853.5 11970.2 12046.1 12057.9 12079.8 12088.1 4305.9 4353.2 4356.7 4364.6 4451.6 10164.9 10214.5 10223.9 10249.4 10261.9 4588.2 4589.4 4591.4 4599.0 4600.6 4032.5 4037.2 4043.3 4050.1 4050.8 3514.6 3575.3 3589.9 3611.1 3618.8 1157.7 1185.7 1192.3 1194.4 1194.5 1164.1 1178.9 1191.2 1205.6 1208.7 7963.8 8098.0 8117.2 8127.7 8133.7 1134.9 1136.1 1143.8 1166.6 1168.4 5571.4 6031.3 6104.5 6112.8 6125.2 9790.2 10366.7 10366.8 10423.0 10547.8 10007.2 10250.0 10277.8 10279.4 10287.0 7570.4 7643.2 7664.7 7665.6 7669.0 7735.0 7867.1 7921.9 7927.0 7944.0 8856.8 9036.5 9056.2 9062.4 9133.8 9036.6 9084.5 9105.3 9226.4 9234.1 7039.0 7080.4 7169.5 7181.8 7185.6 8137.5 8210.8 8213.4 8216.5 8221.9 7587.7 7631.4 7642.2 7704.5 7764.3 7472.2 7489.8 7644.7 7667.2 7712.0 7516.8 7558.1 7565.4 7568.7 7577.1 6780.2 7021.3 7030.3 7041.2 7043.5 7133.2 7208.1 7223.2 7226.7 7228.7
15:43:56 INFO - 2015-10-23 15:43:56,275 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,276 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4453.72 Median:4961.33 stddev:1257.96 (25.4%) stddev-sans-first:1222.36
15:43:56 INFO - 2015-10-23 15:43:56,277 DEBUG : BROWSER_OUTPUT: Values: 305.9 314.7 318.8 322.6 329.6 5199.3 5218.2 5225.4 5227.9 5229.6 5115.6 5129.2 5129.9 5130.5 5135.0 3900.8 3966.2 4006.3 4085.5 4087.9 4048.6 4075.8 4079.4 4084.9 4086.7 4036.1 4036.1 4037.9 4042.8 4043.1 3849.4 3916.7 3972.6 4042.7 4047.4 3600.2 3605.3 3605.9 3608.5 3616.0 4936.5 4936.5 4937.5 4939.7 4946.0 4518.8 4544.7 4546.0 4548.0 4551.6 5131.1 5258.5 5326.3 5344.3 5348.9 3904.5 3907.3 3907.7 3907.9 3907.9 5627.9 5633.9 5635.9 5636.3 5637.9 4027.7 4032.1 4033.1 4033.5 4035.2 3997.2 4088.1 4100.5 4109.8 4117.7 4585.6 4587.4 4590.0 4590.3 4591.8 5326.2 5342.1 5343.1 5343.5 5345.7 1465.9 1487.2 1488.2 1488.4 1488.5 1020.8 1034.9 1049.7 1050.9 1052.8 4431.4 4433.8 4434.1 4434.9 4435.1 5537.1 5538.7 5540.1 5545.0 5545.3 4412.1 4422.2 4426.6 4430.0 4434.7 2851.8 2902.2 2972.2 2991.5 3026.9 5121.0 5204.3 5205.8 5214.5 5216.4 5642.2 5648.9 5655.9 5657.5 5659.8 5193.8 5200.7 5201.1 5208.8 5209.7 5330.6 5469.4 5473.3 5546.1 5568.4 5383.4 5394.0 5401.5 5404.1 5404.5 5242.6 5257.1 5258.2 5275.2 5283.2 4012.0 4032.3 4033.0 4034.4 4038.8 5437.9 5561.1 5573.6 5581.1 5582.1 5261.8 5265.0 5271.4 5273.6 5274.5 5290.4 5290.6 5293.8 5294.1 5296.2 4976.7 5066.4 5115.5 5123.2 5124.3 5305.2 5311.3 5312.7 5312.9 5319.9 4911.4 5014.3 5018.4 5098.1 5100.2
15:43:56 INFO - 2015-10-23 15:43:56,277 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,277 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:10186.95 Median:2903.99 stddev:10874.38 (374.5%) stddev-sans-first:10881.06
15:43:56 INFO - 2015-10-23 15:43:56,279 DEBUG : BROWSER_OUTPUT: Values: 601.6 694.0 750.2 763.2 770.1 10600.3 10775.2 10818.5 10829.9 10832.4 9257.0 10045.4 10127.1 10131.4 10148.0 620.5 794.4 816.9 819.4 819.8 802.4 803.2 803.4 808.3 809.9 656.7 666.6 829.5 838.8 839.3 830.8 844.2 848.0 848.8 848.9 629.6 631.8 632.2 632.4 632.6 10923.5 11030.3 11055.1 11068.6 11078.5 9584.6 9985.6 10237.0 10390.6 10678.1 8278.1 8299.7 8329.5 8414.7 8473.0 2656.1 2702.9 2726.8 2752.1 2782.6 31811.4 32214.2 32233.5 32672.7 32755.5 8377.7 8378.6 8631.7 8726.1 8753.5 14721.6 14725.7 14731.3 14732.0 14764.3 5088.4 5122.9 5148.4 5282.0 5289.0 2933.9 2947.6 2948.7 3013.7 3071.2 2420.2 2475.5 2478.1 2514.4 2558.1 441.9 445.5 448.5 458.2 459.2 375.6 407.5 506.7 524.1 537.4 7065.3 7349.4 7558.3 7802.5 7939.1 430.9 456.3 524.0 526.0 559.3 3265.2 3301.1 3304.6 3400.1 3649.8 41250.8 41390.2 41441.0 41473.0 41483.8 34699.1 35172.0 35429.8 35435.5 35497.5 29580.0 30183.2 30228.2 30466.7 31376.1 7139.3 7278.0 7308.1 7330.7 7384.0 22858.1 23131.7 23505.3 23553.4 23579.9 30820.7 31208.7 31219.4 31254.5 31293.5 6373.5 6593.7 6617.3 6628.9 6648.2 9937.4 9992.6 10145.4 10235.1 10243.8 9495.5 9751.5 9782.5 9806.8 9808.7 9006.9 9077.3 9275.6 9297.0 9480.1 8850.6 9118.6 9174.2 9202.0 9262.8 8560.2 8570.3 8702.6 8704.5 8734.4 8205.5 8251.2 8320.4 8400.7 8464.9
15:43:56 INFO - 2015-10-23 15:43:56,279 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
15:43:56 INFO - 2015-10-23 15:43:56,279 DEBUG : BROWSER_OUTPUT:
15:43:56 INFO - 2015-10-23 15:43:56,937 INFO : Browser exited with error code: 0
15:43:56 INFO - 2015-10-23 15:43:56,961 INFO : Completed test dromaeo_css (00:18:41)
15:43:56 INFO - 2015-10-23 15:43:56,961 INFO : Starting test kraken
15:43:56 INFO - 2015-10-23 15:43:56,961 DEBUG : operating with platform_type : linux_
15:43:56 INFO - 2015-10-23 15:43:56,961 INFO : Initialising browser for kraken test...
15:43:56 INFO - 2015-10-23 15:43:56,967 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYFNZyi/profile http://localhost:56946/getInfo.html
15:44:01 INFO - 2015-10-23 15:44:01,117 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
15:44:01 INFO - 2015-10-23 15:44:01,118 DEBUG : BROWSER_OUTPUT: colorDepth:24
15:44:01 INFO - 2015-10-23 15:44:01,118 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697
15:44:01 INFO - 2015-10-23 15:44:01,118 DEBUG : BROWSER_OUTPUT: __metrics
15:44:01 INFO - 2015-10-23 15:44:01,234 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:44:02 INFO - 2015-10-23 15:44:02,387 INFO : Browser initialized.
15:44:02 INFO - 2015-10-23 15:44:02,388 INFO : Running cycle 1/1 for kraken test...
15:44:02 INFO - 2015-10-23 15:44:02,388 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYFNZyi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
15:44:14 INFO - 2015-10-23 15:44:14,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 141692928
15:44:14 INFO - 2015-10-23 15:44:14,373 DEBUG : BROWSER_OUTPUT:
15:44:17 INFO - 2015-10-23 15:44:17,035 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56946/tests/kraken/driver.html?testName=audio-beat-detection)
15:44:17 INFO - 2015-10-23 15:44:17,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 177643520
15:44:17 INFO - 2015-10-23 15:44:17,621 DEBUG : BROWSER_OUTPUT:
15:44:20 INFO - 2015-10-23 15:44:20,270 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56946/tests/kraken/driver.html?testName=audio-dft)
15:44:20 INFO - 2015-10-23 15:44:20,843 DEBUG : BROWSER_OUTPUT: RSS: Main: 172474368
15:44:20 INFO - 2015-10-23 15:44:20,843 DEBUG : BROWSER_OUTPUT:
15:44:23 INFO - 2015-10-23 15:44:23,845 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56946/tests/kraken/driver.html?testName=audio-fft)
15:44:24 INFO - 2015-10-23 15:44:24,419 DEBUG : BROWSER_OUTPUT: RSS: Main: 152256512
15:44:24 INFO - 2015-10-23 15:44:24,419 DEBUG : BROWSER_OUTPUT:
15:44:26 INFO - 2015-10-23 15:44:26,375 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56946/tests/kraken/driver.html?testName=audio-oscillator)
15:44:26 INFO - 2015-10-23 15:44:26,948 DEBUG : BROWSER_OUTPUT: RSS: Main: 154353664
15:44:26 INFO - 2015-10-23 15:44:26,948 DEBUG : BROWSER_OUTPUT:
15:44:29 INFO - 2015-10-23 15:44:29,182 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56946/tests/kraken/driver.html?testName=imaging-gaussian-blur)
15:44:29 INFO - 2015-10-23 15:44:29,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 160694272
15:44:29 INFO - 2015-10-23 15:44:29,743 DEBUG : BROWSER_OUTPUT:
15:44:32 INFO - 2015-10-23 15:44:32,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56946/tests/kraken/driver.html?testName=imaging-darkroom)
15:44:33 INFO - 2015-10-23 15:44:33,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 155344896
15:44:33 INFO - 2015-10-23 15:44:33,288 DEBUG : BROWSER_OUTPUT:
15:44:36 INFO - 2015-10-23 15:44:36,699 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56946/tests/kraken/driver.html?testName=imaging-desaturate)
15:44:37 INFO - 2015-10-23 15:44:37,272 DEBUG : BROWSER_OUTPUT: RSS: Main: 153055232
15:44:37 INFO - 2015-10-23 15:44:37,272 DEBUG : BROWSER_OUTPUT:
15:44:40 INFO - 2015-10-23 15:44:40,111 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56946/tests/kraken/driver.html?testName=json-parse-financial)
15:44:40 INFO - 2015-10-23 15:44:40,680 DEBUG : BROWSER_OUTPUT: RSS: Main: 156143616
15:44:40 INFO - 2015-10-23 15:44:40,680 DEBUG : BROWSER_OUTPUT:
15:44:42 INFO - 2015-10-23 15:44:42,397 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56946/tests/kraken/driver.html?testName=json-stringify-tinderbox)
15:44:42 INFO - 2015-10-23 15:44:42,966 DEBUG : BROWSER_OUTPUT: RSS: Main: 163463168
15:44:42 INFO - 2015-10-23 15:44:42,966 DEBUG : BROWSER_OUTPUT:
15:44:44 INFO - 2015-10-23 15:44:44,858 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-aes)
15:44:45 INFO - 2015-10-23 15:44:45,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 164876288
15:44:45 INFO - 2015-10-23 15:44:45,432 DEBUG : BROWSER_OUTPUT:
15:44:47 INFO - 2015-10-23 15:44:47,571 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-ccm)
15:44:48 INFO - 2015-10-23 15:44:48,152 DEBUG : BROWSER_OUTPUT: RSS: Main: 174432256
15:44:48 INFO - 2015-10-23 15:44:48,153 DEBUG : BROWSER_OUTPUT:
15:44:50 INFO - 2015-10-23 15:44:50,640 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
15:44:51 INFO - 2015-10-23 15:44:51,224 DEBUG : BROWSER_OUTPUT: RSS: Main: 180121600
15:44:51 INFO - 2015-10-23 15:44:51,224 DEBUG : BROWSER_OUTPUT:
15:44:53 INFO - 2015-10-23 15:44:53,759 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
15:44:54 INFO - 2015-10-23 15:44:54,329 DEBUG : BROWSER_OUTPUT: RSS: Main: 178814976
15:44:54 INFO - 2015-10-23 15:44:54,329 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,099 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56946/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56946/tests/kraken/driver.html?testName=ai-astar)
15:44:56 INFO - 2015-10-23 15:44:56,349 DEBUG : BROWSER_OUTPUT: RSS: Main: 232181760
15:44:56 INFO - 2015-10-23 15:44:56,349 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,352 DEBUG : BROWSER_OUTPUT: __start_tp_report
15:44:56 INFO - 2015-10-23 15:44:56,352 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
15:44:56 INFO - 2015-10-23 15:44:56,352 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;114;107;113;114;113;102;112;107;115
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;134;132;138;148;138;139;131;128;156;129
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;159;157;158;157;157;158;158;164;162
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;70;72;82;71;71;70;85;79;73
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;95;132;94;93;94;128;93;94;131
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;111;110;110;109;109;110;110
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;148;148;147;148;147;148;147;147;148
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;96;95;96;95;96;95
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;69;69;68;69;69;68;68;78;66;67
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;69;69;69;77;69;68;71;69;72
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;84;84;83;84;84;91;85;87;84
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;120;127;118;124;119;120;127;120;126
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;131;142;154;141;150;140;140;140;144;141
15:44:56 INFO - 2015-10-23 15:44:56,353 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;73;72;74;72;72;70;71;72;70;71
15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: __end_tp_report
15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: __start_cc_report
15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,932
15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: __end_cc_report
15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT: __startTimestamp1445640296351__endTimestamp
15:44:56 INFO - 2015-10-23 15:44:56,354 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT: Number of tests: 14
15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.00 Median:113.00 stddev:4.22 (3.7%) stddev-sans-first:4.41
15:44:56 INFO - 2015-10-23 15:44:56,357 DEBUG : BROWSER_OUTPUT: Values: 113.0 114.0 107.0 113.0 114.0 113.0 102.0 112.0 107.0 115.0
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:137.30 Median:138.00 stddev:8.83 (6.4%) stddev-sans-first:9.29
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 134.0 132.0 138.0 148.0 138.0 139.0 131.0 128.0 156.0 129.0
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:158.90 Median:158.50 stddev:2.33 (1.5%) stddev-sans-first:2.47
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 159.0 159.0 157.0 158.0 157.0 157.0 158.0 158.0 164.0 162.0
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:74.40 Median:72.50 stddev:5.50 (7.6%) stddev-sans-first:5.70
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 72.0 82.0 71.0 71.0 70.0 85.0 79.0 73.0
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.70 Median:93.50 stddev:17.73 (19.0%) stddev-sans-first:18.29
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 93.0 95.0 132.0 94.0 93.0 94.0 128.0 93.0 94.0 131.0
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60
15:44:56 INFO - 2015-10-23 15:44:56,358 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 111.0 110.0 110.0 109.0 109.0 110.0 110.0
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.53 (0.4%) stddev-sans-first:0.53
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 147.0 148.0 148.0 147.0 148.0 147.0 148.0 147.0 147.0 148.0
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 96.0 95.0 96.0 95.0 96.0 95.0
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:69.10 Median:69.00 stddev:3.28 (4.8%) stddev-sans-first:3.48
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 68.0 69.0 69.0 68.0 68.0 78.0 66.0 67.0
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:69.50 stddev:2.63 (3.8%) stddev-sans-first:2.78
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 70.0 69.0 69.0 69.0 77.0 69.0 68.0 71.0 69.0 72.0
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:84.50 stddev:2.35 (2.8%) stddev-sans-first:2.47
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT: Values: 86.0 84.0 84.0 83.0 84.0 84.0 91.0 85.0 87.0 84.0
15:44:56 INFO - 2015-10-23 15:44:56,359 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.90 Median:122.00 stddev:3.70 (3.0%) stddev-sans-first:3.64
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: Values: 118.0 120.0 127.0 118.0 124.0 119.0 120.0 127.0 120.0 126.0
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.30 Median:141.50 stddev:6.20 (4.4%) stddev-sans-first:5.05
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: Values: 131.0 142.0 154.0 141.0 150.0 140.0 140.0 140.0 144.0 141.0
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT:
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:72.00 stddev:1.25 (1.7%) stddev-sans-first:1.24
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 74.0 72.0 72.0 70.0 71.0 72.0 70.0 71.0
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
15:44:56 INFO - 2015-10-23 15:44:56,360 DEBUG : BROWSER_OUTPUT:
15:44:57 INFO - 2015-10-23 15:44:57,089 INFO : Browser exited with error code: 0
15:44:57 INFO - 2015-10-23 15:44:57,142 INFO : Completed test kraken (00:01:00)
15:44:57 INFO - 2015-10-23 15:44:57,142 INFO : Starting test v8_7
15:44:57 INFO - 2015-10-23 15:44:57,142 DEBUG : operating with platform_type : linux_
15:44:57 INFO - 2015-10-23 15:44:57,142 INFO : Initialising browser for v8_7 test...
15:44:57 INFO - 2015-10-23 15:44:57,148 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE3i7VW/profile http://localhost:56946/getInfo.html
15:45:01 INFO - 2015-10-23 15:45:01,439 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
15:45:01 INFO - 2015-10-23 15:45:01,439 DEBUG : BROWSER_OUTPUT: colorDepth:24
15:45:01 INFO - 2015-10-23 15:45:01,439 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697
15:45:01 INFO - 2015-10-23 15:45:01,439 DEBUG : BROWSER_OUTPUT: __metrics
15:45:01 INFO - 2015-10-23 15:45:01,515 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:45:02 INFO - 2015-10-23 15:45:02,245 DEBUG : BROWSER_OUTPUT: console.error:
15:45:02 INFO - 2015-10-23 15:45:02,246 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no]
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: Stack:
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:791:13
15:45:02 INFO - 2015-10-23 15:45:02,247 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
15:45:02 INFO - 2015-10-23 15:45:02,248 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
15:45:02 INFO - 2015-10-23 15:45:02,248 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
15:45:02 INFO - 2015-10-23 15:45:02,248 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12
15:45:02 INFO - 2015-10-23 15:45:02,248 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6229.85
15:45:38 INFO - RETURN: kraken: 1499.80
15:45:38 INFO - RETURN: v8_7: 19893.62
15:45:38 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,203,35]]", "result": "19893.62"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,203,35]]", "result": "1499.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,203,35]]", "result": "6229.85"}}}
15:45:38 INFO - Return code: 0
15:45:38 INFO - # TBPL SUCCESS #
15:45:38 INFO - Running post-action listener: _resource_record_post_action
15:45:38 INFO - Running post-run listener: _resource_record_post_run
15:45:39 INFO - Total resource usage - Wall time: 1237s; CPU: 13.0%; Read bytes: 7880704; Write bytes: 410234880; Read time: 1276; Write time: 502376
15:45:39 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 9506816; Read time: 0; Write time: 3248
15:45:39 INFO - run-tests - Wall time: 1223s; CPU: 13.0%; Read bytes: 5644288; Write bytes: 400728064; Read time: 1120; Write time: 499128
15:45:39 INFO - Running post-run listener: _upload_blobber_files
15:45:39 INFO - Blob upload gear active.
15:45:39 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
15:45:39 INFO - Copying logs to upload dir...
15:45:39 INFO - mkdir: /builds/slave/test/build/upload/logs
program finished with exit code 0
elapsedTime=1276.138925
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2015-10-23 15:45:39.996741) =========
========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 15:45:40.000226) =========
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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
build_url:https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2
program finished with exit code 0
elapsedTime=0.009882
build_url: 'https://queue.taskcluster.net/v1/task/4ndwQxl5QFa8hMf4v_zgXw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2'
========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 15:45:40.252292) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:45:40.252699) =========
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=0de07689329a961abe3f5f89000001b3-1445634688.780145-1957243536
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004550
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 15:45:40.509184) =========
========= Started reboot slave lost (results: 0, elapsed: 3 secs) (at 2015-10-23 15:45:40.509545) =========
========= Finished reboot slave lost (results: 0, elapsed: 3 secs) (at 2015-10-23 15:45:43.728722) =========