builder: fx-team_ubuntu64_hw_test-other
slave: talos-linux64-ix-043
starttime: 1446455372.58
results: success (0)
buildid: 20151102000831
builduid: 6ba3099163464737b29fc67280b93594
revision: 83ee1cb04baaedfa97074a651ce34c58973ff5e4
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.582258) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.582688) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.582979) =========
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=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
/builds/slave/test
program finished with exit code 0
elapsedTime=0.006369
basedir: '/builds/slave/test'
========= master_lag: 0.06 =========
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.653071) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.653907) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.825986) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.829294) =========
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=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004558
========= master_lag: 0.08 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.913366) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.913645) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.913999) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.914283) =========
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=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2015-11-02 01:09:32-- 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% 226M=0s
2015-11-02 01:09:33 (226 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.446185
========= master_lag: 0.07 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:33.434445) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:33.434764) =========
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=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.017588
========= master_lag: 0.05 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:33.497693) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:33.498025) =========
bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 83ee1cb04baaedfa97074a651ce34c58973ff5e4 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 83ee1cb04baaedfa97074a651ce34c58973ff5e4 --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=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2015-11-02 01:09:33,506 truncating revision to first 12 chars
2015-11-02 01:09:33,506 Setting DEBUG logging.
2015-11-02 01:09:33,506 attempt 1/10
2015-11-02 01:09:33,506 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/83ee1cb04baa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-11-02 01:09:33,745 unpacking tar archive at: fx-team-83ee1cb04baa/testing/mozharness/
program finished with exit code 0
elapsedTime=0.560449
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.075004) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.075312) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.085498) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.085759) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.086137) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 5 mins, 49 secs) (at 2015-11-02 01:09:34.086432) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite other --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'other', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
01:09:34 INFO - MultiFileLogger online at 20151102 01:09:34 in /builds/slave/test
01:09:34 INFO - Run as scripts/scripts/talos_script.py --suite other --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO
01:09:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
01:09:34 INFO - {'append_to_log': False,
01:09:34 INFO - 'base_work_dir': '/builds/slave/test',
01:09:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO',
01:09:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
01:09:34 INFO - 'branch': 'Fx-Team-Non-PGO',
01:09:34 INFO - 'buildbot_json_path': 'buildprops.json',
01:09:34 INFO - 'config_files': ('talos/linux_config.py',),
01:09:34 INFO - 'default_actions': ('clobber',
01:09:34 INFO - 'read-buildbot-config',
01:09:34 INFO - 'download-and-extract',
01:09:34 INFO - 'populate-webroot',
01:09:34 INFO - 'create-virtualenv',
01:09:34 INFO - 'install',
01:09:34 INFO - 'run-tests'),
01:09:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
01:09:34 INFO - 'download_minidump_stackwalk': True,
01:09:34 INFO - 'download_symbols': 'ondemand',
01:09:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python',
01:09:34 INFO - 'tooltool.py': '/tools/tooltool.py',
01:09:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python',
01:09:34 INFO - '/tools/misc-python/virtualenv.py')},
01:09:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
01:09:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
01:09:34 INFO - 'installer_path': 'installer.exe',
01:09:34 INFO - 'log_level': 'info',
01:09:34 INFO - 'log_name': 'talos',
01:09:34 INFO - 'log_to_console': True,
01:09:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
01:09:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
01:09:34 INFO - 'opt_config_files': (),
01:09:34 INFO - 'pip_index': False,
01:09:34 INFO - 'sps_profile': False,
01:09:34 INFO - 'sps_profile_interval': 0,
01:09:34 INFO - 'suite': 'other',
01:09:34 INFO - 'system_bits': '64',
01:09:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
01:09:34 INFO - 'title': 'talos-linux64-ix-043',
01:09:34 INFO - 'tooltool_cache': '/builds/tooltool_cache',
01:09:34 INFO - 'use_talos_json': True,
01:09:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
01:09:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
01:09:34 INFO - 'work_dir': 'build'}
01:09:34 INFO - #####
01:09:34 INFO - ##### Running clobber step.
01:09:34 INFO - #####
01:09:34 INFO - Running pre-action listener: _resource_record_pre_action
01:09:34 INFO - Running main action method: clobber
01:09:34 INFO - rmtree: /builds/slave/test/build
01:09:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
01:09:34 INFO - Running post-action listener: _resource_record_post_action
01:09:34 INFO - #####
01:09:34 INFO - ##### Running read-buildbot-config step.
01:09:34 INFO - #####
01:09:34 INFO - Running pre-action listener: _resource_record_pre_action
01:09:34 INFO - Running main action method: read_buildbot_config
01:09:34 INFO - Using buildbot properties:
01:09:34 INFO - {
01:09:34 INFO - "properties": {
01:09:34 INFO - "buildnumber": 203,
01:09:34 INFO - "product": "firefox",
01:09:34 INFO - "script_repo_revision": "production",
01:09:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-other",
01:09:34 INFO - "repository": "",
01:09:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos other",
01:09:34 INFO - "buildid": "20151102000831",
01:09:34 INFO - "slavename": "talos-linux64-ix-043",
01:09:34 INFO - "pgo_build": "False",
01:09:34 INFO - "basedir": "/builds/slave/test",
01:09:34 INFO - "project": "",
01:09:34 INFO - "platform": "ubuntu64_hw",
01:09:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
01:09:34 INFO - "slavebuilddir": "test",
01:09:34 INFO - "scheduler": "tests-fx-team-linux64-talos",
01:09:34 INFO - "branch": "fx-team",
01:09:34 INFO - "repo_path": "integration/fx-team",
01:09:34 INFO - "stage_platform": "linux64",
01:09:34 INFO - "builduid": "6ba3099163464737b29fc67280b93594",
01:09:34 INFO - "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"
01:09:34 INFO - },
01:09:34 INFO - "sourcestamp": {
01:09:34 INFO - "repository": "",
01:09:34 INFO - "hasPatch": false,
01:09:34 INFO - "project": "",
01:09:34 INFO - "branch": "fx-team-linux64-talos",
01:09:34 INFO - "changes": [
01:09:34 INFO - {
01:09:34 INFO - "category": null,
01:09:34 INFO - "files": [
01:09:34 INFO - {
01:09:34 INFO - "url": null,
01:09:34 INFO - "name": "https://queue.taskcluster.net/v1/task/1RULjWpRSxqRDJibq0fsIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2"
01:09:34 INFO - }
01:09:34 INFO - ],
01:09:34 INFO - "repository": "",
01:09:34 INFO - "rev": "dec44b4525eb56e6ef4d423f57a6193ddf099206",
01:09:34 INFO - "who": "cbook@mozilla.com",
01:09:34 INFO - "when": 1446455320,
01:09:34 INFO - "number": 6607837,
01:09:34 INFO - "comments": "Bug 1213888 - Block LayerView descendants from interfering with focus. r=kats\n\nUsing a hardware keyboard makes Android leave \"touch mode\", and when\nleaving touch mode from a ViewGroup that prefers descendant\nfocusability, which is what a ScrollView is, Android will shift the\nfocus to the next available View, and cause our LayerView to lose focus.\nThe fix is to set the descendant focusability to blocking.",
01:09:34 INFO - "project": "",
01:09:34 INFO - "at": "Mon 02 Nov 2015 01:08:40",
01:09:34 INFO - "branch": "fx-team-linux64-talos",
01:09:34 INFO - "revlink": "",
01:09:34 INFO - "properties": [
01:09:34 INFO - [
01:09:34 INFO - "buildid",
01:09:34 INFO - "20151102000411",
01:09:34 INFO - "Change"
01:09:34 INFO - ],
01:09:34 INFO - [
01:09:34 INFO - "builduid",
01:09:34 INFO - "795443fafe4e45b9affa943e6cd3651b",
01:09:34 INFO - "Change"
01:09:34 INFO - ],
01:09:34 INFO - [
01:09:34 INFO - "pgo_build",
01:09:34 INFO - "False",
01:09:34 INFO - "Change"
01:09:34 INFO - ]
01:09:34 INFO - ],
01:09:34 INFO - "revision": "dec44b4525eb56e6ef4d423f57a6193ddf099206"
01:09:34 INFO - },
01:09:34 INFO - {
01:09:34 INFO - "category": null,
01:09:34 INFO - "files": [
01:09:34 INFO - {
01:09:34 INFO - "url": null,
01:09:34 INFO - "name": "https://queue.taskcluster.net/v1/task/16GXNDrqS1Wk0l89ocgmgQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2"
01:09:34 INFO - }
01:09:34 INFO - ],
01:09:34 INFO - "repository": "",
01:09:34 INFO - "rev": "46dc0b2f7dce24eb1a5ff6f3341368332a26fcb2",
01:09:34 INFO - "who": "cbook@mozilla.com",
01:09:34 INFO - "when": 1446455324,
01:09:34 INFO - "number": 6607839,
01:09:34 INFO - "comments": "Bug 1205843 - Part 2: Disable maintenance service on WinXP < SP3 - r=rstrong\n\nAttempt to get around the fact that XP SP2 (and below) systems won't be able to\nhandle Authenticode signatues with SHA-2 digests by disabling the maintenance\nservice, the only thing on our update path that uses Authenticode, on systems\nrunning WinXP below SP3.\n\nThis patch both prevents the service from being included in new installations,\nand prevents the service from being used where it is already installed.",
01:09:34 INFO - "project": "",
01:09:34 INFO - "at": "Mon 02 Nov 2015 01:08:44",
01:09:34 INFO - "branch": "fx-team-linux64-talos",
01:09:34 INFO - "revlink": "",
01:09:34 INFO - "properties": [
01:09:34 INFO - [
01:09:34 INFO - "buildid",
01:09:34 INFO - "20151102000630",
01:09:34 INFO - "Change"
01:09:34 INFO - ],
01:09:34 INFO - [
01:09:34 INFO - "builduid",
01:09:34 INFO - "07e935540f0a43198a540945d6001681",
01:09:34 INFO - "Change"
01:09:34 INFO - ],
01:09:34 INFO - [
01:09:34 INFO - "pgo_build",
01:09:34 INFO - "False",
01:09:34 INFO - "Change"
01:09:34 INFO - ]
01:09:34 INFO - ],
01:09:34 INFO - "revision": "46dc0b2f7dce24eb1a5ff6f3341368332a26fcb2"
01:09:34 INFO - },
01:09:34 INFO - {
01:09:34 INFO - "category": null,
01:09:34 INFO - "files": [
01:09:34 INFO - {
01:09:34 INFO - "url": null,
01:09:34 INFO - "name": "https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2"
01:09:34 INFO - }
01:09:34 INFO - ],
01:09:34 INFO - "repository": "",
01:09:34 INFO - "rev": "83ee1cb04baaedfa97074a651ce34c58973ff5e4",
01:09:34 INFO - "who": "cbook@mozilla.com",
01:09:34 INFO - "when": 1446455326,
01:09:34 INFO - "number": 6607842,
01:09:34 INFO - "comments": "Bug 1220407 - include sys/resource.h for struct rusage and getrusage() on all unices, not linux only. r=yoric",
01:09:34 INFO - "project": "",
01:09:34 INFO - "at": "Mon 02 Nov 2015 01:08:46",
01:09:34 INFO - "branch": "fx-team-linux64-talos",
01:09:34 INFO - "revlink": "",
01:09:34 INFO - "properties": [
01:09:34 INFO - [
01:09:34 INFO - "buildid",
01:09:34 INFO - "20151102000831",
01:09:34 INFO - "Change"
01:09:34 INFO - ],
01:09:34 INFO - [
01:09:34 INFO - "builduid",
01:09:34 INFO - "6ba3099163464737b29fc67280b93594",
01:09:34 INFO - "Change"
01:09:34 INFO - ],
01:09:34 INFO - [
01:09:34 INFO - "pgo_build",
01:09:34 INFO - "False",
01:09:34 INFO - "Change"
01:09:34 INFO - ]
01:09:34 INFO - ],
01:09:34 INFO - "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"
01:09:34 INFO - }
01:09:34 INFO - ],
01:09:34 INFO - "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"
01:09:34 INFO - }
01:09:34 INFO - }
01:09:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2.
01:09:34 INFO - Running post-action listener: _resource_record_post_action
01:09:34 INFO - #####
01:09:34 INFO - ##### Running download-and-extract step.
01:09:34 INFO - #####
01:09:34 INFO - Running pre-action listener: _resource_record_pre_action
01:09:34 INFO - Running main action method: download_and_extract
01:09:34 INFO - mkdir: /builds/slave/test/build/tests
01:09:34 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')]}
01:09:34 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
01:09:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json
01:09:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json
01:09:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json
01:09:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1
01:09:39 INFO - Downloaded 1302 bytes.
01:09:39 INFO - Reading from file /builds/slave/test/build/test_packages.json
01:09:39 INFO - Using the following test package requirements:
01:09:39 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'],
01:09:39 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip',
01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'],
01:09:39 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip',
01:09:39 INFO - u'jsshell-linux-x86_64.zip'],
01:09:39 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip',
01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'],
01:09:39 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'],
01:09:39 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip',
01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'],
01:09:39 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip',
01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'],
01:09:39 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip',
01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'],
01:09:39 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'],
01:09:39 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip',
01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']}
01:09:39 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common
01:09: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')]}
01:09:39 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net
01:09:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip
01:09:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip
01:09:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip
01:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1
01:09:40 INFO - Downloaded 21245053 bytes.
01:09:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests
01:09:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip
01:09:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760
01:09:41 INFO - Return code: 0
01:09:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos
01:09:41 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')]}
01:09:41 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net
01:09:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip
01:09:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip
01:09:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip
01:09:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1
01:09:55 INFO - Downloaded 21245053 bytes.
01:09:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests
01:09:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip
01:09:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760
01:09:57 INFO - Return code: 0
01:09: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')]}
01:09:57 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net
01:09:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip
01:09:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip
01:09:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip
01:09:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1
01:09:58 INFO - Downloaded 11131307 bytes.
01:09:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests
01:09:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip
01:09:58 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760
01:09:58 INFO - Return code: 0
01:09:58 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')]}
01:09:58 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net
01:09:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2
01:09:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2
01:09:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2
01:09:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1
01:10:00 INFO - Downloaded 55535362 bytes.
01:10:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2
01:10:00 INFO - mkdir: /builds/slave/test/properties
01:10:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
01:10:00 INFO - Writing to file /builds/slave/test/properties/build_url
01:10:00 INFO - Contents:
01:10:00 INFO - build_url:https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2
01:10:00 INFO - Running post-action listener: _resource_record_post_action
01:10:00 INFO - Running post-action listener: set_extra_try_arguments
01:10:00 INFO - #####
01:10:00 INFO - ##### Running populate-webroot step.
01:10:00 INFO - #####
01:10:00 INFO - Running pre-action listener: _resource_record_pre_action
01:10:00 INFO - Running main action method: populate_webroot
01:10:00 INFO - Guessing talos json url...
01:10: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')]}
01:10:00 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt matches https://queue.taskcluster.net
01:10:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt
01:10:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt
01:10:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.txt
01:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.txt'}, attempt #1
01:10:01 INFO - Downloaded 103 bytes.
01:10:01 INFO - Reading from file /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.txt
01:10:01 INFO - Contents:
01:10:01 INFO - 20151102000831
01:10:01 INFO - https://hg.mozilla.org/integration/fx-team/rev/83ee1cb04baaedfa97074a651ce34c58973ff5e4
01:10:01 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')]}
01:10:01 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/83ee1cb04b/testing/talos/talos.json
01:10:01 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/83ee1cb04b/testing/talos/talos.json to /builds/slave/test/build/talos.json
01:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/83ee1cb04b/testing/talos/talos.json', 'file_name': '/builds/slave/test/build/talos.json'}, attempt #1
01:10:01 INFO - Downloaded 4264 bytes.
01:10:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']},
01:10:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']},
01:10:01 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs',
01:10:01 INFO - '../fennec_ids.txt'],
01:10:01 INFO - 'tests': ['tcheck2']},
01:10:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome',
01:10:01 INFO - '--tppagecycles',
01:10:01 INFO - '7'],
01:10:01 INFO - 'tests': ['tsvgm']}},
01:10:01 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']},
01:10:01 INFO - 'chromez-e10s': {'talos_options': ['--e10s'],
01:10:01 INFO - 'tests': ['tresize', 'tcanvasmark']},
01:10:01 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']},
01:10:01 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'],
01:10:01 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']},
01:10:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
01:10:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:10:01 INFO - 'talos_options': ['--e10s'],
01:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
01:10:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:10:01 INFO - 'tests': ['damp', 'tps']},
01:10:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:10:01 INFO - 'talos_options': ['--e10s'],
01:10:01 INFO - 'tests': ['damp', 'tps']},
01:10:01 INFO - 'g3': {'tests': ['dromaeo_dom']},
01:10:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
01:10:01 INFO - 'other': {'tests': ['a11yr',
01:10:01 INFO - 'ts_paint',
01:10:01 INFO - 'tpaint',
01:10:01 INFO - 'sessionrestore',
01:10:01 INFO - 'sessionrestore_no_auto_restore']},
01:10:01 INFO - 'other-e10s': {'talos_options': ['--e10s'],
01:10:01 INFO - 'tests': ['a11yr',
01:10:01 INFO - 'ts_paint',
01:10:01 INFO - 'tpaint',
01:10:01 INFO - 'sessionrestore',
01:10:01 INFO - 'sessionrestore_no_auto_restore']},
01:10:01 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'],
01:10:01 INFO - 'tests': ['a11yr',
01:10:01 INFO - 'ts_paint',
01:10:01 INFO - 'tpaint',
01:10:01 INFO - 'sessionrestore',
01:10:01 INFO - 'sessionrestore_no_auto_restore']},
01:10:01 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'],
01:10:01 INFO - 'tests': ['a11yr',
01:10:01 INFO - 'ts_paint',
01:10:01 INFO - 'tpaint',
01:10:01 INFO - 'sessionrestore',
01:10:01 INFO - 'sessionrestore_no_auto_restore']},
01:10:01 INFO - 'other_l64': {'tests': ['a11yr',
01:10:01 INFO - 'ts_paint',
01:10:01 INFO - 'tpaint',
01:10:01 INFO - 'sessionrestore',
01:10:01 INFO - 'sessionrestore_no_auto_restore']},
01:10:01 INFO - 'other_nol64': {'tests': ['a11yr',
01:10:01 INFO - 'ts_paint',
01:10:01 INFO - 'tpaint',
01:10:01 INFO - 'sessionrestore',
01:10:01 INFO - 'sessionrestore_no_auto_restore']},
01:10:01 INFO - 'svgr': {'tests': ['tsvgx',
01:10:01 INFO - 'tsvgr_opacity',
01:10:01 INFO - 'tart',
01:10:01 INFO - 'tscrollx',
01:10:01 INFO - 'cart']},
01:10:01 INFO - 'svgr-e10s': {'talos_options': ['--e10s'],
01:10:01 INFO - 'tests': ['tsvgx',
01:10:01 INFO - 'tsvgr_opacity',
01:10:01 INFO - 'tart',
01:10:01 INFO - 'tscrollx',
01:10:01 INFO - 'cart']},
01:10:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:10:01 INFO - 'tests': ['tp5o']},
01:10:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:10:01 INFO - 'talos_options': ['--e10s'],
01:10:01 INFO - 'tests': ['tp5o']},
01:10:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:10:01 INFO - 'talos_options': ['--xperf_path',
01:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
01:10:01 INFO - 'tests': ['tp5n']},
01:10:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:10:01 INFO - 'talos_options': ['--e10s',
01:10:01 INFO - '--xperf_path',
01:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
01:10:01 INFO - 'C:/slave/talos-data/talos/xperf.config'],
01:10:01 INFO - 'tests': ['tp5n']}},
01:10:01 INFO - 'talos.zip': {'path': '',
01:10:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
01:10:01 INFO - Running post-action listener: _resource_record_post_action
01:10:01 INFO - #####
01:10:01 INFO - ##### Running create-virtualenv step.
01:10:01 INFO - #####
01:10:01 INFO - Running pre-action listener: _resource_record_pre_action
01:10:01 INFO - Running main action method: create_virtualenv
01:10:01 INFO - Creating virtualenv /builds/slave/test/build/venv
01:10:01 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
01:10:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv
01:10:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
01:10:01 INFO - Using real prefix '/usr'
01:10:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python
01:10:02 INFO - Installing distribute.............................................................................................................................................................................................done.
01:10:04 INFO - Installing pip.................done.
01:10:04 INFO - Return code: 0
01:10:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
01:10:04 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')]}
01:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:04 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')]}
01:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:04 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 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, '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 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, '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': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', '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
01:10:04 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
01:10:04 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
01:10:04 INFO - Using env: {'DISPLAY': ':0',
01:10:04 INFO - 'HOME': '/home/cltbld',
01:10:04 INFO - 'LANG': 'en_US.UTF-8',
01:10:04 INFO - 'LANGUAGE': 'en_US:en',
01:10:04 INFO - 'LOGNAME': 'cltbld',
01:10:04 INFO - 'MAIL': '/var/mail/cltbld',
01:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:10:04 INFO - 'MOZ_NO_REMOTE': '1',
01:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
01:10:04 INFO - 'NO_EM_RESTART': '1',
01:10:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
01:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
01:10:04 INFO - 'PWD': '/builds/slave/test',
01:10:04 INFO - 'SHELL': '/bin/bash',
01:10:04 INFO - 'SHLVL': '1',
01:10:04 INFO - 'TERM': 'linux',
01:10:04 INFO - 'TMOUT': '86400',
01:10:04 INFO - 'USER': 'cltbld',
01:10:04 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588',
01:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
01:10:04 INFO - '_': '/tools/buildbot/bin/python'}
01:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:10:04 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
01:10:04 INFO - Cleaning up...
01:10:04 INFO - Return code: 0
01:10:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv
01:10:04 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')]}
01:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:04 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')]}
01:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:04 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 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, '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 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, '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': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', '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
01:10:04 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
01:10:04 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
01:10:04 INFO - Using env: {'DISPLAY': ':0',
01:10:04 INFO - 'HOME': '/home/cltbld',
01:10:04 INFO - 'LANG': 'en_US.UTF-8',
01:10:04 INFO - 'LANGUAGE': 'en_US:en',
01:10:04 INFO - 'LOGNAME': 'cltbld',
01:10:04 INFO - 'MAIL': '/var/mail/cltbld',
01:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:10:04 INFO - 'MOZ_NO_REMOTE': '1',
01:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
01:10:04 INFO - 'NO_EM_RESTART': '1',
01:10:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
01:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
01:10:04 INFO - 'PWD': '/builds/slave/test',
01:10:04 INFO - 'SHELL': '/bin/bash',
01:10:04 INFO - 'SHLVL': '1',
01:10:04 INFO - 'TERM': 'linux',
01:10:04 INFO - 'TMOUT': '86400',
01:10:04 INFO - 'USER': 'cltbld',
01:10:04 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588',
01:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
01:10:04 INFO - '_': '/tools/buildbot/bin/python'}
01:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:10:04 INFO - Downloading/unpacking psutil>=0.7.1
01:10:04 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
01:10:04 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
01:10:04 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
01:10:04 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
01:10:04 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
01:10:04 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
01:10:06 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache
01:10:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz
01:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
01:10:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
01:10:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
01:10:06 INFO - Installing collected packages: psutil
01:10:06 INFO - Running setup.py install for psutil
01:10:06 INFO - building 'psutil._psutil_linux' extension
01:10:06 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
01:10:07 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
01:10:07 INFO - building 'psutil._psutil_posix' extension
01:10:07 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
01:10:08 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
01:10:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
01:10:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
01:10:08 INFO - Successfully installed psutil
01:10:08 INFO - Cleaning up...
01:10:08 INFO - Return code: 0
01:10:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv
01:10:08 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')]}
01:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:08 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')]}
01:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:08 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 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, '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 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, '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': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', '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
01:10:08 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
01:10:08 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
01:10:08 INFO - Using env: {'DISPLAY': ':0',
01:10:08 INFO - 'HOME': '/home/cltbld',
01:10:08 INFO - 'LANG': 'en_US.UTF-8',
01:10:08 INFO - 'LANGUAGE': 'en_US:en',
01:10:08 INFO - 'LOGNAME': 'cltbld',
01:10:08 INFO - 'MAIL': '/var/mail/cltbld',
01:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:10:08 INFO - 'MOZ_NO_REMOTE': '1',
01:10:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
01:10:08 INFO - 'NO_EM_RESTART': '1',
01:10:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
01:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
01:10:08 INFO - 'PWD': '/builds/slave/test',
01:10:08 INFO - 'SHELL': '/bin/bash',
01:10:08 INFO - 'SHLVL': '1',
01:10:08 INFO - 'TERM': 'linux',
01:10:08 INFO - 'TMOUT': '86400',
01:10:08 INFO - 'USER': 'cltbld',
01:10:08 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588',
01:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
01:10:08 INFO - '_': '/tools/buildbot/bin/python'}
01:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:10:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
01:10:08 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
01:10:08 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
01:10:08 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
01:10:08 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
01:10:08 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
01:10:08 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
01:10:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz
01:10:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz
01:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
01:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0)
01:10:10 INFO - Installing collected packages: mozsystemmonitor
01:10:10 INFO - Running setup.py install for mozsystemmonitor
01:10:10 INFO - Successfully installed mozsystemmonitor
01:10:10 INFO - Cleaning up...
01:10:10 INFO - Return code: 0
01:10:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
01:10:10 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')]}
01:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:10:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:10 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')]}
01:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:10:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:10 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 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, '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 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, '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': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', '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
01:10:10 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
01:10:10 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
01:10:10 INFO - Using env: {'DISPLAY': ':0',
01:10:10 INFO - 'HOME': '/home/cltbld',
01:10:10 INFO - 'LANG': 'en_US.UTF-8',
01:10:10 INFO - 'LANGUAGE': 'en_US:en',
01:10:10 INFO - 'LOGNAME': 'cltbld',
01:10:10 INFO - 'MAIL': '/var/mail/cltbld',
01:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:10:10 INFO - 'MOZ_NO_REMOTE': '1',
01:10:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
01:10:10 INFO - 'NO_EM_RESTART': '1',
01:10:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
01:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
01:10:10 INFO - 'PWD': '/builds/slave/test',
01:10:10 INFO - 'SHELL': '/bin/bash',
01:10:10 INFO - 'SHLVL': '1',
01:10:10 INFO - 'TERM': 'linux',
01:10:10 INFO - 'TMOUT': '86400',
01:10:10 INFO - 'USER': 'cltbld',
01:10:10 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588',
01:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
01:10:10 INFO - '_': '/tools/buildbot/bin/python'}
01:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:10:10 INFO - Downloading/unpacking blobuploader==1.2.4
01:10:10 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
01:10:10 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
01:10:10 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
01:10:10 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
01:10:10 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
01:10:10 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
01:10:12 INFO - Downloading blobuploader-1.2.4.tar.gz
01:10:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz
01:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
01:10:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
01:10:12 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
01:10:12 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
01:10:12 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
01:10:12 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
01:10:12 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
01:10:12 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
01:10:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz
01:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
01:10:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
01:10:12 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
01:10:12 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
01:10:12 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
01:10:12 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
01:10:12 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
01:10:12 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
01:10:12 INFO - Downloading docopt-0.6.1.tar.gz
01:10:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz
01:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
01:10:12 INFO - Installing collected packages: blobuploader, requests, docopt
01:10:12 INFO - Running setup.py install for blobuploader
01:10:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
01:10:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
01:10:12 INFO - Running setup.py install for requests
01:10:13 INFO - Running setup.py install for docopt
01:10:13 INFO - Successfully installed blobuploader requests docopt
01:10:13 INFO - Cleaning up...
01:10:13 INFO - Return code: 0
01:10:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv
01:10:13 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')]}
01:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:13 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')]}
01:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:13 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 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, '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 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, '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': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', '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
01:10:13 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
01:10:13 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
01:10:13 INFO - Using env: {'DISPLAY': ':0',
01:10:13 INFO - 'HOME': '/home/cltbld',
01:10:13 INFO - 'LANG': 'en_US.UTF-8',
01:10:13 INFO - 'LANGUAGE': 'en_US:en',
01:10:13 INFO - 'LOGNAME': 'cltbld',
01:10:13 INFO - 'MAIL': '/var/mail/cltbld',
01:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:10:13 INFO - 'MOZ_NO_REMOTE': '1',
01:10:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
01:10:13 INFO - 'NO_EM_RESTART': '1',
01:10:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
01:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
01:10:13 INFO - 'PWD': '/builds/slave/test',
01:10:13 INFO - 'SHELL': '/bin/bash',
01:10:13 INFO - 'SHLVL': '1',
01:10:13 INFO - 'TERM': 'linux',
01:10:13 INFO - 'TMOUT': '86400',
01:10:13 INFO - 'USER': 'cltbld',
01:10:13 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588',
01:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
01:10:13 INFO - '_': '/tools/buildbot/bin/python'}
01:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
01:10:13 INFO - Running setup.py (path:/tmp/pip-VCCbX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
01:10:13 INFO - Running setup.py (path:/tmp/pip-jQU5Y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
01:10:13 INFO - Running setup.py (path:/tmp/pip-uIIzji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
01:10:13 INFO - Running setup.py (path:/tmp/pip-F8JHM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
01:10:13 INFO - Running setup.py (path:/tmp/pip-0T_opC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
01:10:13 INFO - Running setup.py (path:/tmp/pip-W7BafH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
01:10:14 INFO - Running setup.py (path:/tmp/pip-QJ34yR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
01:10:14 INFO - Running setup.py (path:/tmp/pip-dP8dAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
01:10:14 INFO - Running setup.py (path:/tmp/pip-IBAZMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
01:10:14 INFO - Running setup.py (path:/tmp/pip-IkogsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
01:10:14 INFO - Running setup.py (path:/tmp/pip-4Bd5Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
01:10:14 INFO - Running setup.py (path:/tmp/pip-Q2RTZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
01:10:14 INFO - Running setup.py (path:/tmp/pip-YcXYk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
01:10:14 INFO - Running setup.py (path:/tmp/pip-WazSRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
01:10:14 INFO - Running setup.py (path:/tmp/pip-pup9e_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
01:10:14 INFO - Running setup.py (path:/tmp/pip-TzM8tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
01:10:14 INFO - Running setup.py (path:/tmp/pip-xiWpsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
01:10:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
01:10:14 INFO - Running setup.py install for manifestparser
01:10:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
01:10:14 INFO - Running setup.py install for mozcrash
01:10:15 INFO - Running setup.py install for mozdebug
01:10:15 INFO - Running setup.py install for mozdevice
01:10:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Running setup.py install for mozfile
01:10:15 INFO - Running setup.py install for mozhttpd
01:10:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Running setup.py install for mozinfo
01:10:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Running setup.py install for mozInstall
01:10:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Running setup.py install for mozleak
01:10:15 INFO - Running setup.py install for mozlog
01:10:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Running setup.py install for moznetwork
01:10:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
01:10:15 INFO - Running setup.py install for mozprocess
01:10:16 INFO - Running setup.py install for mozprofile
01:10:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
01:10:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
01:10:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
01:10:16 INFO - Running setup.py install for mozrunner
01:10:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
01:10:16 INFO - Running setup.py install for mozscreenshot
01:10:16 INFO - Running setup.py install for moztest
01:10:16 INFO - Running setup.py install for mozversion
01:10:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
01:10:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
01:10:16 INFO - Cleaning up...
01:10:16 INFO - Return code: 0
01:10:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv
01:10:16 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')]}
01:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:16 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')]}
01:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:16 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 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, '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 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, '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': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', '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
01:10:16 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
01:10:16 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
01:10:16 INFO - Using env: {'DISPLAY': ':0',
01:10:16 INFO - 'HOME': '/home/cltbld',
01:10:16 INFO - 'LANG': 'en_US.UTF-8',
01:10:16 INFO - 'LANGUAGE': 'en_US:en',
01:10:16 INFO - 'LOGNAME': 'cltbld',
01:10:16 INFO - 'MAIL': '/var/mail/cltbld',
01:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:10:16 INFO - 'MOZ_NO_REMOTE': '1',
01:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
01:10:16 INFO - 'NO_EM_RESTART': '1',
01:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
01:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
01:10:16 INFO - 'PWD': '/builds/slave/test',
01:10:16 INFO - 'SHELL': '/bin/bash',
01:10:16 INFO - 'SHLVL': '1',
01:10:16 INFO - 'TERM': 'linux',
01:10:16 INFO - 'TMOUT': '86400',
01:10:16 INFO - 'USER': 'cltbld',
01:10:16 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588',
01:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
01:10:16 INFO - '_': '/tools/buildbot/bin/python'}
01:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
01:10:16 INFO - Running setup.py (path:/tmp/pip-60diK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
01:10:16 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))
01:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
01:10:16 INFO - Running setup.py (path:/tmp/pip-M8A7Vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
01:10:16 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))
01:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
01:10:16 INFO - Running setup.py (path:/tmp/pip-qopLIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
01:10:16 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))
01:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
01:10:17 INFO - Running setup.py (path:/tmp/pip-iZ0dMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
01:10:17 INFO - Running setup.py (path:/tmp/pip-jZLm7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
01:10:17 INFO - Running setup.py (path:/tmp/pip-iNgDVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
01:10:17 INFO - Running setup.py (path:/tmp/pip-MC2Yp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
01:10:17 INFO - Running setup.py (path:/tmp/pip-WeIVO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
01:10:17 INFO - Running setup.py (path:/tmp/pip-FnmImv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
01:10:17 INFO - Running setup.py (path:/tmp/pip-jgxhnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
01:10:17 INFO - Running setup.py (path:/tmp/pip-lOqfXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
01:10:17 INFO - Running setup.py (path:/tmp/pip-m5k91o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
01:10:17 INFO - Running setup.py (path:/tmp/pip-71rf2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
01:10:17 INFO - Running setup.py (path:/tmp/pip-shikEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
01:10:17 INFO - Running setup.py (path:/tmp/pip-jXl0q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
01:10:17 INFO - Running setup.py (path:/tmp/pip-9g5ZgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
01:10:17 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))
01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
01:10:17 INFO - Running setup.py (path:/tmp/pip-lUjtdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
01:10:18 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))
01:10:18 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))
01:10:18 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))
01:10:18 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))
01:10:18 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
01:10:18 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
01:10:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
01:10:18 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
01:10:18 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
01:10:18 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
01:10:18 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
01:10:18 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
01:10:18 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
01:10:19 INFO - Downloading blessings-1.5.1.tar.gz
01:10:19 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
01:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
01:10:19 INFO - Installing collected packages: blessings
01:10:19 INFO - Running setup.py install for blessings
01:10:19 INFO - Successfully installed blessings
01:10:19 INFO - Cleaning up...
01:10:19 INFO - Return code: 0
01:10:19 INFO - Done creating virtualenv /builds/slave/test/build/venv.
01:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
01:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
01:10:20 INFO - Reading from file tmpfile_stdout
01:10:20 INFO - Current package versions:
01:10:20 INFO - argparse == 1.2.1
01:10:20 INFO - blessings == 1.5.1
01:10:20 INFO - blobuploader == 1.2.4
01:10:20 INFO - docopt == 0.6.1
01:10:20 INFO - manifestparser == 1.1
01:10:20 INFO - mozInstall == 1.12
01:10:20 INFO - mozcrash == 0.16
01:10:20 INFO - mozdebug == 0.1
01:10:20 INFO - mozdevice == 0.47
01:10:20 INFO - mozfile == 1.2
01:10:20 INFO - mozhttpd == 0.7
01:10:20 INFO - mozinfo == 0.9
01:10:20 INFO - mozleak == 0.1
01:10:20 INFO - mozlog == 3.0
01:10:20 INFO - moznetwork == 0.27
01:10:20 INFO - mozprocess == 0.22
01:10:20 INFO - mozprofile == 0.27
01:10:20 INFO - mozrunner == 6.11
01:10:20 INFO - mozscreenshot == 0.1
01:10:20 INFO - mozsystemmonitor == 0.0
01:10:20 INFO - moztest == 0.7
01:10:20 INFO - mozversion == 1.4
01:10:20 INFO - psutil == 3.1.1
01:10:20 INFO - requests == 1.2.3
01:10:20 INFO - wsgiref == 0.1.2
01:10:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv
01:10:20 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')]}
01:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:20 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')]}
01:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
01:10:20 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 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, '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 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, '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': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', '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
01:10:20 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
01:10:20 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
01:10:20 INFO - Using env: {'DISPLAY': ':0',
01:10:20 INFO - 'HOME': '/home/cltbld',
01:10:20 INFO - 'LANG': 'en_US.UTF-8',
01:10:20 INFO - 'LANGUAGE': 'en_US:en',
01:10:20 INFO - 'LOGNAME': 'cltbld',
01:10:20 INFO - 'MAIL': '/var/mail/cltbld',
01:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:10:20 INFO - 'MOZ_NO_REMOTE': '1',
01:10:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
01:10:20 INFO - 'NO_EM_RESTART': '1',
01:10:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
01:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
01:10:20 INFO - 'PWD': '/builds/slave/test',
01:10:20 INFO - 'SHELL': '/bin/bash',
01:10:20 INFO - 'SHLVL': '1',
01:10:20 INFO - 'TERM': 'linux',
01:10:20 INFO - 'TMOUT': '86400',
01:10:20 INFO - 'USER': 'cltbld',
01:10:20 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588',
01:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
01:10:20 INFO - '_': '/tools/buildbot/bin/python'}
01:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:10:20 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))
01:10:20 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))
01:10:20 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))
01:10:20 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))
01:10:20 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))
01:10:20 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))
01:10:20 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))
01:10:20 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))
01:10:20 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))
01:10:20 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))
01:10:20 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))
01:10:20 INFO - Cleaning up...
01:10:20 INFO - Return code: 0
01:10:20 INFO - Running post-action listener: _resource_record_post_action
01:10:20 INFO - Running post-action listener: _start_resource_monitoring
01:10:20 INFO - Starting resource monitoring.
01:10:20 INFO - #####
01:10:20 INFO - ##### Running install step.
01:10:20 INFO - #####
01:10:20 INFO - Running pre-action listener: _resource_record_pre_action
01:10:20 INFO - Running main action method: install
01:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
01:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
01:10:20 INFO - Reading from file tmpfile_stdout
01:10:20 INFO - Detecting whether we're running mozinstall >=1.0...
01:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
01:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
01:10:20 INFO - Reading from file tmpfile_stdout
01:10:20 INFO - Output received:
01:10:20 INFO - Usage: mozinstall [options] installer
01:10:20 INFO - Options:
01:10:20 INFO - -h, --help show this help message and exit
01:10:20 INFO - -d DEST, --destination=DEST
01:10:20 INFO - Directory to install application into. [default:
01:10:20 INFO - "/builds/slave/test"]
01:10:20 INFO - --app=APP Application being installed. [default: firefox]
01:10:20 INFO - mkdir: /builds/slave/test/build/application
01:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application']
01:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application
01:10:35 INFO - Reading from file tmpfile_stdout
01:10:35 INFO - Output received:
01:10:35 INFO - /builds/slave/test/build/application/firefox/firefox
01:10:35 INFO - Running post-action listener: _resource_record_post_action
01:10:35 INFO - #####
01:10:35 INFO - ##### Running run-tests step.
01:10:35 INFO - #####
01:10:35 INFO - Running pre-action listener: _resource_record_pre_action
01:10:35 INFO - Running main action method: run_tests
01:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
01:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
01:10:35 INFO - Python 2.7.3
01:10:35 INFO - Return code: 0
01:10:35 INFO - grabbing minidump binary from tooltool
01:10: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')]}
01:10:35 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 0x16729d0>, '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 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
01:10:35 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
01:10:35 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
01:10:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
01:10:35 INFO - Return code: 0
01:10:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
01:10:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
01:10:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
01:10:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
01:10:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
01:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'other', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost'] in /builds/slave/test/build
01:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite other --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --webServer localhost
01:10:35 INFO - Using env: {'DISPLAY': ':0',
01:10:35 INFO - 'HOME': '/home/cltbld',
01:10:35 INFO - 'LANG': 'en_US.UTF-8',
01:10:35 INFO - 'LANGUAGE': 'en_US:en',
01:10:35 INFO - 'LOGNAME': 'cltbld',
01:10:35 INFO - 'MAIL': '/var/mail/cltbld',
01:10:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
01:10:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
01:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:10:35 INFO - 'MOZ_NO_REMOTE': '1',
01:10:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
01:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
01:10:35 INFO - 'NO_EM_RESTART': '1',
01:10:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
01:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
01:10:35 INFO - 'PWD': '/builds/slave/test',
01:10:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
01:10:35 INFO - 'SHELL': '/bin/bash',
01:10:35 INFO - 'SHLVL': '1',
01:10:35 INFO - 'TERM': 'linux',
01:10:35 INFO - 'TMOUT': '86400',
01:10:35 INFO - 'USER': 'cltbld',
01:10:35 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588',
01:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
01:10:35 INFO - '_': '/tools/buildbot/bin/python'}
01:10:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'other', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost'] with output_timeout 3600
01:10:35 INFO - mozversion INFO | application_buildid: 20151102000831
01:10:35 INFO - mozversion INFO | application_changeset: 83ee1cb04baaedfa97074a651ce34c58973ff5e4
01:10:35 INFO - mozversion INFO | application_display_name: Nightly
01:10:35 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
01:10:35 INFO - mozversion INFO | application_name: Firefox
01:10:35 INFO - mozversion INFO | application_remotingname: firefox
01:10:35 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team
01:10:35 INFO - mozversion INFO | application_vendor: Mozilla
01:10:35 INFO - mozversion INFO | application_version: 45.0a1
01:10:35 INFO - mozversion INFO | platform_buildid: 20151102000831
01:10:35 INFO - mozversion INFO | platform_changeset: 83ee1cb04baaedfa97074a651ce34c58973ff5e4
01:10:35 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team
01:10:35 INFO - mozversion INFO | platform_version: 45.0a1
01:10:35 INFO - 2015-11-02 01:10:35,680 DEBUG : using testdate: 1446455435
01:10:35 INFO - 2015-11-02 01:10:35,680 DEBUG : actual date: 1446455435
01:10:35 INFO - 2015-11-02 01:10:35,761 INFO : starting webserver on 'localhost:36796'
01:10:35 INFO - 2015-11-02 01:10:35,762 INFO : Starting test suite talos-linux64-ix-043
01:10:35 INFO - 2015-11-02 01:10:35,762 INFO : Starting test a11yr
01:10:35 INFO - 2015-11-02 01:10:35,763 DEBUG : operating with platform_type : linux_
01:10:35 INFO - 2015-11-02 01:10:35,763 INFO : Initialising browser for a11yr test...
01:10:35 INFO - 2015-11-02 01:10:35,805 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiGSzdt/profile http://localhost:36796/getInfo.html
01:10:40 INFO - 2015-11-02 01:10:40,110 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:10:40 INFO - 2015-11-02 01:10:40,110 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:10:40 INFO - 2015-11-02 01:10:40,110 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697
01:10:40 INFO - 2015-11-02 01:10:40,110 DEBUG : BROWSER_OUTPUT: __metrics
01:10:40 INFO - 2015-11-02 01:10:40,252 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
01:10:41 INFO - 2015-11-02 01:10:41,324 INFO : Browser initialized.
01:10:41 INFO - 2015-11-02 01:10:41,325 INFO : Running cycle 1/1 for a11yr test...
01:10:41 INFO - 2015-11-02 01:10:41,325 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiGSzdt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/a11y/a11y.manifest.develop -tpchrome -tpmozafterpaint -tpnoisy -tpcycles 1 -tppagecycles 25
01:10:53 INFO - 2015-11-02 01:10:53,181 DEBUG : BROWSER_OUTPUT: RSS: Main: 140685312
01:10:53 INFO - 2015-11-02 01:10:53,181 DEBUG : BROWSER_OUTPUT:
01:10:54 INFO - 2015-11-02 01:10:54,606 DEBUG : BROWSER_OUTPUT:
01:10:54 INFO - 2015-11-02 01:10:54,606 DEBUG : BROWSER_OUTPUT: (firefox:3430): GLib-GObject-CRITICAL **: g_type_add_interface_static: assertion `g_type_parent (interface_type) == G_TYPE_INTERFACE' failed
01:10:54 INFO - 2015-11-02 01:10:54,622 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:10:55 INFO - 2015-11-02 01:10:55,236 DEBUG : BROWSER_OUTPUT: RSS: Main: 178176000
01:10:55 INFO - 2015-11-02 01:10:55,237 DEBUG : BROWSER_OUTPUT:
01:10:56 INFO - 2015-11-02 01:10:56,763 DEBUG : BROWSER_OUTPUT: Cycle 1(2): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:10:57 INFO - 2015-11-02 01:10:57,444 DEBUG : BROWSER_OUTPUT: RSS: Main: 181260288
01:10:57 INFO - 2015-11-02 01:10:57,444 DEBUG : BROWSER_OUTPUT:
01:10:58 INFO - 2015-11-02 01:10:58,970 DEBUG : BROWSER_OUTPUT: Cycle 1(3): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:10:59 INFO - 2015-11-02 01:10:59,658 DEBUG : BROWSER_OUTPUT: RSS: Main: 180834304
01:10:59 INFO - 2015-11-02 01:10:59,658 DEBUG : BROWSER_OUTPUT:
01:11:01 INFO - 2015-11-02 01:11:01,188 DEBUG : BROWSER_OUTPUT: Cycle 1(4): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:01 INFO - 2015-11-02 01:11:01,903 DEBUG : BROWSER_OUTPUT: RSS: Main: 181960704
01:11:01 INFO - 2015-11-02 01:11:01,904 DEBUG : BROWSER_OUTPUT:
01:11:03 INFO - 2015-11-02 01:11:03,562 DEBUG : BROWSER_OUTPUT: Cycle 1(5): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:04 INFO - 2015-11-02 01:11:04,250 DEBUG : BROWSER_OUTPUT: RSS: Main: 180076544
01:11:04 INFO - 2015-11-02 01:11:04,250 DEBUG : BROWSER_OUTPUT:
01:11:05 INFO - 2015-11-02 01:11:05,836 DEBUG : BROWSER_OUTPUT: Cycle 1(6): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:06 INFO - 2015-11-02 01:11:06,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 183234560
01:11:06 INFO - 2015-11-02 01:11:06,539 DEBUG : BROWSER_OUTPUT:
01:11:08 INFO - 2015-11-02 01:11:08,076 DEBUG : BROWSER_OUTPUT: Cycle 1(7): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:08 INFO - 2015-11-02 01:11:08,764 DEBUG : BROWSER_OUTPUT: RSS: Main: 179728384
01:11:08 INFO - 2015-11-02 01:11:08,764 DEBUG : BROWSER_OUTPUT:
01:11:10 INFO - 2015-11-02 01:11:10,382 DEBUG : BROWSER_OUTPUT: Cycle 1(8): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:11 INFO - 2015-11-02 01:11:11,086 DEBUG : BROWSER_OUTPUT: RSS: Main: 182169600
01:11:11 INFO - 2015-11-02 01:11:11,086 DEBUG : BROWSER_OUTPUT:
01:11:12 INFO - 2015-11-02 01:11:12,530 DEBUG : BROWSER_OUTPUT: Cycle 1(9): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:13 INFO - 2015-11-02 01:11:13,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 182976512
01:11:13 INFO - 2015-11-02 01:11:13,221 DEBUG : BROWSER_OUTPUT:
01:11:14 INFO - 2015-11-02 01:11:14,654 DEBUG : BROWSER_OUTPUT: Cycle 1(10): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:15 INFO - 2015-11-02 01:11:15,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 183128064
01:11:15 INFO - 2015-11-02 01:11:15,368 DEBUG : BROWSER_OUTPUT:
01:11:16 INFO - 2015-11-02 01:11:16,995 DEBUG : BROWSER_OUTPUT: Cycle 1(11): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:17 INFO - 2015-11-02 01:11:17,684 DEBUG : BROWSER_OUTPUT: RSS: Main: 181686272
01:11:17 INFO - 2015-11-02 01:11:17,684 DEBUG : BROWSER_OUTPUT:
01:11:19 INFO - 2015-11-02 01:11:19,194 DEBUG : BROWSER_OUTPUT: Cycle 1(12): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:19 INFO - 2015-11-02 01:11:19,210 DEBUG : BROWSER_OUTPUT:
01:11:19 INFO - 2015-11-02 01:11:19,210 DEBUG : BROWSER_OUTPUT: (firefox:3430): GLib-GObject-CRITICAL **: g_type_add_interface_static: assertion `g_type_parent (interface_type) == G_TYPE_INTERFACE' failed
01:11:19 INFO - 2015-11-02 01:11:19,893 DEBUG : BROWSER_OUTPUT: RSS: Main: 183017472
01:11:19 INFO - 2015-11-02 01:11:19,893 DEBUG : BROWSER_OUTPUT:
01:11:21 INFO - 2015-11-02 01:11:21,320 DEBUG : BROWSER_OUTPUT: Cycle 1(13): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:22 INFO - 2015-11-02 01:11:22,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 183533568
01:11:22 INFO - 2015-11-02 01:11:22,023 DEBUG : BROWSER_OUTPUT:
01:11:23 INFO - 2015-11-02 01:11:23,549 DEBUG : BROWSER_OUTPUT: Cycle 1(14): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:24 INFO - 2015-11-02 01:11:24,243 DEBUG : BROWSER_OUTPUT: RSS: Main: 183877632
01:11:24 INFO - 2015-11-02 01:11:24,243 DEBUG : BROWSER_OUTPUT:
01:11:25 INFO - 2015-11-02 01:11:25,774 DEBUG : BROWSER_OUTPUT: Cycle 1(15): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:26 INFO - 2015-11-02 01:11:26,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 180973568
01:11:26 INFO - 2015-11-02 01:11:26,468 DEBUG : BROWSER_OUTPUT:
01:11:27 INFO - 2015-11-02 01:11:27,982 DEBUG : BROWSER_OUTPUT: Cycle 1(16): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:28 INFO - 2015-11-02 01:11:28,676 DEBUG : BROWSER_OUTPUT: RSS: Main: 181501952
01:11:28 INFO - 2015-11-02 01:11:28,676 DEBUG : BROWSER_OUTPUT:
01:11:30 INFO - 2015-11-02 01:11:30,208 DEBUG : BROWSER_OUTPUT: Cycle 1(17): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:30 INFO - 2015-11-02 01:11:30,903 DEBUG : BROWSER_OUTPUT: RSS: Main: 180662272
01:11:30 INFO - 2015-11-02 01:11:30,903 DEBUG : BROWSER_OUTPUT:
01:11:32 INFO - 2015-11-02 01:11:32,333 DEBUG : BROWSER_OUTPUT: Cycle 1(18): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:33 INFO - 2015-11-02 01:11:33,035 DEBUG : BROWSER_OUTPUT: RSS: Main: 181043200
01:11:33 INFO - 2015-11-02 01:11:33,036 DEBUG : BROWSER_OUTPUT:
01:11:34 INFO - 2015-11-02 01:11:34,554 DEBUG : BROWSER_OUTPUT: Cycle 1(19): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:35 INFO - 2015-11-02 01:11:35,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 181235712
01:11:35 INFO - 2015-11-02 01:11:35,249 DEBUG : BROWSER_OUTPUT:
01:11:36 INFO - 2015-11-02 01:11:36,775 DEBUG : BROWSER_OUTPUT: Cycle 1(20): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:37 INFO - 2015-11-02 01:11:37,462 DEBUG : BROWSER_OUTPUT: RSS: Main: 181948416
01:11:37 INFO - 2015-11-02 01:11:37,463 DEBUG : BROWSER_OUTPUT:
01:11:38 INFO - 2015-11-02 01:11:38,893 DEBUG : BROWSER_OUTPUT: Cycle 1(21): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:39 INFO - 2015-11-02 01:11:39,583 DEBUG : BROWSER_OUTPUT: RSS: Main: 181309440
01:11:39 INFO - 2015-11-02 01:11:39,583 DEBUG : BROWSER_OUTPUT:
01:11:41 INFO - 2015-11-02 01:11:41,209 DEBUG : BROWSER_OUTPUT: Cycle 1(22): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:41 INFO - 2015-11-02 01:11:41,912 DEBUG : BROWSER_OUTPUT: RSS: Main: 192036864
01:11:41 INFO - 2015-11-02 01:11:41,912 DEBUG : BROWSER_OUTPUT:
01:11:43 INFO - 2015-11-02 01:11:43,473 DEBUG : BROWSER_OUTPUT: Cycle 1(23): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:43 INFO - 2015-11-02 01:11:43,481 DEBUG : BROWSER_OUTPUT: 1446455503478 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]
01:11:44 INFO - 2015-11-02 01:11:44,185 DEBUG : BROWSER_OUTPUT: RSS: Main: 191315968
01:11:44 INFO - 2015-11-02 01:11:44,185 DEBUG : BROWSER_OUTPUT:
01:11:45 INFO - 2015-11-02 01:11:45,812 DEBUG : BROWSER_OUTPUT: Cycle 1(24): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:46 INFO - 2015-11-02 01:11:46,523 DEBUG : BROWSER_OUTPUT: RSS: Main: 192851968
01:11:46 INFO - 2015-11-02 01:11:46,523 DEBUG : BROWSER_OUTPUT:
01:11:48 INFO - 2015-11-02 01:11:48,074 DEBUG : BROWSER_OUTPUT: Cycle 1(25): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
01:11:48 INFO - 2015-11-02 01:11:48,787 DEBUG : BROWSER_OUTPUT: RSS: Main: 194314240
01:11:48 INFO - 2015-11-02 01:11:48,787 DEBUG : BROWSER_OUTPUT:
01:11:49 INFO - 2015-11-02 01:11:49,982 DEBUG : BROWSER_OUTPUT:
01:11:49 INFO - 2015-11-02 01:11:49,983 DEBUG : BROWSER_OUTPUT: (firefox:3430): GLib-GObject-CRITICAL **: g_type_add_interface_static: assertion `g_type_parent (interface_type) == G_TYPE_INTERFACE' failed
01:11:50 INFO - 2015-11-02 01:11:50,004 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:11:50 INFO - 2015-11-02 01:11:50,586 DEBUG : BROWSER_OUTPUT: RSS: Main: 190353408
01:11:50 INFO - 2015-11-02 01:11:50,586 DEBUG : BROWSER_OUTPUT:
01:11:51 INFO - 2015-11-02 01:11:51,435 DEBUG : BROWSER_OUTPUT: Cycle 1(2): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:11:52 INFO - 2015-11-02 01:11:52,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 188231680
01:11:52 INFO - 2015-11-02 01:11:52,036 DEBUG : BROWSER_OUTPUT:
01:11:52 INFO - 2015-11-02 01:11:52,878 DEBUG : BROWSER_OUTPUT: Cycle 1(3): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:11:53 INFO - 2015-11-02 01:11:53,460 DEBUG : BROWSER_OUTPUT: RSS: Main: 188796928
01:11:53 INFO - 2015-11-02 01:11:53,461 DEBUG : BROWSER_OUTPUT:
01:11:54 INFO - 2015-11-02 01:11:54,327 DEBUG : BROWSER_OUTPUT: Cycle 1(4): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:11:54 INFO - 2015-11-02 01:11:54,913 DEBUG : BROWSER_OUTPUT: RSS: Main: 182800384
01:11:54 INFO - 2015-11-02 01:11:54,913 DEBUG : BROWSER_OUTPUT:
01:11:55 INFO - 2015-11-02 01:11:55,783 DEBUG : BROWSER_OUTPUT: Cycle 1(5): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:11:56 INFO - 2015-11-02 01:11:56,372 DEBUG : BROWSER_OUTPUT: RSS: Main: 180277248
01:11:56 INFO - 2015-11-02 01:11:56,372 DEBUG : BROWSER_OUTPUT:
01:11:57 INFO - 2015-11-02 01:11:57,221 DEBUG : BROWSER_OUTPUT: Cycle 1(6): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:11:57 INFO - 2015-11-02 01:11:57,809 DEBUG : BROWSER_OUTPUT: RSS: Main: 180649984
01:11:57 INFO - 2015-11-02 01:11:57,809 DEBUG : BROWSER_OUTPUT:
01:11:58 INFO - 2015-11-02 01:11:58,631 DEBUG : BROWSER_OUTPUT: Cycle 1(7): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:11:59 INFO - 2015-11-02 01:11:59,217 DEBUG : BROWSER_OUTPUT: RSS: Main: 180039680
01:11:59 INFO - 2015-11-02 01:11:59,218 DEBUG : BROWSER_OUTPUT:
01:12:00 INFO - 2015-11-02 01:12:00,091 DEBUG : BROWSER_OUTPUT: Cycle 1(8): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:00 INFO - 2015-11-02 01:12:00,681 DEBUG : BROWSER_OUTPUT: RSS: Main: 180121600
01:12:00 INFO - 2015-11-02 01:12:00,682 DEBUG : BROWSER_OUTPUT:
01:12:01 INFO - 2015-11-02 01:12:01,531 DEBUG : BROWSER_OUTPUT: Cycle 1(9): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:02 INFO - 2015-11-02 01:12:02,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 178782208
01:12:02 INFO - 2015-11-02 01:12:02,118 DEBUG : BROWSER_OUTPUT:
01:12:02 INFO - 2015-11-02 01:12:02,971 DEBUG : BROWSER_OUTPUT: Cycle 1(10): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:03 INFO - 2015-11-02 01:12:03,560 DEBUG : BROWSER_OUTPUT: RSS: Main: 179142656
01:12:03 INFO - 2015-11-02 01:12:03,561 DEBUG : BROWSER_OUTPUT:
01:12:04 INFO - 2015-11-02 01:12:04,407 DEBUG : BROWSER_OUTPUT: Cycle 1(11): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:04 INFO - 2015-11-02 01:12:04,993 DEBUG : BROWSER_OUTPUT: RSS: Main: 180236288
01:12:04 INFO - 2015-11-02 01:12:04,993 DEBUG : BROWSER_OUTPUT:
01:12:05 INFO - 2015-11-02 01:12:05,854 DEBUG : BROWSER_OUTPUT: Cycle 1(12): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:06 INFO - 2015-11-02 01:12:06,449 DEBUG : BROWSER_OUTPUT: RSS: Main: 180813824
01:12:06 INFO - 2015-11-02 01:12:06,449 DEBUG : BROWSER_OUTPUT:
01:12:07 INFO - 2015-11-02 01:12:07,310 DEBUG : BROWSER_OUTPUT: Cycle 1(13): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:07 INFO - 2015-11-02 01:12:07,913 DEBUG : BROWSER_OUTPUT: RSS: Main: 178397184
01:12:07 INFO - 2015-11-02 01:12:07,913 DEBUG : BROWSER_OUTPUT:
01:12:08 INFO - 2015-11-02 01:12:08,776 DEBUG : BROWSER_OUTPUT: Cycle 1(14): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:09 INFO - 2015-11-02 01:12:09,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 178286592
01:12:09 INFO - 2015-11-02 01:12:09,362 DEBUG : BROWSER_OUTPUT:
01:12:10 INFO - 2015-11-02 01:12:10,228 DEBUG : BROWSER_OUTPUT: Cycle 1(15): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:10 INFO - 2015-11-02 01:12:10,811 DEBUG : BROWSER_OUTPUT: RSS: Main: 176422912
01:12:10 INFO - 2015-11-02 01:12:10,811 DEBUG : BROWSER_OUTPUT:
01:12:11 INFO - 2015-11-02 01:12:11,684 DEBUG : BROWSER_OUTPUT: Cycle 1(16): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:12 INFO - 2015-11-02 01:12:12,271 DEBUG : BROWSER_OUTPUT: RSS: Main: 177999872
01:12:12 INFO - 2015-11-02 01:12:12,271 DEBUG : BROWSER_OUTPUT:
01:12:13 INFO - 2015-11-02 01:12:13,295 DEBUG : BROWSER_OUTPUT: Cycle 1(17): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:13 INFO - 2015-11-02 01:12:13,882 DEBUG : BROWSER_OUTPUT: RSS: Main: 178372608
01:12:13 INFO - 2015-11-02 01:12:13,882 DEBUG : BROWSER_OUTPUT:
01:12:14 INFO - 2015-11-02 01:12:14,756 DEBUG : BROWSER_OUTPUT: Cycle 1(18): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:15 INFO - 2015-11-02 01:12:15,342 DEBUG : BROWSER_OUTPUT: RSS: Main: 179458048
01:12:15 INFO - 2015-11-02 01:12:15,342 DEBUG : BROWSER_OUTPUT:
01:12:16 INFO - 2015-11-02 01:12:16,184 DEBUG : BROWSER_OUTPUT: Cycle 1(19): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:16 INFO - 2015-11-02 01:12:16,771 DEBUG : BROWSER_OUTPUT: RSS: Main: 177201152
01:12:16 INFO - 2015-11-02 01:12:16,771 DEBUG : BROWSER_OUTPUT:
01:12:17 INFO - 2015-11-02 01:12:17,638 DEBUG : BROWSER_OUTPUT: Cycle 1(20): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:18 INFO - 2015-11-02 01:12:18,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 177549312
01:12:18 INFO - 2015-11-02 01:12:18,233 DEBUG : BROWSER_OUTPUT:
01:12:19 INFO - 2015-11-02 01:12:19,087 DEBUG : BROWSER_OUTPUT: Cycle 1(21): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:19 INFO - 2015-11-02 01:12:19,677 DEBUG : BROWSER_OUTPUT: RSS: Main: 177242112
01:12:19 INFO - 2015-11-02 01:12:19,677 DEBUG : BROWSER_OUTPUT:
01:12:20 INFO - 2015-11-02 01:12:20,530 DEBUG : BROWSER_OUTPUT: Cycle 1(22): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:21 INFO - 2015-11-02 01:12:21,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 178618368
01:12:21 INFO - 2015-11-02 01:12:21,117 DEBUG : BROWSER_OUTPUT:
01:12:21 INFO - 2015-11-02 01:12:21,990 DEBUG : BROWSER_OUTPUT: Cycle 1(23): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:22 INFO - 2015-11-02 01:12:22,572 DEBUG : BROWSER_OUTPUT: RSS: Main: 177692672
01:12:22 INFO - 2015-11-02 01:12:22,572 DEBUG : BROWSER_OUTPUT:
01:12:23 INFO - 2015-11-02 01:12:23,434 DEBUG : BROWSER_OUTPUT: Cycle 1(24): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:24 INFO - 2015-11-02 01:12:24,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 179589120
01:12:24 INFO - 2015-11-02 01:12:24,037 DEBUG : BROWSER_OUTPUT:
01:12:24 INFO - 2015-11-02 01:12:24,899 DEBUG : BROWSER_OUTPUT: Cycle 1(25): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
01:12:25 INFO - 2015-11-02 01:12:25,149 DEBUG : BROWSER_OUTPUT: RSS: Main: 180686848
01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT:
01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: __start_tp_report
01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: |0;dhtml.html;1385;1435;1448;1447;1545;1525;1446;1523;1354;1355;1531;1441;1352;1443;1449;1446;1452;1352;1446;1447;1351;1532;1474;1537;1462
01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: |1;tablemutation.html;330;321;321;324;322;322;324;326;322;323;321;323;322;323;323;324;493;324;320;324;322;320;324;324;322
01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: __end_tp_report
01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: __start_cc_report
01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,6834
01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: __end_cc_report
01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455545148__endTimestamp
01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT:
01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
01:12:25 INFO - 2015-11-02 01:12:25,152 DEBUG : BROWSER_OUTPUT: Number of tests: 2
01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT:
01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: [#0] dhtml.html Cycles:25 Average:1447.12 Median:1447.00 stddev:62.33 (4.3%) stddev-sans-first:62.29
01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: Values: 1385.0 1435.0 1448.0 1447.0 1545.0 1525.0 1446.0 1523.0 1354.0 1355.0 1531.0 1441.0 1352.0 1443.0 1449.0 1446.0 1452.0 1352.0 1446.0 1447.0 1351.0 1532.0 1474.0 1537.0 1462.0
01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT:
01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: [#1] tablemutation.html Cycles:25 Average:329.76 Median:323.00 stddev:34.07 (10.5%) stddev-sans-first:34.80
01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: Values: 330.0 321.0 321.0 324.0 322.0 322.0 324.0 326.0 322.0 323.0 321.0 323.0 322.0 323.0 323.0 324.0 493.0 324.0 320.0 324.0 322.0 320.0 324.0 324.0 322.0
01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT:
01:12:25 INFO - 2015-11-02 01:12:25,849 INFO : Browser exited with error code: 0
01:12:25 INFO - 2015-11-02 01:12:25,867 INFO : Completed test a11yr (00:01:50)
01:12:25 INFO - 2015-11-02 01:12:25,867 INFO : Starting test ts_paint
01:12:25 INFO - 2015-11-02 01:12:25,867 DEBUG : operating with platform_type : linux_
01:12:25 INFO - 2015-11-02 01:12:25,868 INFO : Initialising browser for ts_paint test...
01:12:25 INFO - 2015-11-02 01:12:25,874 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/getInfo.html
01:12:29 INFO - 2015-11-02 01:12:29,774 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:12:29 INFO - 2015-11-02 01:12:29,775 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:12:29 INFO - 2015-11-02 01:12:29,775 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697
01:12:29 INFO - 2015-11-02 01:12:29,775 DEBUG : BROWSER_OUTPUT: __metrics
01:12:29 INFO - 2015-11-02 01:12:29,919 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
01:12:31 INFO - 2015-11-02 01:12:31,093 INFO : Browser initialized.
01:12:31 INFO - 2015-11-02 01:12:31,093 INFO : Running cycle 1/20 for ts_paint test...
01:12:31 INFO - 2015-11-02 01:12:31,093 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:32 INFO - 2015-11-02 01:12:32,714 DEBUG : BROWSER_OUTPUT: __start_report1614__end_report
01:12:32 INFO - 2015-11-02 01:12:32,715 DEBUG : BROWSER_OUTPUT:
01:12:32 INFO - 2015-11-02 01:12:32,735 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455552731__endTimestamp
01:12:33 INFO - 2015-11-02 01:12:33,584 INFO : Browser exited with error code: 0
01:12:33 INFO - 2015-11-02 01:12:33,585 INFO : Running cycle 2/20 for ts_paint test...
01:12:33 INFO - 2015-11-02 01:12:33,585 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:34 INFO - 2015-11-02 01:12:34,716 DEBUG : BROWSER_OUTPUT: __start_report1123__end_report
01:12:34 INFO - 2015-11-02 01:12:34,716 DEBUG : BROWSER_OUTPUT:
01:12:34 INFO - 2015-11-02 01:12:34,723 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455554723__endTimestamp
01:12:35 INFO - 2015-11-02 01:12:35,423 INFO : Browser exited with error code: 0
01:12:35 INFO - 2015-11-02 01:12:35,424 INFO : Running cycle 3/20 for ts_paint test...
01:12:35 INFO - 2015-11-02 01:12:35,425 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:36 INFO - 2015-11-02 01:12:36,563 DEBUG : BROWSER_OUTPUT: __start_report1135__end_report
01:12:36 INFO - 2015-11-02 01:12:36,563 DEBUG : BROWSER_OUTPUT:
01:12:36 INFO - 2015-11-02 01:12:36,566 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455556565__endTimestamp
01:12:37 INFO - 2015-11-02 01:12:37,362 INFO : Browser exited with error code: 0
01:12:37 INFO - 2015-11-02 01:12:37,364 INFO : Running cycle 4/20 for ts_paint test...
01:12:37 INFO - 2015-11-02 01:12:37,364 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:38 INFO - 2015-11-02 01:12:38,494 DEBUG : BROWSER_OUTPUT: __start_report1120__end_report
01:12:38 INFO - 2015-11-02 01:12:38,494 DEBUG : BROWSER_OUTPUT:
01:12:38 INFO - 2015-11-02 01:12:38,501 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455558500__endTimestamp
01:12:39 INFO - 2015-11-02 01:12:39,389 INFO : Browser exited with error code: 0
01:12:39 INFO - 2015-11-02 01:12:39,391 INFO : Running cycle 5/20 for ts_paint test...
01:12:39 INFO - 2015-11-02 01:12:39,391 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:40 INFO - 2015-11-02 01:12:40,580 DEBUG : BROWSER_OUTPUT: __start_report1187__end_report
01:12:40 INFO - 2015-11-02 01:12:40,580 DEBUG : BROWSER_OUTPUT:
01:12:40 INFO - 2015-11-02 01:12:40,587 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455560583__endTimestamp
01:12:41 INFO - 2015-11-02 01:12:41,501 INFO : Browser exited with error code: 0
01:12:41 INFO - 2015-11-02 01:12:41,503 INFO : Running cycle 6/20 for ts_paint test...
01:12:41 INFO - 2015-11-02 01:12:41,503 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:42 INFO - 2015-11-02 01:12:42,652 DEBUG : BROWSER_OUTPUT: __start_report1135__end_report
01:12:42 INFO - 2015-11-02 01:12:42,652 DEBUG : BROWSER_OUTPUT:
01:12:42 INFO - 2015-11-02 01:12:42,656 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455562655__endTimestamp
01:12:43 INFO - 2015-11-02 01:12:43,613 INFO : Browser exited with error code: 0
01:12:43 INFO - 2015-11-02 01:12:43,615 INFO : Running cycle 7/20 for ts_paint test...
01:12:43 INFO - 2015-11-02 01:12:43,615 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:44 INFO - 2015-11-02 01:12:44,813 DEBUG : BROWSER_OUTPUT: __start_report1196__end_report
01:12:44 INFO - 2015-11-02 01:12:44,813 DEBUG : BROWSER_OUTPUT:
01:12:44 INFO - 2015-11-02 01:12:44,828 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455564820__endTimestamp
01:12:45 INFO - 2015-11-02 01:12:45,721 INFO : Browser exited with error code: 0
01:12:45 INFO - 2015-11-02 01:12:45,723 INFO : Running cycle 8/20 for ts_paint test...
01:12:45 INFO - 2015-11-02 01:12:45,723 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:46 INFO - 2015-11-02 01:12:46,879 DEBUG : BROWSER_OUTPUT: __start_report1149__end_report
01:12:46 INFO - 2015-11-02 01:12:46,880 DEBUG : BROWSER_OUTPUT:
01:12:46 INFO - 2015-11-02 01:12:46,895 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455566890__endTimestamp
01:12:47 INFO - 2015-11-02 01:12:47,805 INFO : Browser exited with error code: 0
01:12:47 INFO - 2015-11-02 01:12:47,807 INFO : Running cycle 9/20 for ts_paint test...
01:12:47 INFO - 2015-11-02 01:12:47,807 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:48 INFO - 2015-11-02 01:12:48,942 DEBUG : BROWSER_OUTPUT: __start_report1129__end_report
01:12:48 INFO - 2015-11-02 01:12:48,942 DEBUG : BROWSER_OUTPUT:
01:12:48 INFO - 2015-11-02 01:12:48,958 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455568955__endTimestamp
01:12:49 INFO - 2015-11-02 01:12:49,933 INFO : Browser exited with error code: 0
01:12:49 INFO - 2015-11-02 01:12:49,935 INFO : Running cycle 10/20 for ts_paint test...
01:12:49 INFO - 2015-11-02 01:12:49,935 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:51 INFO - 2015-11-02 01:12:51,065 DEBUG : BROWSER_OUTPUT: __start_report1119__end_report
01:12:51 INFO - 2015-11-02 01:12:51,065 DEBUG : BROWSER_OUTPUT:
01:12:51 INFO - 2015-11-02 01:12:51,073 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455571071__endTimestamp
01:12:51 INFO - 2015-11-02 01:12:51,922 INFO : Browser exited with error code: 0
01:12:51 INFO - 2015-11-02 01:12:51,924 INFO : Running cycle 11/20 for ts_paint test...
01:12:51 INFO - 2015-11-02 01:12:51,924 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:53 INFO - 2015-11-02 01:12:53,095 DEBUG : BROWSER_OUTPUT: __start_report1164__end_report
01:12:53 INFO - 2015-11-02 01:12:53,096 DEBUG : BROWSER_OUTPUT:
01:12:53 INFO - 2015-11-02 01:12:53,103 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455573101__endTimestamp
01:12:54 INFO - 2015-11-02 01:12:54,089 INFO : Browser exited with error code: 0
01:12:54 INFO - 2015-11-02 01:12:54,091 INFO : Running cycle 12/20 for ts_paint test...
01:12:54 INFO - 2015-11-02 01:12:54,091 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:55 INFO - 2015-11-02 01:12:55,228 DEBUG : BROWSER_OUTPUT: __start_report1139__end_report
01:12:55 INFO - 2015-11-02 01:12:55,228 DEBUG : BROWSER_OUTPUT:
01:12:55 INFO - 2015-11-02 01:12:55,244 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455575238__endTimestamp
01:12:56 INFO - 2015-11-02 01:12:56,309 INFO : Browser exited with error code: 0
01:12:56 INFO - 2015-11-02 01:12:56,311 INFO : Running cycle 13/20 for ts_paint test...
01:12:56 INFO - 2015-11-02 01:12:56,311 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:57 INFO - 2015-11-02 01:12:57,457 DEBUG : BROWSER_OUTPUT: __start_report1143__end_report
01:12:57 INFO - 2015-11-02 01:12:57,457 DEBUG : BROWSER_OUTPUT:
01:12:57 INFO - 2015-11-02 01:12:57,465 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455577463__endTimestamp
01:12:58 INFO - 2015-11-02 01:12:58,482 INFO : Browser exited with error code: 0
01:12:58 INFO - 2015-11-02 01:12:58,483 INFO : Running cycle 14/20 for ts_paint test...
01:12:58 INFO - 2015-11-02 01:12:58,484 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:12:59 INFO - 2015-11-02 01:12:59,598 DEBUG : BROWSER_OUTPUT: __start_report1105__end_report
01:12:59 INFO - 2015-11-02 01:12:59,598 DEBUG : BROWSER_OUTPUT:
01:12:59 INFO - 2015-11-02 01:12:59,613 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455579607__endTimestamp
01:13:00 INFO - 2015-11-02 01:13:00,649 INFO : Browser exited with error code: 0
01:13:00 INFO - 2015-11-02 01:13:00,651 INFO : Running cycle 15/20 for ts_paint test...
01:13:00 INFO - 2015-11-02 01:13:00,651 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:13:01 INFO - 2015-11-02 01:13:01,781 DEBUG : BROWSER_OUTPUT: __start_report1134__end_report
01:13:01 INFO - 2015-11-02 01:13:01,781 DEBUG : BROWSER_OUTPUT:
01:13:01 INFO - 2015-11-02 01:13:01,782 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455581781__endTimestamp
01:13:02 INFO - 2015-11-02 01:13:02,638 INFO : Browser exited with error code: 0
01:13:02 INFO - 2015-11-02 01:13:02,639 INFO : Running cycle 16/20 for ts_paint test...
01:13:02 INFO - 2015-11-02 01:13:02,640 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:13:03 INFO - 2015-11-02 01:13:03,790 DEBUG : BROWSER_OUTPUT: __start_report1146__end_report
01:13:03 INFO - 2015-11-02 01:13:03,790 DEBUG : BROWSER_OUTPUT:
01:13:03 INFO - 2015-11-02 01:13:03,792 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455583791__endTimestamp
01:13:04 INFO - 2015-11-02 01:13:04,677 INFO : Browser exited with error code: 0
01:13:04 INFO - 2015-11-02 01:13:04,678 INFO : Running cycle 17/20 for ts_paint test...
01:13:04 INFO - 2015-11-02 01:13:04,678 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:13:05 INFO - 2015-11-02 01:13:05,817 DEBUG : BROWSER_OUTPUT: __start_report1133__end_report
01:13:05 INFO - 2015-11-02 01:13:05,817 DEBUG : BROWSER_OUTPUT:
01:13:05 INFO - 2015-11-02 01:13:05,833 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455585827__endTimestamp
01:13:06 INFO - 2015-11-02 01:13:06,765 INFO : Browser exited with error code: 0
01:13:06 INFO - 2015-11-02 01:13:06,767 INFO : Running cycle 18/20 for ts_paint test...
01:13:06 INFO - 2015-11-02 01:13:06,767 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:13:07 INFO - 2015-11-02 01:13:07,890 DEBUG : BROWSER_OUTPUT: __start_report1118__end_report
01:13:07 INFO - 2015-11-02 01:13:07,890 DEBUG : BROWSER_OUTPUT:
01:13:07 INFO - 2015-11-02 01:13:07,906 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455587902__endTimestamp
01:13:08 INFO - 2015-11-02 01:13:08,756 INFO : Browser exited with error code: 0
01:13:08 INFO - 2015-11-02 01:13:08,757 INFO : Running cycle 19/20 for ts_paint test...
01:13:08 INFO - 2015-11-02 01:13:08,757 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:13:09 INFO - 2015-11-02 01:13:09,891 DEBUG : BROWSER_OUTPUT: __start_report1126__end_report
01:13:09 INFO - 2015-11-02 01:13:09,891 DEBUG : BROWSER_OUTPUT:
01:13:09 INFO - 2015-11-02 01:13:09,898 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455589898__endTimestamp
01:13:10 INFO - 2015-11-02 01:13:10,833 INFO : Browser exited with error code: 0
01:13:10 INFO - 2015-11-02 01:13:10,835 INFO : Running cycle 20/20 for ts_paint test...
01:13:10 INFO - 2015-11-02 01:13:10,835 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html
01:13:11 INFO - 2015-11-02 01:13:11,988 DEBUG : BROWSER_OUTPUT: __start_report1143__end_report
01:13:11 INFO - 2015-11-02 01:13:11,988 DEBUG : BROWSER_OUTPUT:
01:13:11 INFO - 2015-11-02 01:13:11,991 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455591990__endTimestamp
01:13:12 INFO - 2015-11-02 01:13:12,872 INFO : Browser exited with error code: 0
01:13:12 INFO - 2015-11-02 01:13:12,894 INFO : Completed test ts_paint (00:00:47)
01:13:12 INFO - 2015-11-02 01:13:12,894 INFO : Starting test tpaint
01:13:12 INFO - 2015-11-02 01:13:12,894 DEBUG : operating with platform_type : linux_
01:13:12 INFO - 2015-11-02 01:13:12,894 INFO : Initialising browser for tpaint test...
01:13:12 INFO - 2015-11-02 01:13:12,901 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7_9Uzk/profile http://localhost:36796/getInfo.html
01:13:16 INFO - 2015-11-02 01:13:16,741 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:13:16 INFO - 2015-11-02 01:13:16,741 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:13:16 INFO - 2015-11-02 01:13:16,741 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697
01:13:16 INFO - 2015-11-02 01:13:16,741 DEBUG : BROWSER_OUTPUT: __metrics
01:13:16 INFO - 2015-11-02 01:13:16,824 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
01:13:18 INFO - 2015-11-02 01:13:18,019 INFO : Browser initialized.
01:13:18 INFO - 2015-11-02 01:13:18,020 INFO : Running cycle 1/1 for tpaint test...
01:13:18 INFO - 2015-11-02 01:13:18,020 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7_9Uzk/profile file:///builds/slave/test/build/tests/talos/talos/startup_test/tpaint.html?auto=1
01:13:44 INFO - 2015-11-02 01:13:44,103 DEBUG : BROWSER_OUTPUT: __start_report201.0650000000005|201.1150000000016|203.23999999999978|205.5300000000002|206.84500000000116|208.90499999999997|209.96000000000004|209.96999999999753|210.86000000000058|212.1949999999997|214.3050000000003|214.375|215.28499999999985|216.44999999999982|219.61999999999898|228.52500000000146|233.22000000000116|239.90499999999884|242.47999999999956|304.71500000000015__end_report__startTimestamp1446455624102__endTimestamp
01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: openingTimes=201.1150000000016,203.23999999999978,205.5300000000002,206.84500000000116,208.90499999999997,209.96000000000004,209.96999999999753,210.86000000000058,212.1949999999997,214.3050000000003,214.375,215.28499999999985,216.44999999999982,219.61999999999898,228.52500000000146,233.22000000000116,239.90499999999884,242.47999999999956,304.71500000000015
01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: avgOpenTime:219.93
01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: minOpenTime:201.07
01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: maxOpenTime:304.72
01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: medOpenTime:213.25
01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: __xulWinOpenTime:213.25
01:13:45 INFO - 2015-11-02 01:13:45,130 INFO : Browser exited with error code: 0
01:13:45 INFO - 2015-11-02 01:13:45,141 INFO : Completed test tpaint (00:00:32)
01:13:45 INFO - 2015-11-02 01:13:45,141 INFO : Starting test sessionrestore
01:13:45 INFO - 2015-11-02 01:13:45,141 DEBUG : operating with platform_type : linux_
01:13:45 INFO - 2015-11-02 01:13:45,141 INFO : Initialising browser for sessionrestore test...
01:13:45 INFO - 2015-11-02 01:13:45,150 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/getInfo.html
01:13:52 INFO - 2015-11-02 01:13:51,999 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:13:52 INFO - 2015-11-02 01:13:52,000 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:13:52 INFO - 2015-11-02 01:13:52,000 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1151/811
01:13:52 INFO - 2015-11-02 01:13:52,000 DEBUG : BROWSER_OUTPUT: __metrics
01:13:52 INFO - 2015-11-02 01:13:52,136 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
01:13:52 INFO - 2015-11-02 01:13:52,865 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code.
01:13:53 INFO - 2015-11-02 01:13:53,629 INFO : Browser initialized.
01:13:53 INFO - 2015-11-02 01:13:53,630 INFO : Running cycle 1/10 for sessionrestore test...
01:13:53 INFO - 2015-11-02 01:13:53,630 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:13:53 INFO - 2015-11-02 01:13:53,632 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:13:53 INFO - 2015-11-02 01:13:53,633 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:13:58 INFO - 2015-11-02 01:13:58,517 DEBUG : BROWSER_OUTPUT: __start_report2557__end_report
01:13:58 INFO - 2015-11-02 01:13:58,517 DEBUG : BROWSER_OUTPUT:
01:13:58 INFO - 2015-11-02 01:13:58,518 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455638513__endTimestamp
01:13:58 INFO - 2015-11-02 01:13:58,518 DEBUG : BROWSER_OUTPUT:
01:14:00 INFO - 2015-11-02 01:14:00,085 INFO : Browser exited with error code: 0
01:14:00 INFO - 2015-11-02 01:14:00,087 INFO : Running cycle 2/10 for sessionrestore test...
01:14:00 INFO - 2015-11-02 01:14:00,087 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:14:00 INFO - 2015-11-02 01:14:00,089 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:14:00 INFO - 2015-11-02 01:14:00,090 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:14:04 INFO - 2015-11-02 01:14:04,285 DEBUG : BROWSER_OUTPUT: __start_report2181__end_report
01:14:04 INFO - 2015-11-02 01:14:04,285 DEBUG : BROWSER_OUTPUT:
01:14:04 INFO - 2015-11-02 01:14:04,285 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455644281__endTimestamp
01:14:04 INFO - 2015-11-02 01:14:04,285 DEBUG : BROWSER_OUTPUT:
01:14:05 INFO - 2015-11-02 01:14:05,989 INFO : Browser exited with error code: 0
01:14:05 INFO - 2015-11-02 01:14:05,991 INFO : Running cycle 3/10 for sessionrestore test...
01:14:05 INFO - 2015-11-02 01:14:05,991 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:14:05 INFO - 2015-11-02 01:14:05,993 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:14:05 INFO - 2015-11-02 01:14:05,994 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:14:10 INFO - 2015-11-02 01:14:10,157 DEBUG : BROWSER_OUTPUT: __start_report2183__end_report
01:14:10 INFO - 2015-11-02 01:14:10,158 DEBUG : BROWSER_OUTPUT:
01:14:10 INFO - 2015-11-02 01:14:10,158 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455650156__endTimestamp
01:14:10 INFO - 2015-11-02 01:14:10,158 DEBUG : BROWSER_OUTPUT:
01:14:11 INFO - 2015-11-02 01:14:11,801 INFO : Browser exited with error code: 0
01:14:11 INFO - 2015-11-02 01:14:11,803 INFO : Running cycle 4/10 for sessionrestore test...
01:14:11 INFO - 2015-11-02 01:14:11,803 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:14:11 INFO - 2015-11-02 01:14:11,805 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:14:11 INFO - 2015-11-02 01:14:11,806 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:14:15 INFO - 2015-11-02 01:14:15,979 DEBUG : BROWSER_OUTPUT: __start_report2196__end_report
01:14:15 INFO - 2015-11-02 01:14:15,979 DEBUG : BROWSER_OUTPUT:
01:14:15 INFO - 2015-11-02 01:14:15,979 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455655978__endTimestamp
01:14:15 INFO - 2015-11-02 01:14:15,979 DEBUG : BROWSER_OUTPUT:
01:14:17 INFO - 2015-11-02 01:14:17,733 INFO : Browser exited with error code: 0
01:14:17 INFO - 2015-11-02 01:14:17,735 INFO : Running cycle 5/10 for sessionrestore test...
01:14:17 INFO - 2015-11-02 01:14:17,735 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:14:17 INFO - 2015-11-02 01:14:17,737 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:14:17 INFO - 2015-11-02 01:14:17,738 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:14:21 INFO - 2015-11-02 01:14:21,970 DEBUG : BROWSER_OUTPUT: __start_report2218__end_report
01:14:21 INFO - 2015-11-02 01:14:21,971 DEBUG : BROWSER_OUTPUT:
01:14:21 INFO - 2015-11-02 01:14:21,971 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455661966__endTimestamp
01:14:21 INFO - 2015-11-02 01:14:21,971 DEBUG : BROWSER_OUTPUT:
01:14:23 INFO - 2015-11-02 01:14:23,729 INFO : Browser exited with error code: 0
01:14:23 INFO - 2015-11-02 01:14:23,731 INFO : Running cycle 6/10 for sessionrestore test...
01:14:23 INFO - 2015-11-02 01:14:23,731 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:14:23 INFO - 2015-11-02 01:14:23,733 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:14:23 INFO - 2015-11-02 01:14:23,734 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:14:27 INFO - 2015-11-02 01:14:27,870 DEBUG : BROWSER_OUTPUT: __start_report2158__end_report
01:14:27 INFO - 2015-11-02 01:14:27,870 DEBUG : BROWSER_OUTPUT:
01:14:27 INFO - 2015-11-02 01:14:27,870 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455667866__endTimestamp
01:14:27 INFO - 2015-11-02 01:14:27,870 DEBUG : BROWSER_OUTPUT:
01:14:29 INFO - 2015-11-02 01:14:29,641 INFO : Browser exited with error code: 0
01:14:29 INFO - 2015-11-02 01:14:29,643 INFO : Running cycle 7/10 for sessionrestore test...
01:14:29 INFO - 2015-11-02 01:14:29,643 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:14:29 INFO - 2015-11-02 01:14:29,645 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:14:29 INFO - 2015-11-02 01:14:29,646 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:14:33 INFO - 2015-11-02 01:14:33,834 DEBUG : BROWSER_OUTPUT: __start_report2191__end_report
01:14:33 INFO - 2015-11-02 01:14:33,834 DEBUG : BROWSER_OUTPUT:
01:14:33 INFO - 2015-11-02 01:14:33,834 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455673834__endTimestamp
01:14:33 INFO - 2015-11-02 01:14:33,834 DEBUG : BROWSER_OUTPUT:
01:14:35 INFO - 2015-11-02 01:14:35,593 INFO : Browser exited with error code: 0
01:14:35 INFO - 2015-11-02 01:14:35,595 INFO : Running cycle 8/10 for sessionrestore test...
01:14:35 INFO - 2015-11-02 01:14:35,595 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:14:35 INFO - 2015-11-02 01:14:35,597 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:14:35 INFO - 2015-11-02 01:14:35,598 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:14:39 INFO - 2015-11-02 01:14:39,799 DEBUG : BROWSER_OUTPUT: __start_report2206__end_report
01:14:39 INFO - 2015-11-02 01:14:39,799 DEBUG : BROWSER_OUTPUT:
01:14:39 INFO - 2015-11-02 01:14:39,799 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455679798__endTimestamp
01:14:39 INFO - 2015-11-02 01:14:39,799 DEBUG : BROWSER_OUTPUT:
01:14:41 INFO - 2015-11-02 01:14:41,597 INFO : Browser exited with error code: 0
01:14:41 INFO - 2015-11-02 01:14:41,599 INFO : Running cycle 9/10 for sessionrestore test...
01:14:41 INFO - 2015-11-02 01:14:41,599 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:14:41 INFO - 2015-11-02 01:14:41,601 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:14:41 INFO - 2015-11-02 01:14:41,602 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:14:45 INFO - 2015-11-02 01:14:45,736 DEBUG : BROWSER_OUTPUT: __start_report2140__end_report
01:14:45 INFO - 2015-11-02 01:14:45,736 DEBUG : BROWSER_OUTPUT:
01:14:45 INFO - 2015-11-02 01:14:45,736 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455685732__endTimestamp
01:14:45 INFO - 2015-11-02 01:14:45,737 DEBUG : BROWSER_OUTPUT:
01:14:47 INFO - 2015-11-02 01:14:47,533 INFO : Browser exited with error code: 0
01:14:47 INFO - 2015-11-02 01:14:47,535 INFO : Running cycle 10/10 for sessionrestore test...
01:14:47 INFO - 2015-11-02 01:14:47,535 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js
01:14:47 INFO - 2015-11-02 01:14:47,537 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json
01:14:47 INFO - 2015-11-02 01:14:47,538 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:14:51 INFO - 2015-11-02 01:14:51,714 DEBUG : BROWSER_OUTPUT: __start_report2153__end_report
01:14:51 INFO - 2015-11-02 01:14:51,714 DEBUG : BROWSER_OUTPUT:
01:14:51 INFO - 2015-11-02 01:14:51,714 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455691712__endTimestamp
01:14:51 INFO - 2015-11-02 01:14:51,714 DEBUG : BROWSER_OUTPUT:
01:14:53 INFO - 2015-11-02 01:14:53,513 INFO : Browser exited with error code: 0
01:14:53 INFO - 2015-11-02 01:14:53,532 INFO : Completed test sessionrestore (00:01:08)
01:14:53 INFO - 2015-11-02 01:14:53,532 INFO : Starting test sessionrestore_no_auto_restore
01:14:53 INFO - 2015-11-02 01:14:53,532 DEBUG : operating with platform_type : linux_
01:14:53 INFO - 2015-11-02 01:14:53,532 INFO : Initialising browser for sessionrestore_no_auto_restore test...
01:14:53 INFO - 2015-11-02 01:14:53,540 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/getInfo.html
01:14:57 INFO - 2015-11-02 01:14:57,157 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:14:57 INFO - 2015-11-02 01:14:57,157 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:14:57 INFO - 2015-11-02 01:14:57,157 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1151/811
01:14:57 INFO - 2015-11-02 01:14:57,157 DEBUG : BROWSER_OUTPUT: __metrics
01:14:57 INFO - 2015-11-02 01:14:57,265 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
01:14:58 INFO - 2015-11-02 01:14:58,560 INFO : Browser initialized.
01:14:58 INFO - 2015-11-02 01:14:58,561 INFO : Running cycle 1/10 for sessionrestore_no_auto_restore test...
01:14:58 INFO - 2015-11-02 01:14:58,561 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:14:58 INFO - 2015-11-02 01:14:58,563 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:14:58 INFO - 2015-11-02 01:14:58,564 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:00 INFO - 2015-11-02 01:15:00,279 DEBUG : BROWSER_OUTPUT: __start_report1073__end_report
01:15:00 INFO - 2015-11-02 01:15:00,280 DEBUG : BROWSER_OUTPUT:
01:15:00 INFO - 2015-11-02 01:15:00,280 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455700274__endTimestamp
01:15:00 INFO - 2015-11-02 01:15:00,280 DEBUG : BROWSER_OUTPUT:
01:15:01 INFO - 2015-11-02 01:15:01,152 INFO : Browser exited with error code: 0
01:15:01 INFO - 2015-11-02 01:15:01,154 INFO : Running cycle 2/10 for sessionrestore_no_auto_restore test...
01:15:01 INFO - 2015-11-02 01:15:01,154 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:15:01 INFO - 2015-11-02 01:15:01,156 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:15:01 INFO - 2015-11-02 01:15:01,156 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:02 INFO - 2015-11-02 01:15:02,439 DEBUG : BROWSER_OUTPUT: __start_report785__end_report
01:15:02 INFO - 2015-11-02 01:15:02,439 DEBUG : BROWSER_OUTPUT:
01:15:02 INFO - 2015-11-02 01:15:02,439 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455702436__endTimestamp
01:15:02 INFO - 2015-11-02 01:15:02,439 DEBUG : BROWSER_OUTPUT:
01:15:03 INFO - 2015-11-02 01:15:03,417 INFO : Browser exited with error code: 0
01:15:03 INFO - 2015-11-02 01:15:03,419 INFO : Running cycle 3/10 for sessionrestore_no_auto_restore test...
01:15:03 INFO - 2015-11-02 01:15:03,419 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:15:03 INFO - 2015-11-02 01:15:03,421 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:15:03 INFO - 2015-11-02 01:15:03,421 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:04 INFO - 2015-11-02 01:15:04,735 DEBUG : BROWSER_OUTPUT: __start_report832__end_report
01:15:04 INFO - 2015-11-02 01:15:04,735 DEBUG : BROWSER_OUTPUT:
01:15:04 INFO - 2015-11-02 01:15:04,735 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455704728__endTimestamp
01:15:04 INFO - 2015-11-02 01:15:04,735 DEBUG : BROWSER_OUTPUT:
01:15:05 INFO - 2015-11-02 01:15:05,737 INFO : Browser exited with error code: 0
01:15:05 INFO - 2015-11-02 01:15:05,739 INFO : Running cycle 4/10 for sessionrestore_no_auto_restore test...
01:15:05 INFO - 2015-11-02 01:15:05,739 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:15:05 INFO - 2015-11-02 01:15:05,741 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:15:05 INFO - 2015-11-02 01:15:05,741 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:06 INFO - 2015-11-02 01:15:06,978 DEBUG : BROWSER_OUTPUT: __start_report758__end_report
01:15:06 INFO - 2015-11-02 01:15:06,978 DEBUG : BROWSER_OUTPUT:
01:15:06 INFO - 2015-11-02 01:15:06,978 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455706977__endTimestamp
01:15:06 INFO - 2015-11-02 01:15:06,978 DEBUG : BROWSER_OUTPUT:
01:15:07 INFO - 2015-11-02 01:15:07,993 INFO : Browser exited with error code: 0
01:15:07 INFO - 2015-11-02 01:15:07,995 INFO : Running cycle 5/10 for sessionrestore_no_auto_restore test...
01:15:07 INFO - 2015-11-02 01:15:07,995 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:15:07 INFO - 2015-11-02 01:15:07,997 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:15:07 INFO - 2015-11-02 01:15:07,997 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:09 INFO - 2015-11-02 01:15:09,305 DEBUG : BROWSER_OUTPUT: __start_report818__end_report
01:15:09 INFO - 2015-11-02 01:15:09,305 DEBUG : BROWSER_OUTPUT:
01:15:09 INFO - 2015-11-02 01:15:09,306 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455709302__endTimestamp
01:15:09 INFO - 2015-11-02 01:15:09,306 DEBUG : BROWSER_OUTPUT:
01:15:10 INFO - 2015-11-02 01:15:10,325 INFO : Browser exited with error code: 0
01:15:10 INFO - 2015-11-02 01:15:10,327 INFO : Running cycle 6/10 for sessionrestore_no_auto_restore test...
01:15:10 INFO - 2015-11-02 01:15:10,327 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:15:10 INFO - 2015-11-02 01:15:10,329 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:15:10 INFO - 2015-11-02 01:15:10,330 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:11 INFO - 2015-11-02 01:15:11,552 DEBUG : BROWSER_OUTPUT: __start_report772__end_report
01:15:11 INFO - 2015-11-02 01:15:11,552 DEBUG : BROWSER_OUTPUT:
01:15:11 INFO - 2015-11-02 01:15:11,552 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455711549__endTimestamp
01:15:11 INFO - 2015-11-02 01:15:11,552 DEBUG : BROWSER_OUTPUT:
01:15:12 INFO - 2015-11-02 01:15:12,601 INFO : Browser exited with error code: 0
01:15:12 INFO - 2015-11-02 01:15:12,603 INFO : Running cycle 7/10 for sessionrestore_no_auto_restore test...
01:15:12 INFO - 2015-11-02 01:15:12,603 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:15:12 INFO - 2015-11-02 01:15:12,605 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:15:12 INFO - 2015-11-02 01:15:12,606 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:13 INFO - 2015-11-02 01:15:13,940 DEBUG : BROWSER_OUTPUT: __start_report843__end_report
01:15:13 INFO - 2015-11-02 01:15:13,940 DEBUG : BROWSER_OUTPUT:
01:15:13 INFO - 2015-11-02 01:15:13,941 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455713936__endTimestamp
01:15:13 INFO - 2015-11-02 01:15:13,941 DEBUG : BROWSER_OUTPUT:
01:15:14 INFO - 2015-11-02 01:15:14,981 INFO : Browser exited with error code: 0
01:15:14 INFO - 2015-11-02 01:15:14,983 INFO : Running cycle 8/10 for sessionrestore_no_auto_restore test...
01:15:14 INFO - 2015-11-02 01:15:14,983 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:15:14 INFO - 2015-11-02 01:15:14,985 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:15:14 INFO - 2015-11-02 01:15:14,986 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:16 INFO - 2015-11-02 01:15:16,237 DEBUG : BROWSER_OUTPUT: __start_report786__end_report
01:15:16 INFO - 2015-11-02 01:15:16,237 DEBUG : BROWSER_OUTPUT:
01:15:16 INFO - 2015-11-02 01:15:16,237 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455716236__endTimestamp
01:15:16 INFO - 2015-11-02 01:15:16,237 DEBUG : BROWSER_OUTPUT:
01:15:17 INFO - 2015-11-02 01:15:17,337 INFO : Browser exited with error code: 0
01:15:17 INFO - 2015-11-02 01:15:17,339 INFO : Running cycle 9/10 for sessionrestore_no_auto_restore test...
01:15:17 INFO - 2015-11-02 01:15:17,339 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:15:17 INFO - 2015-11-02 01:15:17,341 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:15:17 INFO - 2015-11-02 01:15:17,342 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:18 INFO - 2015-11-02 01:15:18,653 DEBUG : BROWSER_OUTPUT: __start_report811__end_report
01:15:18 INFO - 2015-11-02 01:15:18,653 DEBUG : BROWSER_OUTPUT:
01:15:18 INFO - 2015-11-02 01:15:18,654 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455718649__endTimestamp
01:15:18 INFO - 2015-11-02 01:15:18,654 DEBUG : BROWSER_OUTPUT:
01:15:19 INFO - 2015-11-02 01:15:19,673 INFO : Browser exited with error code: 0
01:15:19 INFO - 2015-11-02 01:15:19,675 INFO : Running cycle 10/10 for sessionrestore_no_auto_restore test...
01:15:19 INFO - 2015-11-02 01:15:19,675 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js
01:15:19 INFO - 2015-11-02 01:15:19,677 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json
01:15:19 INFO - 2015-11-02 01:15:19,677 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html
01:15:20 INFO - 2015-11-02 01:15:20,952 DEBUG : BROWSER_OUTPUT: __start_report806__end_report
01:15:20 INFO - 2015-11-02 01:15:20,952 DEBUG : BROWSER_OUTPUT:
01:15:20 INFO - 2015-11-02 01:15:20,953 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455720952__endTimestamp
01:15:20 INFO - 2015-11-02 01:15:20,953 DEBUG : BROWSER_OUTPUT:
01:15:22 INFO - 2015-11-02 01:15:22,029 INFO : Browser exited with error code: 0
01:15:22 INFO - 2015-11-02 01:15:22,042 INFO : Completed test sessionrestore_no_auto_restore (00:00:28)
01:15:22 INFO - 2015-11-02 01:15:22,353 INFO : Completed test suite (00:04:46)
01:15:22 INFO - 2015-11-02 01:15:22,354 DEBUG : Working with test: a11yr
01:15:22 INFO - 2015-11-02 01:15:22,354 DEBUG : Generating results file: a11yr
01:15:22 INFO - 2015-11-02 01:15:22,354 DEBUG : Working with test: ts_paint
01:15:22 INFO - 2015-11-02 01:15:22,354 DEBUG : Generating results file: ts_paint
01:15:22 INFO - 2015-11-02 01:15:22,355 DEBUG : Working with test: tpaint
01:15:22 INFO - 2015-11-02 01:15:22,355 DEBUG : Generating results file: tpaint
01:15:22 INFO - 2015-11-02 01:15:22,355 DEBUG : Working with test: sessionrestore
01:15:22 INFO - 2015-11-02 01:15:22,355 DEBUG : Generating results file: sessionrestore
01:15:22 INFO - 2015-11-02 01:15:22,356 DEBUG : Working with test: sessionrestore_no_auto_restore
01:15:22 INFO - 2015-11-02 01:15:22,356 DEBUG : Generating results file: sessionrestore_no_auto_restore
01:15:22 INFO - 2015-11-02 01:15:22,356 INFO : Posting result 0 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:15:22 INFO - 2015-11-02 01:15:22,526 DEBUG : process_Request line: a11yr_paint graph.html#tests=[[223,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,526 DEBUG : process_Request line: a11yr_paint 683.95 graph.html#tests=[[223,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,526 INFO : Posting result 1 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:15:22 INFO - 2015-11-02 01:15:22,584 DEBUG : process_Request line: ts_paint graph.html#tests=[[83,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,585 DEBUG : process_Request line: ts_paint 1158.97 graph.html#tests=[[83,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,585 INFO : Posting result 2 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:15:22 INFO - 2015-11-02 01:15:22,715 DEBUG : process_Request line: tpaint graph.html#tests=[[82,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,715 DEBUG : process_Request line: tpaint 215.28 graph.html#tests=[[82,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,715 INFO : Posting result 3 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:15:22 INFO - 2015-11-02 01:15:22,762 DEBUG : process_Request line: sessionrestore graph.html#tests=[[313,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,762 DEBUG : process_Request line: sessionrestore 2215.54 graph.html#tests=[[313,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,762 INFO : Posting result 4 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:15:22 INFO - 2015-11-02 01:15:22,808 DEBUG : process_Request line: sessionrestore_no_auto_restore graph.html#tests=[[315,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,808 DEBUG : process_Request line: sessionrestore_no_auto_restore 824.57 graph.html#tests=[[315,132,35]]
01:15:22 INFO - 2015-11-02 01:15:22,809 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"dhtml.html": [1385.0, 1435.0, 1448.0, 1447.0, 1545.0, 1525.0, 1446.0, 1523.0, 1354.0, 1355.0, 1531.0, 1441.0, 1352.0, 1443.0, 1449.0, 1446.0, 1452.0, 1352.0, 1446.0, 1447.0, 1351.0, 1532.0, 1474.0, 1537.0, 1462.0], "tablemutation.html": [330.0, 321.0, 321.0, 324.0, 322.0, 322.0, 324.0, 326.0, 322.0, 323.0, 321.0, 323.0, 322.0, 323.0, 323.0, 324.0, 493.0, 324.0, 320.0, 324.0, 322.0, 320.0, 324.0, 324.0, 322.0]}, "summary": {"suite": 683.9467132558559, "subtests": {"dhtml.html": {"filtered": 1447.0, "value": 1447.0}, "tablemutation.html": {"filtered": 323.0, "value": 323.0}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "a11yr", "options": {"responsiveness": false, "tpmozafterpaint": true, "tpchrome": true, "tppagecycles": 25, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}, {"talos_counters": {}, "results": {"ts_paint": [1614.0, 1123.0, 1135.0, 1120.0, 1187.0, 1135.0, 1196.0, 1149.0, 1129.0, 1119.0, 1164.0, 1139.0, 1143.0, 1105.0, 1134.0, 1146.0, 1133.0, 1118.0, 1126.0, 1143.0]}, "summary": {"suite": 1135.0, "subtests": {"ts_paint": {"filtered": 1135.0, "value": 1135.0}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "ts_paint", "options": {"responsiveness": false, "cycles": 20, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}, {"talos_counters": {}, "results": {"tpaint": [201.0650000000005, 201.1150000000016, 203.23999999999978, 205.5300000000002, 206.84500000000116, 208.90499999999997, 209.96000000000004, 209.96999999999753, 210.86000000000058, 212.1949999999997, 214.3050000000003, 214.375, 215.28499999999985, 216.44999999999982, 219.61999999999898, 228.52500000000146, 233.22000000000116, 239.90499999999884, 242.47999999999956, 304.71500000000015]}, "summary": {"suite": 215.28499999999985, "subtests": {"tpaint": {"filtered": 215.28499999999985, "value": 215.28499999999985}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "tpaint", "options": {"responsiveness": false, "cycles": 1, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}, {"talos_counters": {}, "results": {"sessionrestore": [2557.0, 2181.0, 2183.0, 2196.0, 2218.0, 2158.0, 2191.0, 2206.0, 2140.0, 2153.0]}, "summary": {"suite": 2183.0, "subtests": {"sessionrestore": {"filtered": 2183.0, "value": 2183.0}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "sessionrestore", "options": {"responsiveness": false, "cycles": 10, "tpmozafterpaint": false, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}, {"talos_counters": {}, "results": {"sessionrestore_no_auto_restore": [1073.0, 785.0, 832.0, 758.0, 818.0, 772.0, 843.0, 786.0, 811.0, 806.0]}, "summary": {"suite": 806.0, "subtests": {"sessionrestore_no_auto_restore": {"filtered": 806.0, "value": 806.0}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "sessionrestore_no_auto_restore", "options": {"responsiveness": false, "cycles": 10, "tpmozafterpaint": false, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}]
01:15:22 INFO - RETURN: a11yr_paint: 683.95
01:15:22 INFO - RETURN: ts_paint: 1158.97
01:15:22 INFO - RETURN: tpaint: 215.28
01:15:22 INFO - RETURN: sessionrestore: 2215.54
01:15:22 INFO - RETURN: sessionrestore_no_auto_restore: 824.57
01:15:22 INFO - TinderboxPrint: TalosResult: {"graphserver": {"sessionrestore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[313,132,35]]", "result": "2215.54"}, "a11yr_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[223,132,35]]", "result": "683.95"}, "tpaint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[82,132,35]]", "result": "215.28"}, "ts_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[83,132,35]]", "result": "1158.97"}, "sessionrestore_no_auto_restore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[315,132,35]]", "result": "824.57"}}}
01:15:22 INFO - Return code: 0
01:15:22 INFO - # TBPL SUCCESS #
01:15:22 INFO - Running post-action listener: _resource_record_post_action
01:15:22 INFO - Running post-run listener: _resource_record_post_run
01:15:23 INFO - Total resource usage - Wall time: 302s; CPU: 13.0%; Read bytes: 7680000; Write bytes: 404230144; Read time: 2240; Write time: 281256
01:15:23 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 62767104; Read time: 0; Write time: 65112
01:15:23 INFO - run-tests - Wall time: 288s; CPU: 13.0%; Read bytes: 5943296; Write bytes: 290496512; Read time: 2124; Write time: 153116
01:15:23 INFO - Running post-run listener: _upload_blobber_files
01:15:23 INFO - Blob upload gear active.
01:15:23 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
01:15:23 INFO - Copying logs to upload dir...
01:15:23 INFO - mkdir: /builds/slave/test/build/upload/logs
program finished with exit code 0
elapsedTime=349.849839
========= master_lag: 0.02 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 5 mins, 49 secs) (at 2015-11-02 01:15:23.959904) =========
========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:15:23.963568) =========
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=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
build_url:https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2
program finished with exit code 0
elapsedTime=0.009956
build_url: 'https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'
========= master_lag: 0.04 =========
========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:15:24.015221) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:15:24.015581) =========
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=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004511
========= master_lag: 0.04 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:15:24.065029) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-02 01:15:24.065471) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-02 01:15:24.065942) =========
========= Total master_lag: 0.39 =========