builder: mozilla-aurora_xp-ix_test-other-pgo
slave: t-xp32-ix-047
starttime: 1446193136.39
results: success (0)
buildid: 20151029014646
builduid: e2197d6fa76745edba8eadefeb37f684
revision: 41fdefd640f368bccdeafe6446d42c0a5ad22797
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-30 01:18:56.387607) =========
master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-30 01:18:56.388137) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-30 01:18:56.388510) =========
'cd'
in dir C:\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['cd']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-047\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-047
COMSPEC=C:\WINDOWS\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Documents and Settings\cltbld.T-XP32-IX-047
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-047
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-047
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-047
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
C:\slave\test-pgo
program finished with exit code 0
elapsedTime=0.259000
basedir: 'C:\\slave\\test-pgo'
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-30 01:18:56.988006) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 24 secs) (at 2015-10-30 01:18:56.988398) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 24 secs) (at 2015-10-30 01:19:21.517490) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-30 01:19:21.518166) =========
'rm' '-rf' 'properties'
in dir C:\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'properties']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-047\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-047
COMSPEC=C:\WINDOWS\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Documents and Settings\cltbld.T-XP32-IX-047
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-047
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-047
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-047
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.227000
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-30 01:19:22.321854) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-30 01:19:22.322337) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-30 01:19:22.322821) =========
========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2015-10-30 01:19:22.323257) =========
'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 C:\slave\test-pgo\. (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:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-047\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-047
COMSPEC=C:\WINDOWS\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Documents and Settings\cltbld.T-XP32-IX-047
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-047
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-047
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-047
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
--01:19:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 1.76 GB/s
01:19:14 (1.76 GB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.567000
========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2015-10-30 01:19:34.347607) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-30 01:19:34.348014) =========
'rm' '-rf' 'scripts'
in dir C:\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-047\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-047
COMSPEC=C:\WINDOWS\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Documents and Settings\cltbld.T-XP32-IX-047
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-047
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-047
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-047
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=2.496000
========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-30 01:19:37.072410) =========
========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-30 01:19:37.072813) =========
'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 41fdefd640f368bccdeafe6446d42c0a5ad22797 --destination scripts --debug'
in dir C:\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 41fdefd640f368bccdeafe6446d42c0a5ad22797 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-047\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-047
COMSPEC=C:\WINDOWS\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Documents and Settings\cltbld.T-XP32-IX-047
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-047
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-047
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-047
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
2015-10-30 01:19:29,266 truncating revision to first 12 chars
2015-10-30 01:19:29,266 Setting DEBUG logging.
2015-10-30 01:19:29,266 attempt 1/10
2015-10-30 01:19:29,266 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/41fdefd640f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-10-30 01:19:30,644 unpacking tar archive at: mozilla-aurora-41fdefd640f3/testing/mozharness/
program finished with exit code 0
elapsedTime=2.026000
========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-30 01:19:39.830901) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-30 01:19:39.831496) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-30 01:19:39.966559) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-30 01:19:39.966977) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-30 01:19:39.967509) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 5 mins, 42 secs) (at 2015-10-30 01:19:39.967877) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'other' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Aurora' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Aurora'
in dir C:\slave\test-pgo\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'other', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-047\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-047
COMSPEC=C:\WINDOWS\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Documents and Settings\cltbld.T-XP32-IX-047
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-047
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PROPERTIES_FILE=C:\slave\test-pgo/buildprops.json
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-047
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-047
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
01:19:32 INFO - MultiFileLogger online at 20151030 01:19:32 in C:\slave\test-pgo
01:19:32 INFO - Run as scripts/scripts/talos_script.py --suite other --add-option --webServer,localhost --branch-name Mozilla-Aurora --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora
01:19:32 INFO - Guessing talos json url...
01:19:32 INFO - Using buildbot properties:
01:19:32 INFO - {
01:19:32 INFO - "properties": {
01:19:32 INFO - "buildnumber": 63,
01:19:32 INFO - "product": "firefox",
01:19:32 INFO - "script_repo_revision": "production",
01:19:32 INFO - "builddir": "mozilla-aurora_xp-ix_test-other",
01:19:32 INFO - "repository": "",
01:19:32 INFO - "buildername": "Windows XP 32-bit mozilla-aurora pgo talos other",
01:19:32 INFO - "buildid": "20151029014646",
01:19:32 INFO - "slavename": "t-xp32-ix-047",
01:19:32 INFO - "pgo_build": "True",
01:19:32 INFO - "basedir": "C:\\slave\\test-pgo",
01:19:32 INFO - "project": "",
01:19:32 INFO - "platform": "xp-ix",
01:19:32 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/",
01:19:32 INFO - "slavebuilddir": "test",
01:19:32 INFO - "scheduler": "tests-mozilla-aurora-win32-pgo-talos",
01:19:32 INFO - "branch": "mozilla-aurora",
01:19:32 INFO - "repo_path": "releases/mozilla-aurora",
01:19:32 INFO - "stage_platform": "win32-pgo",
01:19:32 INFO - "builduid": "e2197d6fa76745edba8eadefeb37f684",
01:19:32 INFO - "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"
01:19:32 INFO - },
01:19:32 INFO - "sourcestamp": {
01:19:32 INFO - "repository": "",
01:19:32 INFO - "hasPatch": false,
01:19:32 INFO - "project": "",
01:19:32 INFO - "branch": "mozilla-aurora-win32-pgo-talos",
01:19:32 INFO - "changes": [
01:19:32 INFO - {
01:19:32 INFO - "category": null,
01:19:32 INFO - "files": [
01:19:32 INFO - {
01:19:32 INFO - "url": null,
01:19:32 INFO - "name": "https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip"
01:19:32 INFO - }
01:19:32 INFO - ],
01:19:32 INFO - "repository": "",
01:19:32 INFO - "rev": "41fdefd640f368bccdeafe6446d42c0a5ad22797",
01:19:32 INFO - "who": "jyavenard@mozilla.com",
01:19:32 INFO - "when": 1446121058,
01:19:32 INFO - "number": 6593314,
01:19:32 INFO - "comments": "Bug 1218157: Only ever read from cached data in NotifyDataArrived. r=cpearce a=lizzard\n\nThe logic of queuing NotifyDataArrived and read data there was fundamentally flawed as we would continually perform reads from the same MediaResource at two different ends.\nThis would cause repetitive seeks and data being removed from the media cache. Worse, a read in NotifyDataArrived would cause another NotifyDataArrived to be scheduled.\n\nAs range-request are extremely slow, it would result in stutters and constant interruptions.\n",
01:19:32 INFO - "project": "",
01:19:32 INFO - "at": "Thu 29 Oct 2015 05:17:38",
01:19:32 INFO - "branch": "mozilla-aurora-win32-pgo-talos",
01:19:32 INFO - "revlink": "",
01:19:32 INFO - "properties": [
01:19:32 INFO - [
01:19:32 INFO - "buildid",
01:19:32 INFO - "20151029014646",
01:19:32 INFO - "Change"
01:19:32 INFO - ],
01:19:32 INFO - [
01:19:32 INFO - "builduid",
01:19:32 INFO - "e2197d6fa76745edba8eadefeb37f684",
01:19:32 INFO - "Change"
01:19:32 INFO - ],
01:19:32 INFO - [
01:19:32 INFO - "pgo_build",
01:19:32 INFO - "True",
01:19:32 INFO - "Change"
01:19:32 INFO - ]
01:19:32 INFO - ],
01:19:32 INFO - "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"
01:19:32 INFO - }
01:19:32 INFO - ],
01:19:32 INFO - "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"
01:19:32 INFO - }
01:19:32 INFO - }
01:19:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip.
01:19:32 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:19:32 INFO - https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.txt matches https://queue.taskcluster.net
01:19:32 INFO - trying https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.txt
01:19:32 INFO - Downloading https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.txt to C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.txt
01:19:32 INFO - retry: Calling _download_file with args: ('https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.txt', 'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.txt'), kwargs: {}, attempt #1
01:19:33 INFO - Downloaded 107 bytes.
01:19:33 INFO - Reading from file C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.txt
01:19:33 INFO - Contents:
01:19:33 INFO - 20151029014646
01:19:33 INFO - https://hg.mozilla.org/releases/mozilla-aurora/rev/41fdefd640f368bccdeafe6446d42c0a5ad22797
01:19:33 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:19:33 INFO - trying https://hg.mozilla.org/releases/mozilla-aurora/raw-file/41fdefd640/testing/talos/talos.json
01:19:33 INFO - Downloading https://hg.mozilla.org/releases/mozilla-aurora/raw-file/41fdefd640/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json
01:19:33 INFO - retry: Calling _download_file with args: ('https://hg.mozilla.org/releases/mozilla-aurora/raw-file/41fdefd640/testing/talos/talos.json', 'C:\\slave\\test-pgo\\build\\talos.json'), kwargs: {}, attempt #1
01:19:33 INFO - Downloaded 6818 bytes.
01:19:33 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
01:19:33 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
01:19:33 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
01:19:33 INFO - u'../fennec_ids.txt'],
01:19:33 INFO - u'tests': [u'tcheck2']},
01:19:33 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
01:19:33 INFO - u'--tppagecycles',
01:19:33 INFO - u'7'],
01:19:33 INFO - u'tests': [u'tsvgm']}},
01:19:33 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
01:19:33 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
01:19:33 INFO - u'tests': [u'tresize', u'tcanvasmark']},
01:19:33 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
01:19:33 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
01:19:33 INFO - u'tests': [u'dromaeo_css',
01:19:33 INFO - u'kraken',
01:19:33 INFO - u'v8_7']},
01:19:33 INFO - u'g1': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest',
01:19:33 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/',
01:19:33 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:19:33 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip',
01:19:33 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'},
01:19:33 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
01:19:33 INFO - u'g1-e10s': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest',
01:19:33 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/',
01:19:33 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:19:33 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip',
01:19:33 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'},
01:19:33 INFO - u'talos_options': [u'--e10s'],
01:19:33 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
01:19:33 INFO - u'g2': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest',
01:19:33 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/',
01:19:33 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:19:33 INFO - u'tests': [u'damp', u'tps']},
01:19:33 INFO - u'g2-e10s': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest',
01:19:33 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/',
01:19:33 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:19:33 INFO - u'talos_options': [u'--e10s'],
01:19:33 INFO - u'tests': [u'damp', u'tps']},
01:19:33 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
01:19:33 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
01:19:33 INFO - u'other': {u'tests': [u'a11yr',
01:19:33 INFO - u'ts_paint',
01:19:33 INFO - u'tpaint',
01:19:33 INFO - u'sessionrestore',
01:19:33 INFO - u'sessionrestore_no_auto_restore']},
01:19:33 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
01:19:33 INFO - u'tests': [u'a11yr',
01:19:33 INFO - u'ts_paint',
01:19:33 INFO - u'tpaint',
01:19:33 INFO - u'sessionrestore',
01:19:33 INFO - u'sessionrestore_no_auto_restore']},
01:19:33 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
01:19:33 INFO - u'tests': [u'a11yr',
01:19:33 INFO - u'ts_paint',
01:19:33 INFO - u'tpaint',
01:19:33 INFO - u'sessionrestore',
01:19:33 INFO - u'sessionrestore_no_auto_restore']},
01:19:33 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
01:19:33 INFO - u'tests': [u'a11yr',
01:19:33 INFO - u'ts_paint',
01:19:33 INFO - u'tpaint',
01:19:33 INFO - u'sessionrestore',
01:19:33 INFO - u'sessionrestore_no_auto_restore']},
01:19:33 INFO - u'other_l64': {u'tests': [u'a11yr',
01:19:33 INFO - u'ts_paint',
01:19:33 INFO - u'tpaint',
01:19:33 INFO - u'sessionrestore',
01:19:33 INFO - u'sessionrestore_no_auto_restore']},
01:19:33 INFO - u'other_nol64': {u'tests': [u'a11yr',
01:19:33 INFO - u'ts_paint',
01:19:33 INFO - u'tpaint',
01:19:33 INFO - u'sessionrestore',
01:19:33 INFO - u'sessionrestore_no_auto_restore']},
01:19:33 INFO - u'svgr': {u'tests': [u'tsvgx',
01:19:33 INFO - u'tsvgr_opacity',
01:19:33 INFO - u'tart',
01:19:33 INFO - u'tscrollx',
01:19:33 INFO - u'cart']},
01:19:33 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
01:19:33 INFO - u'tests': [u'tsvgx',
01:19:33 INFO - u'tsvgr_opacity',
01:19:33 INFO - u'tart',
01:19:33 INFO - u'tscrollx',
01:19:33 INFO - u'cart']},
01:19:33 INFO - u'tp5o': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest',
01:19:33 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/',
01:19:33 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:19:33 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip',
01:19:33 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'},
01:19:33 INFO - u'tests': [u'tp5o']},
01:19:33 INFO - u'tp5o-e10s': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest',
01:19:33 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/',
01:19:33 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:19:33 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip',
01:19:33 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'},
01:19:33 INFO - u'talos_options': [u'--e10s'],
01:19:33 INFO - u'tests': [u'tp5o']},
01:19:33 INFO - u'xperf': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5n.manifest',
01:19:33 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/',
01:19:33 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:19:33 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip',
01:19:33 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'},
01:19:33 INFO - u'talos_options': [u'--xperf_path',
01:19:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
01:19:33 INFO - u'tests': [u'tp5n']},
01:19:33 INFO - u'xperf-e10s': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5n.manifest',
01:19:33 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/',
01:19:33 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:19:33 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip',
01:19:33 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'},
01:19:33 INFO - u'talos_options': [u'--e10s',
01:19:33 INFO - u'--xperf_path',
01:19:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
01:19:33 INFO - u'C:/slave/talos-data/talos/xperf.config'],
01:19:33 INFO - u'tests': [u'tp5n']}},
01:19:33 INFO - u'talos.zip': {u'path': u'',
01:19:33 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
01:19:33 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json.
01:19:33 INFO - {'append_to_log': False,
01:19:33 INFO - 'base_work_dir': 'C:\\slave\\test-pgo',
01:19:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora',
01:19:33 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt',
01:19:33 INFO - 'branch': 'Mozilla-Aurora',
01:19:33 INFO - 'buildbot_json_path': 'buildprops.json',
01:19:33 INFO - 'config_files': ('talos/windows_config.py',),
01:19:33 INFO - 'default_actions': ('clobber',
01:19:33 INFO - 'read-buildbot-config',
01:19:33 INFO - 'download-and-extract',
01:19:33 INFO - 'populate-webroot',
01:19:33 INFO - 'create-virtualenv',
01:19:33 INFO - 'install',
01:19:33 INFO - 'run-tests'),
01:19:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
01:19:33 INFO - 'download_minidump_stackwalk': True,
01:19:33 INFO - 'download_symbols': 'ondemand',
01:19:33 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
01:19:33 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'),
01:19:33 INFO - 'hg': 'c:/mozilla-build/hg/hg',
01:19:33 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
01:19:33 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'),
01:19:33 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
01:19:33 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
01:19:33 INFO - 'C:/mozilla-build/tooltool.py'),
01:19:33 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
01:19:33 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
01:19:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
01:19:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
01:19:33 INFO - 'installer_path': 'installer.exe',
01:19:33 INFO - 'log_level': 'info',
01:19:33 INFO - 'log_name': 'talos',
01:19:33 INFO - 'log_to_console': True,
01:19:33 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
01:19:33 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
01:19:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
01:19:33 INFO - 'opt_config_files': (),
01:19:33 INFO - 'pip_index': False,
01:19:33 INFO - 'python_webserver': False,
01:19:33 INFO - 'sps_profile': False,
01:19:33 INFO - 'sps_profile_interval': 0,
01:19:33 INFO - 'suite': 'other',
01:19:33 INFO - 'system_bits': '32',
01:19:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
01:19:33 INFO - 'talos_url': 'https://hg.mozilla.org/build/talos/archive/tip.tar.gz',
01:19:33 INFO - 'tests': (),
01:19:33 INFO - 'title': 't-xp32-ix-047',
01:19:33 INFO - 'use_talos_json': True,
01:19:33 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
01:19:33 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv',
01:19:33 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
01:19:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
01:19:33 INFO - 'webroot': 'c:/slave/talos-data',
01:19:33 INFO - 'webroot_extract_cmd': 'c:/mozilla-build/msys/bin/bash -c "PATH=/c/mozilla-build/msys/bin:$PATH tar zx --strip-components=1 -f \'%(tarball)s\' --wildcards \'**/talos/\'"',
01:19:33 INFO - 'work_dir': 'build'}
01:19:33 INFO - #####
01:19:33 INFO - ##### Running clobber step.
01:19:33 INFO - #####
01:19:33 INFO - Running pre-action listener: _resource_record_pre_action
01:19:33 INFO - Running main action method: clobber
01:19:33 INFO - rmtree: C:\slave\test-pgo\build
01:19:33 INFO - Using _rmtree_windows ...
01:19:33 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1
01:20:03 INFO - Running post-action listener: _resource_record_post_action
01:20:03 INFO - #####
01:20:03 INFO - ##### Running read-buildbot-config step.
01:20:03 INFO - #####
01:20:03 INFO - Running pre-action listener: _resource_record_pre_action
01:20:03 INFO - Running main action method: read_buildbot_config
01:20:03 INFO - Using buildbot properties:
01:20:03 INFO - {
01:20:03 INFO - "properties": {
01:20:03 INFO - "buildnumber": 63,
01:20:03 INFO - "product": "firefox",
01:20:03 INFO - "script_repo_revision": "production",
01:20:03 INFO - "builddir": "mozilla-aurora_xp-ix_test-other",
01:20:03 INFO - "repository": "",
01:20:03 INFO - "buildername": "Windows XP 32-bit mozilla-aurora pgo talos other",
01:20:03 INFO - "buildid": "20151029014646",
01:20:03 INFO - "slavename": "t-xp32-ix-047",
01:20:03 INFO - "pgo_build": "True",
01:20:03 INFO - "basedir": "C:\\slave\\test-pgo",
01:20:03 INFO - "project": "",
01:20:03 INFO - "platform": "xp-ix",
01:20:03 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/",
01:20:03 INFO - "slavebuilddir": "test",
01:20:03 INFO - "scheduler": "tests-mozilla-aurora-win32-pgo-talos",
01:20:03 INFO - "branch": "mozilla-aurora",
01:20:03 INFO - "repo_path": "releases/mozilla-aurora",
01:20:03 INFO - "stage_platform": "win32-pgo",
01:20:03 INFO - "builduid": "e2197d6fa76745edba8eadefeb37f684",
01:20:03 INFO - "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"
01:20:03 INFO - },
01:20:03 INFO - "sourcestamp": {
01:20:03 INFO - "repository": "",
01:20:03 INFO - "hasPatch": false,
01:20:03 INFO - "project": "",
01:20:03 INFO - "branch": "mozilla-aurora-win32-pgo-talos",
01:20:03 INFO - "changes": [
01:20:03 INFO - {
01:20:03 INFO - "category": null,
01:20:03 INFO - "files": [
01:20:03 INFO - {
01:20:03 INFO - "url": null,
01:20:03 INFO - "name": "https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip"
01:20:03 INFO - }
01:20:03 INFO - ],
01:20:03 INFO - "repository": "",
01:20:03 INFO - "rev": "41fdefd640f368bccdeafe6446d42c0a5ad22797",
01:20:03 INFO - "who": "jyavenard@mozilla.com",
01:20:03 INFO - "when": 1446121058,
01:20:03 INFO - "number": 6593314,
01:20:03 INFO - "comments": "Bug 1218157: Only ever read from cached data in NotifyDataArrived. r=cpearce a=lizzard\n\nThe logic of queuing NotifyDataArrived and read data there was fundamentally flawed as we would continually perform reads from the same MediaResource at two different ends.\nThis would cause repetitive seeks and data being removed from the media cache. Worse, a read in NotifyDataArrived would cause another NotifyDataArrived to be scheduled.\n\nAs range-request are extremely slow, it would result in stutters and constant interruptions.\n",
01:20:03 INFO - "project": "",
01:20:03 INFO - "at": "Thu 29 Oct 2015 05:17:38",
01:20:03 INFO - "branch": "mozilla-aurora-win32-pgo-talos",
01:20:03 INFO - "revlink": "",
01:20:03 INFO - "properties": [
01:20:03 INFO - [
01:20:03 INFO - "buildid",
01:20:03 INFO - "20151029014646",
01:20:03 INFO - "Change"
01:20:03 INFO - ],
01:20:03 INFO - [
01:20:03 INFO - "builduid",
01:20:03 INFO - "e2197d6fa76745edba8eadefeb37f684",
01:20:03 INFO - "Change"
01:20:03 INFO - ],
01:20:03 INFO - [
01:20:03 INFO - "pgo_build",
01:20:03 INFO - "True",
01:20:03 INFO - "Change"
01:20:03 INFO - ]
01:20:03 INFO - ],
01:20:03 INFO - "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"
01:20:03 INFO - }
01:20:03 INFO - ],
01:20:03 INFO - "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"
01:20:03 INFO - }
01:20:03 INFO - }
01:20:03 INFO - Running post-action listener: _resource_record_post_action
01:20:03 INFO - #####
01:20:03 INFO - ##### Running download-and-extract step.
01:20:03 INFO - #####
01:20:03 INFO - Running pre-action listener: _resource_record_pre_action
01:20:03 INFO - Running main action method: download_and_extract
01:20:03 INFO - mkdir: C:\slave\test-pgo\build\tests
01:20:03 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:20:03 INFO - https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
01:20:03 INFO - trying https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/test_packages.json
01:20:03 INFO - Downloading https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json
01:20:03 INFO - retry: Calling _download_file with args: ('https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/test_packages.json', 'C:\\slave\\test-pgo\\build\\test_packages.json'), kwargs: {}, attempt #1
01:20:04 INFO - Downloaded 1221 bytes.
01:20:04 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json
01:20:04 INFO - Using the following test package requirements:
01:20:04 INFO - {u'common': [u'firefox-43.0a2.en-US.win32.common.tests.zip'],
01:20:04 INFO - u'cppunittest': [u'firefox-43.0a2.en-US.win32.common.tests.zip',
01:20:04 INFO - u'firefox-43.0a2.en-US.win32.cppunittest.tests.zip'],
01:20:04 INFO - u'jittest': [u'firefox-43.0a2.en-US.win32.common.tests.zip',
01:20:04 INFO - u'jsshell-win32.zip'],
01:20:04 INFO - u'mochitest': [u'firefox-43.0a2.en-US.win32.common.tests.zip',
01:20:04 INFO - u'firefox-43.0a2.en-US.win32.mochitest.tests.zip'],
01:20:04 INFO - u'mozbase': [u'firefox-43.0a2.en-US.win32.common.tests.zip'],
01:20:04 INFO - u'reftest': [u'firefox-43.0a2.en-US.win32.common.tests.zip',
01:20:04 INFO - u'firefox-43.0a2.en-US.win32.reftest.tests.zip'],
01:20:04 INFO - u'talos': [u'firefox-43.0a2.en-US.win32.common.tests.zip',
01:20:04 INFO - u'firefox-43.0a2.en-US.win32.talos.tests.zip'],
01:20:04 INFO - u'web-platform': [u'firefox-43.0a2.en-US.win32.common.tests.zip',
01:20:04 INFO - u'firefox-43.0a2.en-US.win32.web-platform.tests.zip'],
01:20:04 INFO - u'webapprt': [u'firefox-43.0a2.en-US.win32.common.tests.zip'],
01:20:04 INFO - u'xpcshell': [u'firefox-43.0a2.en-US.win32.common.tests.zip',
01:20:04 INFO - u'firefox-43.0a2.en-US.win32.xpcshell.tests.zip']}
01:20:04 INFO - Downloading packages: [u'firefox-43.0a2.en-US.win32.common.tests.zip'] for test suite category: common
01:20: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:20:04 INFO - https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
01:20:04 INFO - trying https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.common.tests.zip
01:20:04 INFO - Downloading https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.common.tests.zip
01:20:04 INFO - retry: Calling _download_file with args: (u'https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.common.tests.zip', u'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.common.tests.zip'), kwargs: {}, attempt #1
01:20:06 INFO - Downloaded 19943480 bytes.
01:20:06 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests
01:20:06 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.common.tests.zip
01:20:06 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.common.tests.zip'] with output_timeout 1760
01:20:41 INFO - Return code: 0
01:20:41 INFO - Downloading packages: [u'firefox-43.0a2.en-US.win32.common.tests.zip', u'firefox-43.0a2.en-US.win32.talos.tests.zip'] for test suite category: talos
01:20: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:20:41 INFO - https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
01:20:41 INFO - trying https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.common.tests.zip
01:20:41 INFO - Downloading https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.common.tests.zip
01:20:41 INFO - retry: Calling _download_file with args: (u'https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.common.tests.zip', u'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.common.tests.zip'), kwargs: {}, attempt #1
01:20:45 INFO - Downloaded 19943480 bytes.
01:20:45 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests
01:20:45 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.common.tests.zip
01:20:45 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.common.tests.zip'] with output_timeout 1760
01:21:08 INFO - Return code: 0
01:21: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:21:08 INFO - https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net
01:21:08 INFO - trying https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.talos.tests.zip
01:21:08 INFO - Downloading https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.talos.tests.zip to C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.talos.tests.zip
01:21:08 INFO - retry: Calling _download_file with args: (u'https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.talos.tests.zip', u'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.talos.tests.zip'), kwargs: {}, attempt #1
01:21:11 INFO - Downloaded 10908295 bytes.
01:21:11 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.talos.tests.zip'] in C:\slave\test-pgo\build\tests
01:21:11 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.talos.tests.zip
01:21:11 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.talos.tests.zip'] with output_timeout 1760
01:21:11 INFO - Return code: 0
01:21:11 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:21:11 INFO - https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip matches https://queue.taskcluster.net
01:21:11 INFO - trying https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip
01:21:11 INFO - Downloading https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip to C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.zip
01:21:11 INFO - retry: Calling _download_file with args: ('https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip', 'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.zip'), kwargs: {}, attempt #1
01:21:15 INFO - Downloaded 53935461 bytes.
01:21:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip
01:21:15 INFO - mkdir: C:\slave\test-pgo\properties
01:21:15 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url
01:21:15 INFO - Writing to file C:\slave\test-pgo\properties\build_url
01:21:15 INFO - Contents:
01:21:15 INFO - build_url:https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip
01:21:15 INFO - Running post-action listener: _resource_record_post_action
01:21:15 INFO - Running post-action listener: _set_extra_try_arguments
01:21:15 INFO - #####
01:21:15 INFO - ##### Running populate-webroot step.
01:21:15 INFO - #####
01:21:15 INFO - Running pre-action listener: _resource_record_pre_action
01:21:15 INFO - Running main action method: populate_webroot
01:21:15 INFO - Populating webroot c:/slave/talos-data...
01:21:15 INFO - rmtree: c:/slave/talos-data\talos
01:21:15 INFO - Using _rmtree_windows ...
01:21:15 INFO - retry: Calling _rmtree_windows with args: ('c:/slave/talos-data\\talos',), kwargs: {}, attempt #1
01:21:16 INFO - copying tree: C:\slave\test-pgo\build\tests\talos\talos to c:/slave/talos-data\talos
01:21:16 INFO - rmtree: c:/slave/talos-data\talos
01:21:16 INFO - Using _rmtree_windows ...
01:21:16 INFO - retry: Calling _rmtree_windows with args: ('c:/slave/talos-data\\talos',), kwargs: {}, attempt #1
01:21:17 INFO - Running post-action listener: _resource_record_post_action
01:21:17 INFO - #####
01:21:17 INFO - ##### Running create-virtualenv step.
01:21:17 INFO - #####
01:21:17 INFO - Running pre-action listener: _resource_record_pre_action
01:21:17 INFO - Running main action method: create_virtualenv
01:21:17 INFO - Creating virtualenv C:\slave\test-pgo\build/venv
01:21:17 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts
01:21:17 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll
01:21:17 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'!
01:21:17 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build
01:21:17 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv
01:21:20 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe
01:21:22 INFO - Installing distribute......................................................................................................................................................................................done.
01:21:23 INFO - Return code: 0
01:21:23 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv
01:21:23 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:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:21:23 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:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:21:23 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--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 0x00BF9AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011A7020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D2F1F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58BF0>, '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 0x00A7A860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D03700>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-047', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-047', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-047', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047'}}, attempt #1
01:21:23 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build
01:21:23 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
01:21:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
01:21:23 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data',
01:21:23 INFO - 'CLIENTNAME': 'Console',
01:21:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:21:23 INFO - 'COMPUTERNAME': 'T-XP32-IX-047',
01:21:23 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
01:21:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:21:23 INFO - 'DCLOCATION': 'SCL3',
01:21:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:21:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:21:23 INFO - 'HOMEDRIVE': 'C:',
01:21:23 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:21:23 INFO - 'KTS_VERSION': '1.19c',
01:21:23 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-047',
01:21:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:21:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:21:23 INFO - 'MOZ_NO_REMOTE': '1',
01:21:23 INFO - 'NO_EM_RESTART': '1',
01:21:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:21:23 INFO - 'OS': 'Windows_NT',
01:21:23 INFO - 'OURDRIVE': 'C:',
01:21:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:21:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
01:21:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:21:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:21:23 INFO - 'PROCESSOR_LEVEL': '6',
01:21:23 INFO - 'PROCESSOR_REVISION': '1e05',
01:21:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:21:23 INFO - 'PROMPT': '$P$G',
01:21:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
01:21:23 INFO - 'PWD': 'C:\\slave\\test-pgo',
01:21:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:21:23 INFO - 'SESSIONNAME': 'Console',
01:21:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:21:23 INFO - 'SYSTEMDRIVE': 'C:',
01:21:23 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
01:21:23 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:23 INFO - 'TEST1': 'testie',
01:21:23 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:23 INFO - 'USERDOMAIN': 'T-XP32-IX-047',
01:21:23 INFO - 'USERNAME': 'cltbld',
01:21:23 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:23 INFO - 'WINDIR': 'C:\\WINDOWS',
01:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:21:23 INFO - Ignoring indexes: http://pypi.python.org/simple/
01:21:23 INFO - Downloading/unpacking pip>=1.5
01:21:23 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache
01:21:27 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz
01:21:27 INFO - Running setup.py egg_info for package pip
01:21:27 INFO - warning: no files found matching 'pip\cacert.pem'
01:21:27 INFO - warning: no files found matching '*.html' under directory 'docs'
01:21:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
01:21:27 INFO - no previously-included directories found matching 'docs\_build\_sources'
01:21:27 INFO - Installing collected packages: pip
01:21:27 INFO - Found existing installation: pip 0.8.2
01:21:27 INFO - Uninstalling pip:
01:21:27 INFO - Successfully uninstalled pip
01:21:27 INFO - Running setup.py install for pip
01:21:27 INFO - warning: no files found matching 'pip\cacert.pem'
01:21:27 INFO - warning: no files found matching '*.html' under directory 'docs'
01:21:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
01:21:27 INFO - no previously-included directories found matching 'docs\_build\_sources'
01:21:27 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:27 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:27 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:27 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:27 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:27 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:27 INFO - Successfully installed pip
01:21:27 INFO - Cleaning up...
01:21:27 INFO - Return code: 0
01:21:27 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv
01:21:27 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:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:21:27 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:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:21:27 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--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 0x00BF9AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011A7020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D2F1F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58BF0>, '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 0x00A7A860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D03700>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-047', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-047', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-047', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047'}}, attempt #1
01:21:27 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test-pgo\build
01:21:27 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1
01:21:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
01:21:27 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data',
01:21:27 INFO - 'CLIENTNAME': 'Console',
01:21:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:21:27 INFO - 'COMPUTERNAME': 'T-XP32-IX-047',
01:21:27 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
01:21:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:21:27 INFO - 'DCLOCATION': 'SCL3',
01:21:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:21:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:21:27 INFO - 'HOMEDRIVE': 'C:',
01:21:27 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:21:27 INFO - 'KTS_VERSION': '1.19c',
01:21:27 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-047',
01:21:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:21:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:21:27 INFO - 'MOZ_NO_REMOTE': '1',
01:21:27 INFO - 'NO_EM_RESTART': '1',
01:21:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:21:27 INFO - 'OS': 'Windows_NT',
01:21:27 INFO - 'OURDRIVE': 'C:',
01:21:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:21:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
01:21:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:21:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:21:27 INFO - 'PROCESSOR_LEVEL': '6',
01:21:27 INFO - 'PROCESSOR_REVISION': '1e05',
01:21:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:21:27 INFO - 'PROMPT': '$P$G',
01:21:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
01:21:27 INFO - 'PWD': 'C:\\slave\\test-pgo',
01:21:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:21:27 INFO - 'SESSIONNAME': 'Console',
01:21:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:21:27 INFO - 'SYSTEMDRIVE': 'C:',
01:21:27 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
01:21:27 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:27 INFO - 'TEST1': 'testie',
01:21:27 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:27 INFO - 'USERDOMAIN': 'T-XP32-IX-047',
01:21:27 INFO - 'USERNAME': 'cltbld',
01:21:27 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:27 INFO - 'WINDIR': 'C:\\WINDOWS',
01:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:21:28 INFO - Downloading/unpacking psutil>=0.7.1
01:21:28 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:21:28 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:21:29 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl
01:21:29 INFO - Installing collected packages: psutil
01:21:29 INFO - Successfully installed psutil
01:21:29 INFO - Cleaning up...
01:21:29 INFO - Return code: 0
01:21:29 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv
01:21:29 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:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:21:29 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:21:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:21:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--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 0x00BF9AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011A7020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D2F1F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58BF0>, '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 0x00A7A860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D03700>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-047', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-047', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-047', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047'}}, attempt #1
01:21:29 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build
01:21:29 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0
01:21:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
01:21:29 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data',
01:21:29 INFO - 'CLIENTNAME': 'Console',
01:21:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:21:29 INFO - 'COMPUTERNAME': 'T-XP32-IX-047',
01:21:29 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
01:21:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:21:29 INFO - 'DCLOCATION': 'SCL3',
01:21:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:21:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:21:29 INFO - 'HOMEDRIVE': 'C:',
01:21:29 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:21:29 INFO - 'KTS_VERSION': '1.19c',
01:21:29 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-047',
01:21:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:21:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:21:29 INFO - 'MOZ_NO_REMOTE': '1',
01:21:29 INFO - 'NO_EM_RESTART': '1',
01:21:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:21:29 INFO - 'OS': 'Windows_NT',
01:21:29 INFO - 'OURDRIVE': 'C:',
01:21:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:21:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
01:21:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:21:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:21:29 INFO - 'PROCESSOR_LEVEL': '6',
01:21:29 INFO - 'PROCESSOR_REVISION': '1e05',
01:21:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:21:29 INFO - 'PROMPT': '$P$G',
01:21:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
01:21:29 INFO - 'PWD': 'C:\\slave\\test-pgo',
01:21:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:21:29 INFO - 'SESSIONNAME': 'Console',
01:21:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:21:29 INFO - 'SYSTEMDRIVE': 'C:',
01:21:29 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
01:21:29 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:29 INFO - 'TEST1': 'testie',
01:21:29 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:29 INFO - 'USERDOMAIN': 'T-XP32-IX-047',
01:21:29 INFO - 'USERNAME': 'cltbld',
01:21:29 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:29 INFO - 'WINDIR': 'C:\\WINDOWS',
01:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:21:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
01:21:30 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:21:30 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:21:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz
01:21:30 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz
01:21:30 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
01:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0)
01:21:31 INFO - Installing collected packages: mozsystemmonitor
01:21:31 INFO - Running setup.py install for mozsystemmonitor
01:21:31 INFO - Successfully installed mozsystemmonitor
01:21:31 INFO - Cleaning up...
01:21:31 INFO - Return code: 0
01:21:31 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv
01:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:21:31 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--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 0x00BF9AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011A7020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D2F1F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58BF0>, '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 0x00A7A860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D03700>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-047', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-047', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-047', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047'}}, attempt #1
01:21:31 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build
01:21:31 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
01:21:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
01:21:31 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data',
01:21:31 INFO - 'CLIENTNAME': 'Console',
01:21:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:21:31 INFO - 'COMPUTERNAME': 'T-XP32-IX-047',
01:21:31 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
01:21:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:21:31 INFO - 'DCLOCATION': 'SCL3',
01:21:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:21:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:21:31 INFO - 'HOMEDRIVE': 'C:',
01:21:31 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:21:31 INFO - 'KTS_VERSION': '1.19c',
01:21:31 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-047',
01:21:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:21:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:21:31 INFO - 'MOZ_NO_REMOTE': '1',
01:21:31 INFO - 'NO_EM_RESTART': '1',
01:21:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:21:31 INFO - 'OS': 'Windows_NT',
01:21:31 INFO - 'OURDRIVE': 'C:',
01:21:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:21:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
01:21:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:21:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:21:31 INFO - 'PROCESSOR_LEVEL': '6',
01:21:31 INFO - 'PROCESSOR_REVISION': '1e05',
01:21:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:21:31 INFO - 'PROMPT': '$P$G',
01:21:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
01:21:31 INFO - 'PWD': 'C:\\slave\\test-pgo',
01:21:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:21:31 INFO - 'SESSIONNAME': 'Console',
01:21:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:21:31 INFO - 'SYSTEMDRIVE': 'C:',
01:21:31 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
01:21:31 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:31 INFO - 'TEST1': 'testie',
01:21:31 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:31 INFO - 'USERDOMAIN': 'T-XP32-IX-047',
01:21:31 INFO - 'USERNAME': 'cltbld',
01:21:31 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:31 INFO - 'WINDIR': 'C:\\WINDOWS',
01:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:21:31 INFO - Downloading/unpacking blobuploader==1.2.4
01:21:31 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:21:31 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:21:32 INFO - Downloading blobuploader-1.2.4.tar.gz
01:21:32 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz
01:21:32 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
01:21:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
01:21:32 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:21:32 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:21:32 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz
01:21:32 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests
01:21:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
01:21:33 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:21:33 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:21:33 INFO - Downloading docopt-0.6.1.tar.gz
01:21:33 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz
01:21:33 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt
01:21:33 INFO - Installing collected packages: blobuploader, requests, docopt
01:21:33 INFO - Running setup.py install for blobuploader
01:21:33 INFO - Running setup.py install for requests
01:21:33 INFO - Running setup.py install for docopt
01:21:34 INFO - Successfully installed blobuploader requests docopt
01:21:34 INFO - Cleaning up...
01:21:34 INFO - Return code: 0
01:21:34 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
01:21: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:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:21: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:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:21:34 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00BF9AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011A7020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D2F1F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58BF0>, '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 0x00A7A860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D03700>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-047', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-047', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-047', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047'}}, attempt #1
01:21:34 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config
01:21:34 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
01:21:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
01:21:34 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data',
01:21:34 INFO - 'CLIENTNAME': 'Console',
01:21:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:21:34 INFO - 'COMPUTERNAME': 'T-XP32-IX-047',
01:21:34 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
01:21:34 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:21:34 INFO - 'DCLOCATION': 'SCL3',
01:21:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:21:34 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:21:34 INFO - 'HOMEDRIVE': 'C:',
01:21:34 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:21:34 INFO - 'KTS_VERSION': '1.19c',
01:21:34 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-047',
01:21:34 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:21:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:21:34 INFO - 'MOZ_NO_REMOTE': '1',
01:21:34 INFO - 'NO_EM_RESTART': '1',
01:21:34 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:21:34 INFO - 'OS': 'Windows_NT',
01:21:34 INFO - 'OURDRIVE': 'C:',
01:21:34 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:21:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
01:21:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:21:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:21:34 INFO - 'PROCESSOR_LEVEL': '6',
01:21:34 INFO - 'PROCESSOR_REVISION': '1e05',
01:21:34 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:21:34 INFO - 'PROMPT': '$P$G',
01:21:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
01:21:34 INFO - 'PWD': 'C:\\slave\\test-pgo',
01:21:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:21:34 INFO - 'SESSIONNAME': 'Console',
01:21:34 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:21:34 INFO - 'SYSTEMDRIVE': 'C:',
01:21:34 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
01:21:34 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:34 INFO - 'TEST1': 'testie',
01:21:34 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:34 INFO - 'USERDOMAIN': 'T-XP32-IX-047',
01:21:34 INFO - 'USERNAME': 'cltbld',
01:21:34 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:34 INFO - 'WINDIR': 'C:\\WINDOWS',
01:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:21:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
01:21:34 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-j4srvo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
01:21:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
01:21:34 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-lgm9zb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
01:21:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
01:21:34 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-xhdu7c-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
01:21:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-qkp_0c-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
01:21:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-bisto3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
01:21:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-fafiye-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
01:21:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-amfjev-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
01:21:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-lzi5cv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
01:21:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-qtehnq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
01:21:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-dmko2p-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
01:21:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ofja4n-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
01:21:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-qg8bff-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
01:21:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
01:21:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-_tx_62-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
01:21:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
01:21:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-8tgasl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
01:21:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
01:21:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-z72vy_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
01:21:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
01:21:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-8kchhl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
01:21:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
01:21:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ptvxz5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
01:21:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
01:21:36 INFO - Running setup.py install for manifestparser
01:21:36 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:36 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:36 INFO - Running setup.py install for mozcrash
01:21:36 INFO - Running setup.py install for mozdebug
01:21:37 INFO - Running setup.py install for mozdevice
01:21:37 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:37 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:37 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:37 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:37 INFO - Running setup.py install for mozfile
01:21:37 INFO - Running setup.py install for mozhttpd
01:21:37 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:37 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:37 INFO - Running setup.py install for mozinfo
01:21:37 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:37 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:37 INFO - Running setup.py install for mozInstall
01:21:38 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Running setup.py install for mozleak
01:21:38 INFO - Running setup.py install for mozlog
01:21:38 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Running setup.py install for moznetwork
01:21:38 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:38 INFO - Running setup.py install for mozprocess
01:21:39 INFO - Running setup.py install for mozprofile
01:21:39 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:39 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:39 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:39 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:39 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:39 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:39 INFO - Running setup.py install for mozrunner
01:21:39 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:39 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:39 INFO - Running setup.py install for mozscreenshot
01:21:39 INFO - Running setup.py install for moztest
01:21:39 INFO - Running setup.py install for mozversion
01:21:40 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts
01:21:40 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts
01:21:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
01:21:40 INFO - Cleaning up...
01:21:40 INFO - Return code: 0
01:21:40 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
01:21:40 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:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:21:40 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:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:21:40 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00BF9AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011A7020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D2F1F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58BF0>, '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 0x00A7A860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D03700>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-047', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-047', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-047', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047'}}, attempt #1
01:21:40 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config
01:21:40 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
01:21:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
01:21:40 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data',
01:21:40 INFO - 'CLIENTNAME': 'Console',
01:21:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:21:40 INFO - 'COMPUTERNAME': 'T-XP32-IX-047',
01:21:40 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
01:21:40 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:21:40 INFO - 'DCLOCATION': 'SCL3',
01:21:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:21:40 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:21:40 INFO - 'HOMEDRIVE': 'C:',
01:21:40 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:21:40 INFO - 'KTS_VERSION': '1.19c',
01:21:40 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-047',
01:21:40 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:21:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:21:40 INFO - 'MOZ_NO_REMOTE': '1',
01:21:40 INFO - 'NO_EM_RESTART': '1',
01:21:40 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:21:40 INFO - 'OS': 'Windows_NT',
01:21:40 INFO - 'OURDRIVE': 'C:',
01:21:40 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:21:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
01:21:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:21:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:21:40 INFO - 'PROCESSOR_LEVEL': '6',
01:21:40 INFO - 'PROCESSOR_REVISION': '1e05',
01:21:40 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:21:40 INFO - 'PROMPT': '$P$G',
01:21:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
01:21:40 INFO - 'PWD': 'C:\\slave\\test-pgo',
01:21:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:21:40 INFO - 'SESSIONNAME': 'Console',
01:21:40 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:21:40 INFO - 'SYSTEMDRIVE': 'C:',
01:21:40 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
01:21:40 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:40 INFO - 'TEST1': 'testie',
01:21:40 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:40 INFO - 'USERDOMAIN': 'T-XP32-IX-047',
01:21:40 INFO - 'USERNAME': 'cltbld',
01:21:40 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:40 INFO - 'WINDIR': 'C:\\WINDOWS',
01:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
01:21:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-iq0o4t-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
01:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1))
01:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
01:21:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-duglor-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
01:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2))
01:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
01:21:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-tv6lqj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
01:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3))
01:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
01:21:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-vcuunz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
01:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4))
01:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
01:21:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-rjffqc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
01:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5))
01:21:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
01:21:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ayxjhn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
01:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6))
01:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
01:21:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-w62wuj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
01:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7))
01:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
01:21:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-g0dmtq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
01:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8))
01:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
01:21:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-8yimx1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
01:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9))
01:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
01:21:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ibuezq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
01:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10))
01:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
01:21:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-zsya74-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
01:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11))
01:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
01:21:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-pkmnbz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
01:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12))
01:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
01:21:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-lujvd8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
01:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13))
01:21:41 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
01:21:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-n_qxl7-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14))
01:21:42 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
01:21:42 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-kn7j6c-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15))
01:21:42 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
01:21:42 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-6mnpu5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16))
01:21:42 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
01:21:42 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-r0vyqk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17))
01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2))
01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2))
01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3))
01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4))
01:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4))
01:21:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10))
01:21:42 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:21:42 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:21:43 INFO - Downloading blessings-1.5.1.tar.gz
01:21:43 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz
01:21:43 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings
01:21:43 INFO - Installing collected packages: blessings
01:21:43 INFO - Running setup.py install for blessings
01:21:44 INFO - Successfully installed blessings
01:21:44 INFO - Cleaning up...
01:21:44 INFO - Return code: 0
01:21:44 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv.
01:21:44 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
01:21:44 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
01:21:44 INFO - Reading from file tmpfile_stdout
01:21:44 INFO - Using _rmtree_windows ...
01:21:44 INFO - Using _rmtree_windows ...
01:21:44 INFO - Current package versions:
01:21:44 INFO - blessings == 1.5.1
01:21:44 INFO - blobuploader == 1.2.4
01:21:44 INFO - docopt == 0.6.1
01:21:44 INFO - manifestparser == 1.1
01:21:44 INFO - mozInstall == 1.12
01:21:44 INFO - mozcrash == 0.16
01:21:44 INFO - mozdebug == 0.1
01:21:44 INFO - mozdevice == 0.46
01:21:44 INFO - mozfile == 1.2
01:21:44 INFO - mozhttpd == 0.7
01:21:44 INFO - mozinfo == 0.8
01:21:44 INFO - mozleak == 0.1
01:21:44 INFO - mozlog == 3.0
01:21:44 INFO - moznetwork == 0.27
01:21:44 INFO - mozprocess == 0.22
01:21:44 INFO - mozprofile == 0.27
01:21:44 INFO - mozrunner == 6.10
01:21:44 INFO - mozscreenshot == 0.1
01:21:44 INFO - mozsystemmonitor == 0.0
01:21:44 INFO - moztest == 0.7
01:21:44 INFO - mozversion == 1.4
01:21:44 INFO - psutil == 3.1.1
01:21:44 INFO - requests == 1.2.3
01:21:44 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
01:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:21:44 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00BF9AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x011A7020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D2F1F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58BF0>, '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 0x00A7A860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D03700>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-047', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-047', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-047', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047'}}, attempt #1
01:21:44 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\talos
01:21:44 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
01:21:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
01:21:44 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data',
01:21:44 INFO - 'CLIENTNAME': 'Console',
01:21:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:21:44 INFO - 'COMPUTERNAME': 'T-XP32-IX-047',
01:21:44 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
01:21:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:21:44 INFO - 'DCLOCATION': 'SCL3',
01:21:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:21:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:21:44 INFO - 'HOMEDRIVE': 'C:',
01:21:44 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:21:44 INFO - 'KTS_VERSION': '1.19c',
01:21:44 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-047',
01:21:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:21:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:21:44 INFO - 'MOZ_NO_REMOTE': '1',
01:21:44 INFO - 'NO_EM_RESTART': '1',
01:21:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:21:44 INFO - 'OS': 'Windows_NT',
01:21:44 INFO - 'OURDRIVE': 'C:',
01:21:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:21:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
01:21:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:21:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:21:44 INFO - 'PROCESSOR_LEVEL': '6',
01:21:44 INFO - 'PROCESSOR_REVISION': '1e05',
01:21:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:21:44 INFO - 'PROMPT': '$P$G',
01:21:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
01:21:44 INFO - 'PWD': 'C:\\slave\\test-pgo',
01:21:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:21:44 INFO - 'SESSIONNAME': 'Console',
01:21:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:21:44 INFO - 'SYSTEMDRIVE': 'C:',
01:21:44 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
01:21:44 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:44 INFO - 'TEST1': 'testie',
01:21:44 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:44 INFO - 'USERDOMAIN': 'T-XP32-IX-047',
01:21:44 INFO - 'USERNAME': 'cltbld',
01:21:44 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:44 INFO - 'WINDIR': 'C:\\WINDOWS',
01:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3))
01:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1))
01:21:44 INFO - Cleaning up...
01:21:44 INFO - Return code: 0
01:21:44 INFO - Running post-action listener: _resource_record_post_action
01:21:44 INFO - Running post-action listener: _start_resource_monitoring
01:21:44 INFO - Starting resource monitoring.
01:21:44 INFO - #####
01:21:44 INFO - ##### Running install step.
01:21:44 INFO - #####
01:21:44 INFO - Running pre-action listener: _resource_record_pre_action
01:21:44 INFO - Running main action method: install
01:21:44 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
01:21:44 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
01:21:45 INFO - Reading from file tmpfile_stdout
01:21:45 INFO - Using _rmtree_windows ...
01:21:45 INFO - Using _rmtree_windows ...
01:21:45 INFO - Detecting whether we're running mozinstall >=1.0...
01:21:45 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h']
01:21:45 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h
01:21:45 INFO - Reading from file tmpfile_stdout
01:21:45 INFO - Output received:
01:21:45 INFO - Usage: mozinstall-script.py [options] installer
01:21:45 INFO - Options:
01:21:45 INFO - -h, --help show this help message and exit
01:21:45 INFO - -d DEST, --destination=DEST
01:21:45 INFO - Directory to install application into. [default:
01:21:45 INFO - "C:\slave\test-pgo"]
01:21:45 INFO - --app=APP Application being installed. [default: firefox]
01:21:45 INFO - Using _rmtree_windows ...
01:21:45 INFO - Using _rmtree_windows ...
01:21:45 INFO - mkdir: C:\slave\test-pgo\build\application
01:21:45 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-43.0a2.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application']
01:21:45 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-43.0a2.en-US.win32.zip --destination C:\slave\test-pgo\build\application
01:21:47 INFO - Reading from file tmpfile_stdout
01:21:47 INFO - Output received:
01:21:47 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe
01:21:47 INFO - Using _rmtree_windows ...
01:21:47 INFO - Using _rmtree_windows ...
01:21:47 INFO - Running post-action listener: _resource_record_post_action
01:21:47 INFO - #####
01:21:47 INFO - ##### Running run-tests step.
01:21:47 INFO - #####
01:21:47 INFO - Running pre-action listener: _resource_record_pre_action
01:21:47 INFO - Running main action method: run_tests
01:21:47 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version']
01:21:47 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version
01:21:47 INFO - Python 2.7.3
01:21:47 INFO - Return code: 0
01:21:47 INFO - grabbing minidump binary from tooltool
01:21:47 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:21:47 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58BF0>, '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 0x00A7A860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D03700>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1
01:21:47 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build
01:21:47 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o
01:21:47 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
01:21:50 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpjblfpg
01:21:50 INFO - INFO - File integrity verified, renaming tmpjblfpg to win32-minidump_stackwalk.exe
01:21:50 INFO - Return code: 0
01:21:50 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755
01:21:50 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir
01:21:50 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir
01:21:50 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe
01:21:50 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir
01:21:50 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.crashreporter-symbols.zip', '--activeTests', u'a11yr:ts_paint:tpaint:sessionrestore:sessionrestore_no_auto_restore', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--title', 't-xp32-ix-047', '--webServer', 'localhost'] in C:\slave\test-pgo\build
01:21:50 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Mozilla-Aurora --symbolsPath https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.crashreporter-symbols.zip --activeTests a11yr:ts_paint:tpaint:sessionrestore:sessionrestore_no_auto_restore --executablePath C:\slave\test-pgo\build\application\firefox\firefox --title t-xp32-ix-047 --webServer localhost
01:21:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
01:21:50 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047\\Application Data',
01:21:50 INFO - 'CLIENTNAME': 'Console',
01:21:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:21:50 INFO - 'COMPUTERNAME': 'T-XP32-IX-047',
01:21:50 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
01:21:50 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:21:50 INFO - 'DCLOCATION': 'SCL3',
01:21:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:21:50 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:21:50 INFO - 'HOMEDRIVE': 'C:',
01:21:50 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:21:50 INFO - 'KTS_VERSION': '1.19c',
01:21:50 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-047',
01:21:50 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
01:21:50 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe',
01:21:50 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:21:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:21:50 INFO - 'MOZ_NO_REMOTE': '1',
01:21:50 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
01:21:50 INFO - 'NO_EM_RESTART': '1',
01:21:50 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:21:50 INFO - 'OS': 'Windows_NT',
01:21:50 INFO - 'OURDRIVE': 'C:',
01:21:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:21:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
01:21:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:21:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:21:50 INFO - 'PROCESSOR_LEVEL': '6',
01:21:50 INFO - 'PROCESSOR_REVISION': '1e05',
01:21:50 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:21:50 INFO - 'PROMPT': '$P$G',
01:21:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
01:21:50 INFO - 'PWD': 'C:\\slave\\test-pgo',
01:21:50 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos',
01:21:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:21:50 INFO - 'SESSIONNAME': 'Console',
01:21:50 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:21:50 INFO - 'SYSTEMDRIVE': 'C:',
01:21:50 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
01:21:50 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:50 INFO - 'TEST1': 'testie',
01:21:50 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
01:21:50 INFO - 'USERDOMAIN': 'T-XP32-IX-047',
01:21:50 INFO - 'USERNAME': 'cltbld',
01:21:50 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-047',
01:21:50 INFO - 'WINDIR': 'C:\\WINDOWS',
01:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:21:50 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.crashreporter-symbols.zip', '--activeTests', u'a11yr:ts_paint:tpaint:sessionrestore:sessionrestore_no_auto_restore', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--title', 't-xp32-ix-047', '--webServer', 'localhost'] with output_timeout 3600
01:21:50 INFO - mozversion INFO | application_buildid: 20151029014646
01:21:50 INFO - mozversion INFO | application_changeset: 41fdefd640f368bccdeafe6446d42c0a5ad22797
01:21:50 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition
01:21:50 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
01:21:50 INFO - mozversion INFO | application_name: Firefox
01:21:50 INFO - mozversion INFO | application_remotingname: firefox-dev
01:21:50 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora
01:21:50 INFO - mozversion INFO | application_vendor: Mozilla
01:21:50 INFO - mozversion INFO | application_version: 43.0a2
01:21:50 INFO - mozversion INFO | platform_buildid: 20151029014646
01:21:50 INFO - mozversion INFO | platform_changeset: 41fdefd640f368bccdeafe6446d42c0a5ad22797
01:21:50 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora
01:21:50 INFO - mozversion INFO | platform_version: 43.0a2
01:21:50 INFO - 2015-10-30 01:21:50,687 DEBUG : using testdate: 1446193310
01:21:50 INFO - 2015-10-30 01:21:50,687 DEBUG : actual date: 1446193310
01:21:50 INFO - 2015-10-30 01:21:50,703 INFO : Starting test suite t-xp32-ix-047
01:21:50 INFO - 2015-10-30 01:21:50,703 INFO : Starting test a11yr
01:21:50 INFO - 2015-10-30 01:21:50,703 DEBUG : operating with platform_type : win_
01:21:50 INFO - 2015-10-30 01:21:50,703 INFO : Initialising browser for a11yr test...
01:21:50 INFO - 2015-10-30 01:21:50,752 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmtlgia\profile http://localhost/getInfo.html
01:21:53 INFO - 2015-10-30 01:21:53,918 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:21:53 INFO - 2015-10-30 01:21:53,918 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:21:53 INFO - 2015-10-30 01:21:53,918 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
01:21:53 INFO - 2015-10-30 01:21:53,918 DEBUG : BROWSER_OUTPUT: __metrics
01:21:53 INFO - 2015-10-30 01:21:53,934 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:21:54 INFO - 2015-10-30 01:21:54,647 INFO : Browser initialized.
01:21:54 INFO - 2015-10-30 01:21:54,647 INFO : Running cycle 1/1 for a11yr test...
01:21:54 INFO - 2015-10-30 01:21:54,647 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmtlgia\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\page_load_test\a11y\a11y.manifest -tpchrome -tpmozafterpaint -tpnoisy -tpcycles 1 -tppagecycles 25
01:22:06 INFO - 2015-10-30 01:22:06,125 DEBUG : BROWSER_OUTPUT: RSS: Main: 118968320
01:22:06 INFO - 2015-10-30 01:22:06,125 DEBUG : BROWSER_OUTPUT:
01:22:06 INFO - 2015-10-30 01:22:06,950 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:07 INFO - 2015-10-30 01:22:07,535 DEBUG : BROWSER_OUTPUT: RSS: Main: 148295680
01:22:07 INFO - 2015-10-30 01:22:07,535 DEBUG : BROWSER_OUTPUT:
01:22:08 INFO - 2015-10-30 01:22:08,390 DEBUG : BROWSER_OUTPUT: Cycle 1(2): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:09 INFO - 2015-10-30 01:22:09,007 DEBUG : BROWSER_OUTPUT: RSS: Main: 152244224
01:22:09 INFO - 2015-10-30 01:22:09,007 DEBUG : BROWSER_OUTPUT:
01:22:09 INFO - 2015-10-30 01:22:09,846 DEBUG : BROWSER_OUTPUT: Cycle 1(3): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:10 INFO - 2015-10-30 01:22:10,480 DEBUG : BROWSER_OUTPUT: RSS: Main: 150724608
01:22:10 INFO - 2015-10-30 01:22:10,480 DEBUG : BROWSER_OUTPUT:
01:22:11 INFO - 2015-10-30 01:22:11,351 DEBUG : BROWSER_OUTPUT: Cycle 1(4): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:12 INFO - 2015-10-30 01:22:11,984 DEBUG : BROWSER_OUTPUT: RSS: Main: 152031232
01:22:12 INFO - 2015-10-30 01:22:11,984 DEBUG : BROWSER_OUTPUT:
01:22:12 INFO - 2015-10-30 01:22:12,855 DEBUG : BROWSER_OUTPUT: Cycle 1(5): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:13 INFO - 2015-10-30 01:22:13,489 DEBUG : BROWSER_OUTPUT: RSS: Main: 150712320
01:22:13 INFO - 2015-10-30 01:22:13,489 DEBUG : BROWSER_OUTPUT:
01:22:14 INFO - 2015-10-30 01:22:14,407 DEBUG : BROWSER_OUTPUT: Cycle 1(6): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:15 INFO - 2015-10-30 01:22:15,025 DEBUG : BROWSER_OUTPUT: RSS: Main: 152924160
01:22:15 INFO - 2015-10-30 01:22:15,025 DEBUG : BROWSER_OUTPUT:
01:22:15 INFO - 2015-10-30 01:22:15,895 DEBUG : BROWSER_OUTPUT: Cycle 1(7): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:16 INFO - 2015-10-30 01:22:16,529 DEBUG : BROWSER_OUTPUT: RSS: Main: 152260608
01:22:16 INFO - 2015-10-30 01:22:16,529 DEBUG : BROWSER_OUTPUT:
01:22:17 INFO - 2015-10-30 01:22:17,384 DEBUG : BROWSER_OUTPUT: Cycle 1(8): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:18 INFO - 2015-10-30 01:22:18,000 DEBUG : BROWSER_OUTPUT: RSS: Main: 153489408
01:22:18 INFO - 2015-10-30 01:22:18,000 DEBUG : BROWSER_OUTPUT:
01:22:18 INFO - 2015-10-30 01:22:18,871 DEBUG : BROWSER_OUTPUT: Cycle 1(9): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:19 INFO - 2015-10-30 01:22:19,506 DEBUG : BROWSER_OUTPUT: RSS: Main: 150953984
01:22:19 INFO - 2015-10-30 01:22:19,506 DEBUG : BROWSER_OUTPUT:
01:22:20 INFO - 2015-10-30 01:22:20,361 DEBUG : BROWSER_OUTPUT: Cycle 1(10): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:20 INFO - 2015-10-30 01:22:20,993 DEBUG : BROWSER_OUTPUT: RSS: Main: 151527424
01:22:20 INFO - 2015-10-30 01:22:20,993 DEBUG : BROWSER_OUTPUT:
01:22:21 INFO - 2015-10-30 01:22:21,832 DEBUG : BROWSER_OUTPUT: Cycle 1(11): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:22 INFO - 2015-10-30 01:22:22,450 DEBUG : BROWSER_OUTPUT: RSS: Main: 152723456
01:22:22 INFO - 2015-10-30 01:22:22,450 DEBUG : BROWSER_OUTPUT:
01:22:23 INFO - 2015-10-30 01:22:23,352 DEBUG : BROWSER_OUTPUT: Cycle 1(12): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:24 INFO - 2015-10-30 01:22:24,002 DEBUG : BROWSER_OUTPUT: RSS: Main: 152616960
01:22:24 INFO - 2015-10-30 01:22:24,002 DEBUG : BROWSER_OUTPUT:
01:22:24 INFO - 2015-10-30 01:22:24,857 DEBUG : BROWSER_OUTPUT: Cycle 1(13): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:25 INFO - 2015-10-30 01:22:25,474 DEBUG : BROWSER_OUTPUT: RSS: Main: 151851008
01:22:25 INFO - 2015-10-30 01:22:25,474 DEBUG : BROWSER_OUTPUT:
01:22:26 INFO - 2015-10-30 01:22:26,345 DEBUG : BROWSER_OUTPUT: Cycle 1(14): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:26 INFO - 2015-10-30 01:22:26,979 DEBUG : BROWSER_OUTPUT: RSS: Main: 152137728
01:22:26 INFO - 2015-10-30 01:22:26,979 DEBUG : BROWSER_OUTPUT:
01:22:27 INFO - 2015-10-30 01:22:27,865 DEBUG : BROWSER_OUTPUT: Cycle 1(15): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:28 INFO - 2015-10-30 01:22:28,499 DEBUG : BROWSER_OUTPUT: RSS: Main: 151470080
01:22:28 INFO - 2015-10-30 01:22:28,499 DEBUG : BROWSER_OUTPUT:
01:22:29 INFO - 2015-10-30 01:22:29,338 DEBUG : BROWSER_OUTPUT: Cycle 1(16): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:29 INFO - 2015-10-30 01:22:29,971 DEBUG : BROWSER_OUTPUT: RSS: Main: 153432064
01:22:29 INFO - 2015-10-30 01:22:29,971 DEBUG : BROWSER_OUTPUT:
01:22:30 INFO - 2015-10-30 01:22:30,795 DEBUG : BROWSER_OUTPUT: Cycle 1(17): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:31 INFO - 2015-10-30 01:22:31,427 DEBUG : BROWSER_OUTPUT: RSS: Main: 153059328
01:22:31 INFO - 2015-10-30 01:22:31,427 DEBUG : BROWSER_OUTPUT:
01:22:32 INFO - 2015-10-30 01:22:32,282 DEBUG : BROWSER_OUTPUT: Cycle 1(18): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:32 INFO - 2015-10-30 01:22:32,901 DEBUG : BROWSER_OUTPUT: RSS: Main: 152895488
01:22:32 INFO - 2015-10-30 01:22:32,901 DEBUG : BROWSER_OUTPUT:
01:22:33 INFO - 2015-10-30 01:22:33,819 DEBUG : BROWSER_OUTPUT: Cycle 1(19): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:34 INFO - 2015-10-30 01:22:34,453 DEBUG : BROWSER_OUTPUT: RSS: Main: 153546752
01:22:34 INFO - 2015-10-30 01:22:34,453 DEBUG : BROWSER_OUTPUT:
01:22:35 INFO - 2015-10-30 01:22:35,322 DEBUG : BROWSER_OUTPUT: Cycle 1(20): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:35 INFO - 2015-10-30 01:22:35,957 DEBUG : BROWSER_OUTPUT: RSS: Main: 153235456
01:22:35 INFO - 2015-10-30 01:22:35,957 DEBUG : BROWSER_OUTPUT:
01:22:36 INFO - 2015-10-30 01:22:36,858 DEBUG : BROWSER_OUTPUT: Cycle 1(21): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:37 INFO - 2015-10-30 01:22:37,493 DEBUG : BROWSER_OUTPUT: RSS: Main: 152465408
01:22:37 INFO - 2015-10-30 01:22:37,493 DEBUG : BROWSER_OUTPUT:
01:22:38 INFO - 2015-10-30 01:22:38,362 DEBUG : BROWSER_OUTPUT: Cycle 1(22): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:38 INFO - 2015-10-30 01:22:38,980 DEBUG : BROWSER_OUTPUT: RSS: Main: 153436160
01:22:38 INFO - 2015-10-30 01:22:38,980 DEBUG : BROWSER_OUTPUT:
01:22:39 INFO - 2015-10-30 01:22:39,868 DEBUG : BROWSER_OUTPUT: Cycle 1(23): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:40 INFO - 2015-10-30 01:22:40,484 DEBUG : BROWSER_OUTPUT: RSS: Main: 152334336
01:22:40 INFO - 2015-10-30 01:22:40,484 DEBUG : BROWSER_OUTPUT:
01:22:41 INFO - 2015-10-30 01:22:41,388 DEBUG : BROWSER_OUTPUT: Cycle 1(24): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:42 INFO - 2015-10-30 01:22:42,020 DEBUG : BROWSER_OUTPUT: RSS: Main: 153845760
01:22:42 INFO - 2015-10-30 01:22:42,020 DEBUG : BROWSER_OUTPUT:
01:22:42 INFO - 2015-10-30 01:22:42,859 DEBUG : BROWSER_OUTPUT: Cycle 1(25): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html)
01:22:43 INFO - 2015-10-30 01:22:43,493 DEBUG : BROWSER_OUTPUT: RSS: Main: 152145920
01:22:43 INFO - 2015-10-30 01:22:43,493 DEBUG : BROWSER_OUTPUT:
01:22:44 INFO - 2015-10-30 01:22:44,048 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:44 INFO - 2015-10-30 01:22:44,601 DEBUG : BROWSER_OUTPUT: RSS: Main: 152489984
01:22:44 INFO - 2015-10-30 01:22:44,601 DEBUG : BROWSER_OUTPUT:
01:22:45 INFO - 2015-10-30 01:22:45,140 DEBUG : BROWSER_OUTPUT: Cycle 1(2): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:45 INFO - 2015-10-30 01:22:45,710 DEBUG : BROWSER_OUTPUT: RSS: Main: 153686016
01:22:45 INFO - 2015-10-30 01:22:45,710 DEBUG : BROWSER_OUTPUT:
01:22:46 INFO - 2015-10-30 01:22:46,263 DEBUG : BROWSER_OUTPUT: Cycle 1(3): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:46 INFO - 2015-10-30 01:22:46,849 DEBUG : BROWSER_OUTPUT: RSS: Main: 153772032
01:22:46 INFO - 2015-10-30 01:22:46,849 DEBUG : BROWSER_OUTPUT:
01:22:47 INFO - 2015-10-30 01:22:47,388 DEBUG : BROWSER_OUTPUT: Cycle 1(4): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:47 INFO - 2015-10-30 01:22:47,959 DEBUG : BROWSER_OUTPUT: RSS: Main: 155963392
01:22:47 INFO - 2015-10-30 01:22:47,959 DEBUG : BROWSER_OUTPUT:
01:22:48 INFO - 2015-10-30 01:22:48,496 DEBUG : BROWSER_OUTPUT: Cycle 1(5): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:49 INFO - 2015-10-30 01:22:49,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 155480064
01:22:49 INFO - 2015-10-30 01:22:49,082 DEBUG : BROWSER_OUTPUT:
01:22:49 INFO - 2015-10-30 01:22:49,637 DEBUG : BROWSER_OUTPUT: Cycle 1(6): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:50 INFO - 2015-10-30 01:22:50,207 DEBUG : BROWSER_OUTPUT: RSS: Main: 154333184
01:22:50 INFO - 2015-10-30 01:22:50,207 DEBUG : BROWSER_OUTPUT:
01:22:50 INFO - 2015-10-30 01:22:50,744 DEBUG : BROWSER_OUTPUT: Cycle 1(7): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:51 INFO - 2015-10-30 01:22:51,331 DEBUG : BROWSER_OUTPUT: RSS: Main: 155697152
01:22:51 INFO - 2015-10-30 01:22:51,331 DEBUG : BROWSER_OUTPUT:
01:22:51 INFO - 2015-10-30 01:22:51,869 DEBUG : BROWSER_OUTPUT: Cycle 1(8): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:52 INFO - 2015-10-30 01:22:52,454 DEBUG : BROWSER_OUTPUT: RSS: Main: 153972736
01:22:52 INFO - 2015-10-30 01:22:52,454 DEBUG : BROWSER_OUTPUT:
01:22:53 INFO - 2015-10-30 01:22:53,009 DEBUG : BROWSER_OUTPUT: Cycle 1(9): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:53 INFO - 2015-10-30 01:22:53,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 156524544
01:22:53 INFO - 2015-10-30 01:22:53,579 DEBUG : BROWSER_OUTPUT:
01:22:54 INFO - 2015-10-30 01:22:54,134 DEBUG : BROWSER_OUTPUT: Cycle 1(10): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:54 INFO - 2015-10-30 01:22:54,703 DEBUG : BROWSER_OUTPUT: RSS: Main: 156221440
01:22:54 INFO - 2015-10-30 01:22:54,703 DEBUG : BROWSER_OUTPUT:
01:22:55 INFO - 2015-10-30 01:22:55,257 DEBUG : BROWSER_OUTPUT: Cycle 1(11): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:55 INFO - 2015-10-30 01:22:55,844 DEBUG : BROWSER_OUTPUT: RSS: Main: 158715904
01:22:55 INFO - 2015-10-30 01:22:55,844 DEBUG : BROWSER_OUTPUT:
01:22:56 INFO - 2015-10-30 01:22:56,365 DEBUG : BROWSER_OUTPUT: Cycle 1(12): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:57 INFO - 2015-10-30 01:22:56,983 DEBUG : BROWSER_OUTPUT: RSS: Main: 165097472
01:22:57 INFO - 2015-10-30 01:22:56,983 DEBUG : BROWSER_OUTPUT:
01:22:57 INFO - 2015-10-30 01:22:57,553 DEBUG : BROWSER_OUTPUT: Cycle 1(13): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:58 INFO - 2015-10-30 01:22:58,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 162447360
01:22:58 INFO - 2015-10-30 01:22:58,154 DEBUG : BROWSER_OUTPUT:
01:22:58 INFO - 2015-10-30 01:22:58,677 DEBUG : BROWSER_OUTPUT: Cycle 1(14): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:22:59 INFO - 2015-10-30 01:22:59,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 160591872
01:22:59 INFO - 2015-10-30 01:22:59,263 DEBUG : BROWSER_OUTPUT:
01:22:59 INFO - 2015-10-30 01:22:59,802 DEBUG : BROWSER_OUTPUT: Cycle 1(15): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:00 INFO - 2015-10-30 01:23:00,388 DEBUG : BROWSER_OUTPUT: RSS: Main: 160645120
01:23:00 INFO - 2015-10-30 01:23:00,388 DEBUG : BROWSER_OUTPUT:
01:23:00 INFO - 2015-10-30 01:23:00,941 DEBUG : BROWSER_OUTPUT: Cycle 1(16): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:01 INFO - 2015-10-30 01:23:01,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 160677888
01:23:01 INFO - 2015-10-30 01:23:01,528 DEBUG : BROWSER_OUTPUT:
01:23:02 INFO - 2015-10-30 01:23:02,065 DEBUG : BROWSER_OUTPUT: Cycle 1(17): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:02 INFO - 2015-10-30 01:23:02,651 DEBUG : BROWSER_OUTPUT: RSS: Main: 159862784
01:23:02 INFO - 2015-10-30 01:23:02,651 DEBUG : BROWSER_OUTPUT:
01:23:03 INFO - 2015-10-30 01:23:03,190 DEBUG : BROWSER_OUTPUT: Cycle 1(18): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:03 INFO - 2015-10-30 01:23:03,776 DEBUG : BROWSER_OUTPUT: RSS: Main: 161882112
01:23:03 INFO - 2015-10-30 01:23:03,776 DEBUG : BROWSER_OUTPUT:
01:23:04 INFO - 2015-10-30 01:23:04,315 DEBUG : BROWSER_OUTPUT: Cycle 1(19): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:04 INFO - 2015-10-30 01:23:04,884 DEBUG : BROWSER_OUTPUT: RSS: Main: 159916032
01:23:04 INFO - 2015-10-30 01:23:04,884 DEBUG : BROWSER_OUTPUT:
01:23:05 INFO - 2015-10-30 01:23:05,454 DEBUG : BROWSER_OUTPUT: Cycle 1(20): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:06 INFO - 2015-10-30 01:23:06,040 DEBUG : BROWSER_OUTPUT: RSS: Main: 161914880
01:23:06 INFO - 2015-10-30 01:23:06,040 DEBUG : BROWSER_OUTPUT:
01:23:06 INFO - 2015-10-30 01:23:06,578 DEBUG : BROWSER_OUTPUT: Cycle 1(21): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:07 INFO - 2015-10-30 01:23:07,148 DEBUG : BROWSER_OUTPUT: RSS: Main: 161693696
01:23:07 INFO - 2015-10-30 01:23:07,148 DEBUG : BROWSER_OUTPUT:
01:23:07 INFO - 2015-10-30 01:23:07,703 DEBUG : BROWSER_OUTPUT: Cycle 1(22): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:08 INFO - 2015-10-30 01:23:08,289 DEBUG : BROWSER_OUTPUT: RSS: Main: 155127808
01:23:08 INFO - 2015-10-30 01:23:08,289 DEBUG : BROWSER_OUTPUT:
01:23:08 INFO - 2015-10-30 01:23:08,811 DEBUG : BROWSER_OUTPUT: Cycle 1(23): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:09 INFO - 2015-10-30 01:23:09,397 DEBUG : BROWSER_OUTPUT: RSS: Main: 154071040
01:23:09 INFO - 2015-10-30 01:23:09,397 DEBUG : BROWSER_OUTPUT:
01:23:09 INFO - 2015-10-30 01:23:09,936 DEBUG : BROWSER_OUTPUT: Cycle 1(24): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:10 INFO - 2015-10-30 01:23:10,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 154587136
01:23:10 INFO - 2015-10-30 01:23:10,520 DEBUG : BROWSER_OUTPUT:
01:23:11 INFO - 2015-10-30 01:23:11,059 DEBUG : BROWSER_OUTPUT: Cycle 1(25): loaded file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/tablemutation.html (next: file:///C:/slave/test-pgo/build/tests/talos/talos/page_load_test/a11y/dhtml.html)
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: RSS: Main: 158281728
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT:
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: __start_tp_report
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: |0;dhtml.html;744;785;790;795;791;869;792;837;793;837;791;870;798;840;820;791;786;795;874;795;863;794;791;820;794
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: |1;tablemutation.html;170;172;172;170;171;228;171;171;171;171;171;169;173;172;170;179;180;171;170;226;171;173;171;172;172
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: __end_tp_report
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: __start_cc_report
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,4608
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: __end_cc_report
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193391304__endTimestamp
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT:
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: Number of tests: 2
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT:
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: [#0] dhtml.html Cycles:25 Average:810.20 Median:795.00 stddev:32.84 (4.1%) stddev-sans-first:30.45
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: Values: 744.0 785.0 790.0 795.0 791.0 869.0 792.0 837.0 793.0 837.0 791.0 870.0 798.0 840.0 820.0 791.0 786.0 795.0 874.0 795.0 863.0 794.0 791.0 820.0 794.0
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT:
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: [#1] tablemutation.html Cycles:25 Average:176.28 Median:171.00 stddev:15.47 (9.0%) stddev-sans-first:15.75
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: Values: 170.0 172.0 172.0 170.0 171.0 228.0 171.0 171.0 171.0 171.0 171.0 169.0 173.0 172.0 170.0 179.0 180.0 171.0 170.0 226.0 171.0 173.0 171.0 172.0 172.0
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
01:23:11 INFO - 2015-10-30 01:23:11,312 DEBUG : BROWSER_OUTPUT:
01:23:11 INFO - 2015-10-30 01:23:11,772 INFO : Browser exited with error code: 0
01:23:11 INFO - 2015-10-30 01:23:11,819 INFO : Completed test a11yr (00:01:21)
01:23:11 INFO - 2015-10-30 01:23:11,819 INFO : Starting test ts_paint
01:23:11 INFO - 2015-10-30 01:23:11,819 DEBUG : operating with platform_type : win_
01:23:11 INFO - 2015-10-30 01:23:11,819 INFO : Initialising browser for ts_paint test...
01:23:11 INFO - 2015-10-30 01:23:11,835 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/getInfo.html
01:23:13 INFO - 2015-10-30 01:23:13,276 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:23:13 INFO - 2015-10-30 01:23:13,276 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:23:13 INFO - 2015-10-30 01:23:13,276 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
01:23:13 INFO - 2015-10-30 01:23:13,276 DEBUG : BROWSER_OUTPUT: __metrics
01:23:13 INFO - 2015-10-30 01:23:13,276 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:23:13 INFO - 2015-10-30 01:23:13,926 INFO : Browser initialized.
01:23:13 INFO - 2015-10-30 01:23:13,926 INFO : Running cycle 1/20 for ts_paint test...
01:23:13 INFO - 2015-10-30 01:23:13,926 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:15 INFO - 2015-10-30 01:23:15,002 DEBUG : BROWSER_OUTPUT: __start_report1056__end_report
01:23:15 INFO - 2015-10-30 01:23:15,002 DEBUG : BROWSER_OUTPUT:
01:23:15 INFO - 2015-10-30 01:23:15,002 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193394999__endTimestamp
01:23:15 INFO - 2015-10-30 01:23:15,667 INFO : Browser exited with error code: 0
01:23:15 INFO - 2015-10-30 01:23:15,667 INFO : Running cycle 2/20 for ts_paint test...
01:23:15 INFO - 2015-10-30 01:23:15,667 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:16 INFO - 2015-10-30 01:23:16,427 DEBUG : BROWSER_OUTPUT: __start_report763__end_report
01:23:16 INFO - 2015-10-30 01:23:16,427 DEBUG : BROWSER_OUTPUT:
01:23:16 INFO - 2015-10-30 01:23:16,443 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193396433__endTimestamp
01:23:16 INFO - 2015-10-30 01:23:16,982 INFO : Browser exited with error code: 0
01:23:16 INFO - 2015-10-30 01:23:16,982 INFO : Running cycle 3/20 for ts_paint test...
01:23:16 INFO - 2015-10-30 01:23:16,982 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:17 INFO - 2015-10-30 01:23:17,742 DEBUG : BROWSER_OUTPUT: __start_report764__end_report
01:23:17 INFO - 2015-10-30 01:23:17,742 DEBUG : BROWSER_OUTPUT:
01:23:17 INFO - 2015-10-30 01:23:17,742 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193397746__endTimestamp
01:23:18 INFO - 2015-10-30 01:23:18,312 INFO : Browser exited with error code: 0
01:23:18 INFO - 2015-10-30 01:23:18,312 INFO : Running cycle 4/20 for ts_paint test...
01:23:18 INFO - 2015-10-30 01:23:18,312 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:19 INFO - 2015-10-30 01:23:19,086 DEBUG : BROWSER_OUTPUT: __start_report761__end_report
01:23:19 INFO - 2015-10-30 01:23:19,086 DEBUG : BROWSER_OUTPUT:
01:23:19 INFO - 2015-10-30 01:23:19,102 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193399096__endTimestamp
01:23:19 INFO - 2015-10-30 01:23:19,688 INFO : Browser exited with error code: 0
01:23:19 INFO - 2015-10-30 01:23:19,688 INFO : Running cycle 5/20 for ts_paint test...
01:23:19 INFO - 2015-10-30 01:23:19,688 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:20 INFO - 2015-10-30 01:23:20,448 DEBUG : BROWSER_OUTPUT: __start_report741__end_report
01:23:20 INFO - 2015-10-30 01:23:20,448 DEBUG : BROWSER_OUTPUT:
01:23:20 INFO - 2015-10-30 01:23:20,448 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193400452__endTimestamp
01:23:21 INFO - 2015-10-30 01:23:21,114 INFO : Browser exited with error code: 0
01:23:21 INFO - 2015-10-30 01:23:21,114 INFO : Running cycle 6/20 for ts_paint test...
01:23:21 INFO - 2015-10-30 01:23:21,114 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:21 INFO - 2015-10-30 01:23:21,890 DEBUG : BROWSER_OUTPUT: __start_report764__end_report
01:23:21 INFO - 2015-10-30 01:23:21,890 DEBUG : BROWSER_OUTPUT:
01:23:21 INFO - 2015-10-30 01:23:21,905 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193401898__endTimestamp
01:23:22 INFO - 2015-10-30 01:23:22,539 INFO : Browser exited with error code: 0
01:23:22 INFO - 2015-10-30 01:23:22,539 INFO : Running cycle 7/20 for ts_paint test...
01:23:22 INFO - 2015-10-30 01:23:22,539 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:23 INFO - 2015-10-30 01:23:23,315 DEBUG : BROWSER_OUTPUT: __start_report758__end_report
01:23:23 INFO - 2015-10-30 01:23:23,315 DEBUG : BROWSER_OUTPUT:
01:23:23 INFO - 2015-10-30 01:23:23,315 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193403315__endTimestamp
01:23:23 INFO - 2015-10-30 01:23:23,980 INFO : Browser exited with error code: 0
01:23:23 INFO - 2015-10-30 01:23:23,980 INFO : Running cycle 8/20 for ts_paint test...
01:23:23 INFO - 2015-10-30 01:23:23,980 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:24 INFO - 2015-10-30 01:23:24,756 DEBUG : BROWSER_OUTPUT: __start_report770__end_report
01:23:24 INFO - 2015-10-30 01:23:24,756 DEBUG : BROWSER_OUTPUT:
01:23:24 INFO - 2015-10-30 01:23:24,756 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193404763__endTimestamp
01:23:25 INFO - 2015-10-30 01:23:25,453 INFO : Browser exited with error code: 0
01:23:25 INFO - 2015-10-30 01:23:25,453 INFO : Running cycle 9/20 for ts_paint test...
01:23:25 INFO - 2015-10-30 01:23:25,453 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:26 INFO - 2015-10-30 01:23:26,213 DEBUG : BROWSER_OUTPUT: __start_report766__end_report
01:23:26 INFO - 2015-10-30 01:23:26,213 DEBUG : BROWSER_OUTPUT:
01:23:26 INFO - 2015-10-30 01:23:26,227 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193406221__endTimestamp
01:23:26 INFO - 2015-10-30 01:23:26,878 INFO : Browser exited with error code: 0
01:23:26 INFO - 2015-10-30 01:23:26,878 INFO : Running cycle 10/20 for ts_paint test...
01:23:26 INFO - 2015-10-30 01:23:26,878 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:27 INFO - 2015-10-30 01:23:27,638 DEBUG : BROWSER_OUTPUT: __start_report740__end_report
01:23:27 INFO - 2015-10-30 01:23:27,638 DEBUG : BROWSER_OUTPUT:
01:23:27 INFO - 2015-10-30 01:23:27,638 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193407641__endTimestamp
01:23:28 INFO - 2015-10-30 01:23:28,318 INFO : Browser exited with error code: 0
01:23:28 INFO - 2015-10-30 01:23:28,318 INFO : Running cycle 11/20 for ts_paint test...
01:23:28 INFO - 2015-10-30 01:23:28,318 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:29 INFO - 2015-10-30 01:23:29,078 DEBUG : BROWSER_OUTPUT: __start_report765__end_report
01:23:29 INFO - 2015-10-30 01:23:29,078 DEBUG : BROWSER_OUTPUT:
01:23:29 INFO - 2015-10-30 01:23:29,078 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193409083__endTimestamp
01:23:29 INFO - 2015-10-30 01:23:29,743 INFO : Browser exited with error code: 0
01:23:29 INFO - 2015-10-30 01:23:29,743 INFO : Running cycle 12/20 for ts_paint test...
01:23:29 INFO - 2015-10-30 01:23:29,743 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:30 INFO - 2015-10-30 01:23:30,503 DEBUG : BROWSER_OUTPUT: __start_report758__end_report
01:23:30 INFO - 2015-10-30 01:23:30,503 DEBUG : BROWSER_OUTPUT:
01:23:30 INFO - 2015-10-30 01:23:30,519 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193410512__endTimestamp
01:23:31 INFO - 2015-10-30 01:23:31,168 INFO : Browser exited with error code: 0
01:23:31 INFO - 2015-10-30 01:23:31,168 INFO : Running cycle 13/20 for ts_paint test...
01:23:31 INFO - 2015-10-30 01:23:31,168 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:31 INFO - 2015-10-30 01:23:31,944 DEBUG : BROWSER_OUTPUT: __start_report757__end_report
01:23:31 INFO - 2015-10-30 01:23:31,944 DEBUG : BROWSER_OUTPUT:
01:23:31 INFO - 2015-10-30 01:23:31,944 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193411942__endTimestamp
01:23:32 INFO - 2015-10-30 01:23:32,657 INFO : Browser exited with error code: 0
01:23:32 INFO - 2015-10-30 01:23:32,657 INFO : Running cycle 14/20 for ts_paint test...
01:23:32 INFO - 2015-10-30 01:23:32,657 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:33 INFO - 2015-10-30 01:23:33,433 DEBUG : BROWSER_OUTPUT: __start_report758__end_report
01:23:33 INFO - 2015-10-30 01:23:33,433 DEBUG : BROWSER_OUTPUT:
01:23:33 INFO - 2015-10-30 01:23:33,433 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193413426__endTimestamp
01:23:34 INFO - 2015-10-30 01:23:34,082 INFO : Browser exited with error code: 0
01:23:34 INFO - 2015-10-30 01:23:34,082 INFO : Running cycle 15/20 for ts_paint test...
01:23:34 INFO - 2015-10-30 01:23:34,082 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:34 INFO - 2015-10-30 01:23:34,842 DEBUG : BROWSER_OUTPUT: __start_report760__end_report
01:23:34 INFO - 2015-10-30 01:23:34,842 DEBUG : BROWSER_OUTPUT:
01:23:34 INFO - 2015-10-30 01:23:34,842 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193414844__endTimestamp
01:23:35 INFO - 2015-10-30 01:23:35,506 INFO : Browser exited with error code: 0
01:23:35 INFO - 2015-10-30 01:23:35,506 INFO : Running cycle 16/20 for ts_paint test...
01:23:35 INFO - 2015-10-30 01:23:35,506 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:36 INFO - 2015-10-30 01:23:36,266 DEBUG : BROWSER_OUTPUT: __start_report753__end_report
01:23:36 INFO - 2015-10-30 01:23:36,266 DEBUG : BROWSER_OUTPUT:
01:23:36 INFO - 2015-10-30 01:23:36,282 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193416280__endTimestamp
01:23:36 INFO - 2015-10-30 01:23:36,994 INFO : Browser exited with error code: 0
01:23:36 INFO - 2015-10-30 01:23:36,994 INFO : Running cycle 17/20 for ts_paint test...
01:23:36 INFO - 2015-10-30 01:23:36,994 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:37 INFO - 2015-10-30 01:23:37,755 DEBUG : BROWSER_OUTPUT: __start_report747__end_report
01:23:37 INFO - 2015-10-30 01:23:37,755 DEBUG : BROWSER_OUTPUT:
01:23:37 INFO - 2015-10-30 01:23:37,755 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193417757__endTimestamp
01:23:38 INFO - 2015-10-30 01:23:38,420 INFO : Browser exited with error code: 0
01:23:38 INFO - 2015-10-30 01:23:38,420 INFO : Running cycle 18/20 for ts_paint test...
01:23:38 INFO - 2015-10-30 01:23:38,420 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:39 INFO - 2015-10-30 01:23:39,196 DEBUG : BROWSER_OUTPUT: __start_report760__end_report
01:23:39 INFO - 2015-10-30 01:23:39,196 DEBUG : BROWSER_OUTPUT:
01:23:39 INFO - 2015-10-30 01:23:39,196 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193419195__endTimestamp
01:23:39 INFO - 2015-10-30 01:23:39,845 INFO : Browser exited with error code: 0
01:23:39 INFO - 2015-10-30 01:23:39,845 INFO : Running cycle 19/20 for ts_paint test...
01:23:39 INFO - 2015-10-30 01:23:39,845 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:40 INFO - 2015-10-30 01:23:40,605 DEBUG : BROWSER_OUTPUT: __start_report760__end_report
01:23:40 INFO - 2015-10-30 01:23:40,605 DEBUG : BROWSER_OUTPUT:
01:23:40 INFO - 2015-10-30 01:23:40,621 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193420610__endTimestamp
01:23:41 INFO - 2015-10-30 01:23:41,269 INFO : Browser exited with error code: 0
01:23:41 INFO - 2015-10-30 01:23:41,269 INFO : Running cycle 20/20 for ts_paint test...
01:23:41 INFO - 2015-10-30 01:23:41,269 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpec7j22\profile http://localhost/startup_test/tspaint_test.html
01:23:42 INFO - 2015-10-30 01:23:42,046 DEBUG : BROWSER_OUTPUT: __start_report764__end_report
01:23:42 INFO - 2015-10-30 01:23:42,046 DEBUG : BROWSER_OUTPUT:
01:23:42 INFO - 2015-10-30 01:23:42,046 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193422047__endTimestamp
01:23:42 INFO - 2015-10-30 01:23:42,710 INFO : Browser exited with error code: 0
01:23:42 INFO - 2015-10-30 01:23:42,775 INFO : Completed test ts_paint (00:00:30)
01:23:42 INFO - 2015-10-30 01:23:42,775 INFO : Starting test tpaint
01:23:42 INFO - 2015-10-30 01:23:42,775 DEBUG : operating with platform_type : win_
01:23:42 INFO - 2015-10-30 01:23:42,775 INFO : Initialising browser for tpaint test...
01:23:42 INFO - 2015-10-30 01:23:42,789 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpefubem\profile http://localhost/getInfo.html
01:23:44 INFO - 2015-10-30 01:23:44,151 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:23:44 INFO - 2015-10-30 01:23:44,151 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:23:44 INFO - 2015-10-30 01:23:44,151 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
01:23:44 INFO - 2015-10-30 01:23:44,151 DEBUG : BROWSER_OUTPUT: __metrics
01:23:44 INFO - 2015-10-30 01:23:44,167 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:23:44 INFO - 2015-10-30 01:23:44,801 INFO : Browser initialized.
01:23:44 INFO - 2015-10-30 01:23:44,801 INFO : Running cycle 1/1 for tpaint test...
01:23:44 INFO - 2015-10-30 01:23:44,801 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpefubem\profile file://C:\slave\test-pgo\build\tests\talos\talos/startup_test/tpaint.html?auto=1
01:24:09 INFO - 2015-10-30 01:24:09,437 DEBUG : BROWSER_OUTPUT: __start_report148.73500000000058|149.79000000000087|149.84500000000025|150.10500000000002|150.41999999999916|152.3000000000011|155.9000000000001|157.0599999999995|157.4950000000008|163.9800000000032|164.20499999999993|165.14999999999964|166.03499999999985|171.62999999999738|171.92499999999927|172.27500000000146|173.66499999999996|175.27499999999964|178.6800000000003|180.1899999999987__end_report__startTimestamp1446193449426__endTimestamp
01:24:09 INFO - 2015-10-30 01:24:09,437 DEBUG : BROWSER_OUTPUT: openingTimes=149.79000000000087,149.84500000000025,150.10500000000002,150.41999999999916,152.3000000000011,155.9000000000001,157.0599999999995,157.4950000000008,163.9800000000032,164.20499999999993,165.14999999999964,166.03499999999985,171.62999999999738,171.92499999999927,172.27500000000146,173.66499999999996,175.27499999999964,178.6800000000003,180.1899999999987
01:24:09 INFO - 2015-10-30 01:24:09,437 DEBUG : BROWSER_OUTPUT: avgOpenTime:162.73
01:24:09 INFO - 2015-10-30 01:24:09,437 DEBUG : BROWSER_OUTPUT: minOpenTime:148.74
01:24:09 INFO - 2015-10-30 01:24:09,437 DEBUG : BROWSER_OUTPUT: maxOpenTime:180.19
01:24:09 INFO - 2015-10-30 01:24:09,437 DEBUG : BROWSER_OUTPUT: medOpenTime:164.09250000000156
01:24:09 INFO - 2015-10-30 01:24:09,437 DEBUG : BROWSER_OUTPUT: __xulWinOpenTime:164.09250000000156
01:24:10 INFO - 2015-10-30 01:24:10,213 INFO : Browser exited with error code: 0
01:24:10 INFO - 2015-10-30 01:24:10,246 INFO : Completed test tpaint (00:00:27)
01:24:10 INFO - 2015-10-30 01:24:10,246 INFO : Starting test sessionrestore
01:24:10 INFO - 2015-10-30 01:24:10,246 DEBUG : operating with platform_type : win_
01:24:10 INFO - 2015-10-30 01:24:10,246 INFO : Initialising browser for sessionrestore test...
01:24:10 INFO - 2015-10-30 01:24:10,262 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/getInfo.html
01:24:11 INFO - 2015-10-30 01:24:11,733 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:24:11 INFO - 2015-10-30 01:24:11,733 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:24:11 INFO - 2015-10-30 01:24:11,733 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1192/770
01:24:11 INFO - 2015-10-30 01:24:11,733 DEBUG : BROWSER_OUTPUT: __metrics
01:24:11 INFO - 2015-10-30 01:24:11,750 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:24:12 INFO - 2015-10-30 01:24:12,414 INFO : Browser initialized.
01:24:12 INFO - 2015-10-30 01:24:12,414 INFO : Running cycle 1/10 for sessionrestore test...
01:24:12 INFO - 2015-10-30 01:24:12,414 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:12 INFO - 2015-10-30 01:24:12,414 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:15 INFO - 2015-10-30 01:24:15,359 DEBUG : BROWSER_OUTPUT: __start_report1499__end_report
01:24:15 INFO - 2015-10-30 01:24:15,359 DEBUG : BROWSER_OUTPUT:
01:24:15 INFO - 2015-10-30 01:24:15,359 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193455363__endTimestamp
01:24:15 INFO - 2015-10-30 01:24:15,359 DEBUG : BROWSER_OUTPUT:
01:24:16 INFO - 2015-10-30 01:24:16,548 INFO : Browser exited with error code: 0
01:24:16 INFO - 2015-10-30 01:24:16,562 INFO : Running cycle 2/10 for sessionrestore test...
01:24:16 INFO - 2015-10-30 01:24:16,562 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:16 INFO - 2015-10-30 01:24:16,562 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:18 INFO - 2015-10-30 01:24:18,733 DEBUG : BROWSER_OUTPUT: __start_report1302__end_report
01:24:18 INFO - 2015-10-30 01:24:18,733 DEBUG : BROWSER_OUTPUT:
01:24:18 INFO - 2015-10-30 01:24:18,733 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193458707__endTimestamp
01:24:18 INFO - 2015-10-30 01:24:18,733 DEBUG : BROWSER_OUTPUT:
01:24:19 INFO - 2015-10-30 01:24:19,983 INFO : Browser exited with error code: 0
01:24:19 INFO - 2015-10-30 01:24:19,983 INFO : Running cycle 3/10 for sessionrestore test...
01:24:19 INFO - 2015-10-30 01:24:19,983 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:19 INFO - 2015-10-30 01:24:19,983 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:22 INFO - 2015-10-30 01:24:22,153 DEBUG : BROWSER_OUTPUT: __start_report1317__end_report
01:24:22 INFO - 2015-10-30 01:24:22,153 DEBUG : BROWSER_OUTPUT:
01:24:22 INFO - 2015-10-30 01:24:22,153 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193462133__endTimestamp
01:24:22 INFO - 2015-10-30 01:24:22,153 DEBUG : BROWSER_OUTPUT:
01:24:23 INFO - 2015-10-30 01:24:23,450 INFO : Browser exited with error code: 0
01:24:23 INFO - 2015-10-30 01:24:23,450 INFO : Running cycle 4/10 for sessionrestore test...
01:24:23 INFO - 2015-10-30 01:24:23,450 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:23 INFO - 2015-10-30 01:24:23,450 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:25 INFO - 2015-10-30 01:24:25,619 DEBUG : BROWSER_OUTPUT: __start_report1304__end_report
01:24:25 INFO - 2015-10-30 01:24:25,619 DEBUG : BROWSER_OUTPUT:
01:24:25 INFO - 2015-10-30 01:24:25,619 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193465595__endTimestamp
01:24:25 INFO - 2015-10-30 01:24:25,619 DEBUG : BROWSER_OUTPUT:
01:24:26 INFO - 2015-10-30 01:24:26,966 INFO : Browser exited with error code: 0
01:24:26 INFO - 2015-10-30 01:24:26,966 INFO : Running cycle 5/10 for sessionrestore test...
01:24:26 INFO - 2015-10-30 01:24:26,966 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:26 INFO - 2015-10-30 01:24:26,966 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:29 INFO - 2015-10-30 01:24:29,183 DEBUG : BROWSER_OUTPUT: __start_report1330__end_report
01:24:29 INFO - 2015-10-30 01:24:29,183 DEBUG : BROWSER_OUTPUT:
01:24:29 INFO - 2015-10-30 01:24:29,183 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193469157__endTimestamp
01:24:29 INFO - 2015-10-30 01:24:29,183 DEBUG : BROWSER_OUTPUT:
01:24:30 INFO - 2015-10-30 01:24:30,529 INFO : Browser exited with error code: 0
01:24:30 INFO - 2015-10-30 01:24:30,529 INFO : Running cycle 6/10 for sessionrestore test...
01:24:30 INFO - 2015-10-30 01:24:30,529 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:30 INFO - 2015-10-30 01:24:30,529 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:32 INFO - 2015-10-30 01:24:32,697 DEBUG : BROWSER_OUTPUT: __start_report1307__end_report
01:24:32 INFO - 2015-10-30 01:24:32,697 DEBUG : BROWSER_OUTPUT:
01:24:32 INFO - 2015-10-30 01:24:32,697 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193472676__endTimestamp
01:24:32 INFO - 2015-10-30 01:24:32,697 DEBUG : BROWSER_OUTPUT:
01:24:34 INFO - 2015-10-30 01:24:34,107 INFO : Browser exited with error code: 0
01:24:34 INFO - 2015-10-30 01:24:34,107 INFO : Running cycle 7/10 for sessionrestore test...
01:24:34 INFO - 2015-10-30 01:24:34,107 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:34 INFO - 2015-10-30 01:24:34,107 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:36 INFO - 2015-10-30 01:24:36,276 DEBUG : BROWSER_OUTPUT: __start_report1305__end_report
01:24:36 INFO - 2015-10-30 01:24:36,276 DEBUG : BROWSER_OUTPUT:
01:24:36 INFO - 2015-10-30 01:24:36,276 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193476253__endTimestamp
01:24:36 INFO - 2015-10-30 01:24:36,276 DEBUG : BROWSER_OUTPUT:
01:24:37 INFO - 2015-10-30 01:24:37,621 INFO : Browser exited with error code: 0
01:24:37 INFO - 2015-10-30 01:24:37,621 INFO : Running cycle 8/10 for sessionrestore test...
01:24:37 INFO - 2015-10-30 01:24:37,621 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:37 INFO - 2015-10-30 01:24:37,621 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:39 INFO - 2015-10-30 01:24:39,775 DEBUG : BROWSER_OUTPUT: __start_report1306__end_report
01:24:39 INFO - 2015-10-30 01:24:39,775 DEBUG : BROWSER_OUTPUT:
01:24:39 INFO - 2015-10-30 01:24:39,775 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193479761__endTimestamp
01:24:39 INFO - 2015-10-30 01:24:39,775 DEBUG : BROWSER_OUTPUT:
01:24:41 INFO - 2015-10-30 01:24:41,137 INFO : Browser exited with error code: 0
01:24:41 INFO - 2015-10-30 01:24:41,137 INFO : Running cycle 9/10 for sessionrestore test...
01:24:41 INFO - 2015-10-30 01:24:41,137 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:41 INFO - 2015-10-30 01:24:41,137 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:43 INFO - 2015-10-30 01:24:43,369 DEBUG : BROWSER_OUTPUT: __start_report1342__end_report
01:24:43 INFO - 2015-10-30 01:24:43,369 DEBUG : BROWSER_OUTPUT:
01:24:43 INFO - 2015-10-30 01:24:43,369 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193483352__endTimestamp
01:24:43 INFO - 2015-10-30 01:24:43,369 DEBUG : BROWSER_OUTPUT:
01:24:44 INFO - 2015-10-30 01:24:44,811 INFO : Browser exited with error code: 0
01:24:44 INFO - 2015-10-30 01:24:44,811 INFO : Running cycle 10/10 for sessionrestore test...
01:24:44 INFO - 2015-10-30 01:24:44,811 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile\sessionstore.js
01:24:44 INFO - 2015-10-30 01:24:44,811 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpspabop\profile http://localhost/startup_test/sessionrestore/index.html
01:24:46 INFO - 2015-10-30 01:24:46,980 DEBUG : BROWSER_OUTPUT: __start_report1307__end_report
01:24:46 INFO - 2015-10-30 01:24:46,980 DEBUG : BROWSER_OUTPUT:
01:24:46 INFO - 2015-10-30 01:24:46,980 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193486953__endTimestamp
01:24:46 INFO - 2015-10-30 01:24:46,980 DEBUG : BROWSER_OUTPUT:
01:24:48 INFO - 2015-10-30 01:24:48,325 INFO : Browser exited with error code: 0
01:24:48 INFO - 2015-10-30 01:24:48,388 INFO : Completed test sessionrestore (00:00:38)
01:24:48 INFO - 2015-10-30 01:24:48,388 INFO : Starting test sessionrestore_no_auto_restore
01:24:48 INFO - 2015-10-30 01:24:48,388 DEBUG : operating with platform_type : win_
01:24:48 INFO - 2015-10-30 01:24:48,388 INFO : Initialising browser for sessionrestore_no_auto_restore test...
01:24:48 INFO - 2015-10-30 01:24:48,404 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/getInfo.html
01:24:49 INFO - 2015-10-30 01:24:49,845 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:24:49 INFO - 2015-10-30 01:24:49,845 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:24:49 INFO - 2015-10-30 01:24:49,845 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1192/770
01:24:49 INFO - 2015-10-30 01:24:49,845 DEBUG : BROWSER_OUTPUT: __metrics
01:24:49 INFO - 2015-10-30 01:24:49,861 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:24:50 INFO - 2015-10-30 01:24:50,542 INFO : Browser initialized.
01:24:50 INFO - 2015-10-30 01:24:50,542 INFO : Running cycle 1/10 for sessionrestore_no_auto_restore test...
01:24:50 INFO - 2015-10-30 01:24:50,542 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:24:50 INFO - 2015-10-30 01:24:50,542 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:24:51 INFO - 2015-10-30 01:24:51,618 DEBUG : BROWSER_OUTPUT: __start_report710__end_report
01:24:51 INFO - 2015-10-30 01:24:51,618 DEBUG : BROWSER_OUTPUT:
01:24:51 INFO - 2015-10-30 01:24:51,618 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193491620__endTimestamp
01:24:51 INFO - 2015-10-30 01:24:51,618 DEBUG : BROWSER_OUTPUT:
01:24:52 INFO - 2015-10-30 01:24:52,283 INFO : Browser exited with error code: 0
01:24:52 INFO - 2015-10-30 01:24:52,283 INFO : Running cycle 2/10 for sessionrestore_no_auto_restore test...
01:24:52 INFO - 2015-10-30 01:24:52,283 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:24:52 INFO - 2015-10-30 01:24:52,283 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:24:53 INFO - 2015-10-30 01:24:53,138 DEBUG : BROWSER_OUTPUT: __start_report559__end_report
01:24:53 INFO - 2015-10-30 01:24:53,138 DEBUG : BROWSER_OUTPUT:
01:24:53 INFO - 2015-10-30 01:24:53,138 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193493135__endTimestamp
01:24:53 INFO - 2015-10-30 01:24:53,138 DEBUG : BROWSER_OUTPUT:
01:24:53 INFO - 2015-10-30 01:24:53,709 INFO : Browser exited with error code: 0
01:24:53 INFO - 2015-10-30 01:24:53,709 INFO : Running cycle 3/10 for sessionrestore_no_auto_restore test...
01:24:53 INFO - 2015-10-30 01:24:53,709 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:24:53 INFO - 2015-10-30 01:24:53,724 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:24:54 INFO - 2015-10-30 01:24:54,563 DEBUG : BROWSER_OUTPUT: __start_report549__end_report
01:24:54 INFO - 2015-10-30 01:24:54,563 DEBUG : BROWSER_OUTPUT:
01:24:54 INFO - 2015-10-30 01:24:54,563 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193494554__endTimestamp
01:24:54 INFO - 2015-10-30 01:24:54,563 DEBUG : BROWSER_OUTPUT:
01:24:55 INFO - 2015-10-30 01:24:55,197 INFO : Browser exited with error code: 0
01:24:55 INFO - 2015-10-30 01:24:55,197 INFO : Running cycle 4/10 for sessionrestore_no_auto_restore test...
01:24:55 INFO - 2015-10-30 01:24:55,197 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:24:55 INFO - 2015-10-30 01:24:55,197 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:24:56 INFO - 2015-10-30 01:24:56,036 DEBUG : BROWSER_OUTPUT: __start_report561__end_report
01:24:56 INFO - 2015-10-30 01:24:56,036 DEBUG : BROWSER_OUTPUT:
01:24:56 INFO - 2015-10-30 01:24:56,036 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193496039__endTimestamp
01:24:56 INFO - 2015-10-30 01:24:56,036 DEBUG : BROWSER_OUTPUT:
01:24:56 INFO - 2015-10-30 01:24:56,686 INFO : Browser exited with error code: 0
01:24:56 INFO - 2015-10-30 01:24:56,686 INFO : Running cycle 5/10 for sessionrestore_no_auto_restore test...
01:24:56 INFO - 2015-10-30 01:24:56,686 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:24:56 INFO - 2015-10-30 01:24:56,686 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:24:57 INFO - 2015-10-30 01:24:57,525 DEBUG : BROWSER_OUTPUT: __start_report546__end_report
01:24:57 INFO - 2015-10-30 01:24:57,525 DEBUG : BROWSER_OUTPUT:
01:24:57 INFO - 2015-10-30 01:24:57,525 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193497515__endTimestamp
01:24:57 INFO - 2015-10-30 01:24:57,525 DEBUG : BROWSER_OUTPUT:
01:24:58 INFO - 2015-10-30 01:24:58,157 INFO : Browser exited with error code: 0
01:24:58 INFO - 2015-10-30 01:24:58,157 INFO : Running cycle 6/10 for sessionrestore_no_auto_restore test...
01:24:58 INFO - 2015-10-30 01:24:58,157 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:24:58 INFO - 2015-10-30 01:24:58,157 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:24:59 INFO - 2015-10-30 01:24:59,029 DEBUG : BROWSER_OUTPUT: __start_report577__end_report
01:24:59 INFO - 2015-10-30 01:24:59,029 DEBUG : BROWSER_OUTPUT:
01:24:59 INFO - 2015-10-30 01:24:59,029 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193499018__endTimestamp
01:24:59 INFO - 2015-10-30 01:24:59,029 DEBUG : BROWSER_OUTPUT:
01:24:59 INFO - 2015-10-30 01:24:59,694 INFO : Browser exited with error code: 0
01:24:59 INFO - 2015-10-30 01:24:59,694 INFO : Running cycle 7/10 for sessionrestore_no_auto_restore test...
01:24:59 INFO - 2015-10-30 01:24:59,694 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:24:59 INFO - 2015-10-30 01:24:59,694 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:25:00 INFO - 2015-10-30 01:25:00,517 DEBUG : BROWSER_OUTPUT: __start_report543__end_report
01:25:00 INFO - 2015-10-30 01:25:00,517 DEBUG : BROWSER_OUTPUT:
01:25:00 INFO - 2015-10-30 01:25:00,517 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193500517__endTimestamp
01:25:00 INFO - 2015-10-30 01:25:00,517 DEBUG : BROWSER_OUTPUT:
01:25:01 INFO - 2015-10-30 01:25:01,183 INFO : Browser exited with error code: 0
01:25:01 INFO - 2015-10-30 01:25:01,183 INFO : Running cycle 8/10 for sessionrestore_no_auto_restore test...
01:25:01 INFO - 2015-10-30 01:25:01,183 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:25:01 INFO - 2015-10-30 01:25:01,183 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:25:02 INFO - 2015-10-30 01:25:02,022 DEBUG : BROWSER_OUTPUT: __start_report556__end_report
01:25:02 INFO - 2015-10-30 01:25:02,022 DEBUG : BROWSER_OUTPUT:
01:25:02 INFO - 2015-10-30 01:25:02,022 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193502019__endTimestamp
01:25:02 INFO - 2015-10-30 01:25:02,022 DEBUG : BROWSER_OUTPUT:
01:25:02 INFO - 2015-10-30 01:25:02,717 INFO : Browser exited with error code: 0
01:25:02 INFO - 2015-10-30 01:25:02,717 INFO : Running cycle 9/10 for sessionrestore_no_auto_restore test...
01:25:02 INFO - 2015-10-30 01:25:02,717 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:25:02 INFO - 2015-10-30 01:25:02,717 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:25:03 INFO - 2015-10-30 01:25:03,542 DEBUG : BROWSER_OUTPUT: __start_report546__end_report
01:25:03 INFO - 2015-10-30 01:25:03,542 DEBUG : BROWSER_OUTPUT:
01:25:03 INFO - 2015-10-30 01:25:03,542 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193503544__endTimestamp
01:25:03 INFO - 2015-10-30 01:25:03,542 DEBUG : BROWSER_OUTPUT:
01:25:04 INFO - 2015-10-30 01:25:04,190 INFO : Browser exited with error code: 0
01:25:04 INFO - 2015-10-30 01:25:04,190 INFO : Running cycle 10/10 for sessionrestore_no_auto_restore test...
01:25:04 INFO - 2015-10-30 01:25:04,190 DEBUG : Reinstalling C:\slave\test-pgo\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile\sessionstore.js
01:25:04 INFO - 2015-10-30 01:25:04,190 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmslnrt\profile http://localhost/startup_test/sessionrestore/index.html
01:25:05 INFO - 2015-10-30 01:25:05,046 DEBUG : BROWSER_OUTPUT: __start_report559__end_report
01:25:05 INFO - 2015-10-30 01:25:05,046 DEBUG : BROWSER_OUTPUT:
01:25:05 INFO - 2015-10-30 01:25:05,046 DEBUG : BROWSER_OUTPUT: __startTimestamp1446193505035__endTimestamp
01:25:05 INFO - 2015-10-30 01:25:05,046 DEBUG : BROWSER_OUTPUT:
01:25:05 INFO - 2015-10-30 01:25:05,678 INFO : Browser exited with error code: 0
01:25:05 INFO - 2015-10-30 01:25:05,726 INFO : Completed test sessionrestore_no_auto_restore (00:00:17)
01:25:05 INFO - 2015-10-30 01:25:05,726 INFO : Completed test suite (00:03:15)
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Working with test: a11yr
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Generating results file: a11yr
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Working with test: ts_paint
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Generating results file: ts_paint
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Working with test: tpaint
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Generating results file: tpaint
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Working with test: sessionrestore
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Generating results file: sessionrestore
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Working with test: sessionrestore_no_auto_restore
01:25:05 INFO - 2015-10-30 01:25:05,726 DEBUG : Generating results file: sessionrestore_no_auto_restore
01:25:05 INFO - 2015-10-30 01:25:05,726 INFO : Posting result 0 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:25:05 INFO - 2015-10-30 01:25:05,868 DEBUG : process_Request line: a11yr_paint graph.html#tests=[[223,52,37]]
01:25:05 INFO - 2015-10-30 01:25:05,868 DEBUG : process_Request line: a11yr_paint 369.02 graph.html#tests=[[223,52,37]]
01:25:05 INFO - 2015-10-30 01:25:05,868 INFO : Posting result 1 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:25:06 INFO - 2015-10-30 01:25:06,028 DEBUG : process_Request line: ts_paint graph.html#tests=[[83,52,37]]
01:25:06 INFO - 2015-10-30 01:25:06,028 DEBUG : process_Request line: ts_paint 770.99 graph.html#tests=[[83,52,37]]
01:25:06 INFO - 2015-10-30 01:25:06,028 INFO : Posting result 2 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:25:06 INFO - 2015-10-30 01:25:06,154 DEBUG : process_Request line: tpaint graph.html#tests=[[82,52,37]]
01:25:06 INFO - 2015-10-30 01:25:06,154 DEBUG : process_Request line: tpaint 166.03 graph.html#tests=[[82,52,37]]
01:25:06 INFO - 2015-10-30 01:25:06,154 INFO : Posting result 3 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:25:06 INFO - 2015-10-30 01:25:06,296 DEBUG : process_Request line: sessionrestore graph.html#tests=[[313,52,37]]
01:25:06 INFO - 2015-10-30 01:25:06,296 DEBUG : process_Request line: sessionrestore 1330.76 graph.html#tests=[[313,52,37]]
01:25:06 INFO - 2015-10-30 01:25:06,296 INFO : Posting result 4 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
01:25:06 INFO - 2015-10-30 01:25:06,438 DEBUG : process_Request line: sessionrestore_no_auto_restore graph.html#tests=[[315,52,37]]
01:25:06 INFO - 2015-10-30 01:25:06,438 DEBUG : process_Request line: sessionrestore_no_auto_restore 568.86 graph.html#tests=[[315,52,37]]
01:25:06 INFO - 2015-10-30 01:25:06,438 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"dhtml.html": [744.0, 785.0, 790.0, 795.0, 791.0, 869.0, 792.0, 837.0, 793.0, 837.0, 791.0, 870.0, 798.0, 840.0, 820.0, 791.0, 786.0, 795.0, 874.0, 795.0, 863.0, 794.0, 791.0, 820.0, 794.0], "tablemutation.html": [170.0, 172.0, 172.0, 170.0, 171.0, 228.0, 171.0, 171.0, 171.0, 171.0, 171.0, 169.0, 173.0, 172.0, 170.0, 179.0, 180.0, 171.0, 170.0, 226.0, 171.0, 173.0, 171.0, 172.0, 172.0]}, "summary": {"suite": 369.0162158608727, "subtests": {"dhtml.html": {"std": 29.806989066697465, "min": 785.0, "max": 874.0, "median": 795.0, "filtered": 795.0, "mean": 812.9583333333334}, "tablemutation.html": {"std": 15.41368214354449, "min": 169.0, "max": 228.0, "median": 171.0, "filtered": 171.0, "mean": 176.54166666666666}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "t-xp32-ix-047"}, "testrun": {"date": 1446193310, "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": "43.0a2", "id": "20151029014646", "branch": "Mozilla-Aurora", "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"}}, {"talos_counters": {}, "results": {"ts_paint": [1056.0, 763.0, 764.0, 761.0, 741.0, 764.0, 758.0, 770.0, 766.0, 740.0, 765.0, 758.0, 757.0, 758.0, 760.0, 753.0, 747.0, 760.0, 760.0, 764.0]}, "summary": {"suite": 760.0, "subtests": {"ts_paint": {"std": 7.841928645132285, "min": 740.0, "max": 770.0, "median": 760.0, "filtered": 760.0, "mean": 758.3684210526316}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "t-xp32-ix-047"}, "testrun": {"date": 1446193310, "suite": "ts_paint", "options": {"responsiveness": false, "cycles": 20, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "43.0a2", "id": "20151029014646", "branch": "Mozilla-Aurora", "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"}}, {"talos_counters": {}, "results": {"tpaint": [148.73500000000058, 149.79000000000087, 149.84500000000025, 150.10500000000002, 150.41999999999916, 152.3000000000011, 155.9000000000001, 157.0599999999995, 157.4950000000008, 163.9800000000032, 164.20499999999993, 165.14999999999964, 166.03499999999985, 171.62999999999738, 171.92499999999927, 172.27500000000146, 173.66499999999996, 175.27499999999964, 178.6800000000003, 180.1899999999987]}, "summary": {"suite": 166.03499999999985, "subtests": {"tpaint": {"std": 8.35268523689595, "min": 152.3000000000011, "max": 180.1899999999987, "median": 166.03499999999985, "filtered": 166.03499999999985, "mean": 167.05100000000004}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "t-xp32-ix-047"}, "testrun": {"date": 1446193310, "suite": "tpaint", "options": {"responsiveness": false, "cycles": 1, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "43.0a2", "id": "20151029014646", "branch": "Mozilla-Aurora", "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"}}, {"talos_counters": {}, "results": {"sessionrestore": [1499.0, 1302.0, 1317.0, 1304.0, 1330.0, 1307.0, 1305.0, 1306.0, 1342.0, 1307.0]}, "summary": {"suite": 1307.0, "subtests": {"sessionrestore": {"std": 13.04692386052062, "min": 1302.0, "max": 1342.0, "median": 1307.0, "filtered": 1307.0, "mean": 1313.3333333333333}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "t-xp32-ix-047"}, "testrun": {"date": 1446193310, "suite": "sessionrestore", "options": {"responsiveness": false, "cycles": 10, "tpmozafterpaint": false, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "43.0a2", "id": "20151029014646", "branch": "Mozilla-Aurora", "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"}}, {"talos_counters": {}, "results": {"sessionrestore_no_auto_restore": [710.0, 559.0, 549.0, 561.0, 546.0, 577.0, 543.0, 556.0, 546.0, 559.0]}, "summary": {"suite": 556.0, "subtests": {"sessionrestore_no_auto_restore": {"std": 9.971564509415366, "min": 543.0, "max": 577.0, "median": 556.0, "filtered": 556.0, "mean": 555.1111111111111}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "t-xp32-ix-047"}, "testrun": {"date": 1446193310, "suite": "sessionrestore_no_auto_restore", "options": {"responsiveness": false, "cycles": 10, "tpmozafterpaint": false, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "43.0a2", "id": "20151029014646", "branch": "Mozilla-Aurora", "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797"}}]
01:25:06 INFO - RETURN: a11yr_paint: 369.02
01:25:06 INFO - RETURN: ts_paint: 770.99
01:25:06 INFO - RETURN: tpaint: 166.03
01:25:06 INFO - RETURN: sessionrestore: 1330.76
01:25:06 INFO - RETURN: sessionrestore_no_auto_restore: 568.86
01:25:06 INFO - TinderboxPrint: TalosResult: {"graphserver": {"sessionrestore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[313,52,37]]", "result": "1330.76"}, "a11yr_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[223,52,37]]", "result": "369.02"}, "tpaint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[82,52,37]]", "result": "166.03"}, "ts_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[83,52,37]]", "result": "770.99"}, "sessionrestore_no_auto_restore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[315,52,37]]", "result": "568.86"}}}
01:25:06 INFO - Return code: 0
01:25:06 INFO - # TBPL SUCCESS #
01:25:06 INFO - Running post-action listener: _resource_record_post_action
01:25:06 INFO - Running post-run listener: _resource_record_post_run
01:25:07 INFO - Total resource usage - Wall time: 201s; CPU: 10.0%; Read bytes: 47693312; Write bytes: 283195392; Read time: 199945; Write time: 360741
01:25:07 INFO - install - Wall time: 2s; CPU: 11.0%; Read bytes: 0; Write bytes: 87981568; Read time: 0; Write time: 84020
01:25:07 INFO - run-tests - Wall time: 199s; CPU: 10.0%; Read bytes: 47693312; Write bytes: 190595072; Read time: 199945; Write time: 272655
01:25:07 INFO - Running post-run listener: _upload_blobber_files
01:25:07 INFO - Blob upload gear active.
01:25:07 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
01:25:07 INFO - Copying logs to upload dir...
01:25:07 INFO - mkdir: C:\slave\test-pgo\build\upload\logs
program finished with exit code 0
elapsedTime=335.574000
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 5 mins, 42 secs) (at 2015-10-30 01:25:22.628764) =========
========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-30 01:25:22.632733) =========
'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f'
in dir C:\slave\test-pgo\properties (timeout 1200 secs)
watching logfiles {}
argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-047\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-047
COMSPEC=C:\WINDOWS\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Documents and Settings\cltbld.T-XP32-IX-047
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-047
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test-pgo\properties
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-047
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-047
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
build_url:https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.111000
build_url: 'https://queue.taskcluster.net/v1/task/PBSldvgpSbC0AV8FYp2Fsw/artifacts/public/build/firefox-43.0a2.en-US.win32.zip'
========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-30 01:25:23.452271) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-30 01:25:23.452680) =========
'rm' '-f' 'oauth.txt'
in dir C:\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-047\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-047
COMSPEC=C:\WINDOWS\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Documents and Settings\cltbld.T-XP32-IX-047
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-047
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-047
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-047
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.110000
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-30 01:25:23.993044) =========
========= Started reboot slave lost (results: 0, elapsed: 34 secs) (at 2015-10-30 01:25:23.993450) =========
========= Finished reboot slave lost (results: 0, elapsed: 34 secs) (at 2015-10-30 01:25:58.303491) =========