builder: fx-team_win8_64_test-dromaeojs
slave: t-w864-ix-115
starttime: 1445648262.45
results: success (0)
buildid: 20151023160116
builduid: 1f85eb4b8fb1499fa9e5ebcc3bf98531
revision: 335ea7a95492650e23c9ba14648d4f43620babab
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:42.454587) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:42.460157) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:42.460615) =========
'cd'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['cd']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
COMMONPROGRAMW6432=C:\Program Files\Common Files
COMPUTERNAME=T-W864-IX-115
COMSPEC=C:\windows\system32\cmd.exe
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld.T-W864-IX-115
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Local
LOGONSERVER=\\T-W864-IX-115
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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_ARCHITEW6432=AMD64
PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files (x86)
PROGRAMFILES(X86)=C:\Program Files (x86)
PROGRAMW6432=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W864-IX-115
USERDOMAIN_ROAMINGPROFILE=T-W864-IX-115
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W864-IX-115
WINDIR=C:\windows
XPCOM_DEBUG_BREAK=warn
using PTY: False
C:\slave\test
program finished with exit code 0
elapsedTime=0.795000
basedir: 'C:\\slave\\test'
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:43.275675) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:43.275996) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:43.364758) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:43.365191) =========
'rm' '-rf' 'properties'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'properties']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
COMMONPROGRAMW6432=C:\Program Files\Common Files
COMPUTERNAME=T-W864-IX-115
COMSPEC=C:\windows\system32\cmd.exe
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld.T-W864-IX-115
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Local
LOGONSERVER=\\T-W864-IX-115
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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_ARCHITEW6432=AMD64
PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files (x86)
PROGRAMFILES(X86)=C:\Program Files (x86)
PROGRAMW6432=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W864-IX-115
USERDOMAIN_ROAMINGPROFILE=T-W864-IX-115
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W864-IX-115
WINDIR=C:\windows
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.201000
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:43.581303) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:43.581638) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:43.582003) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:43.582287) =========
'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\. (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:\ProgramData
APPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
COMMONPROGRAMW6432=C:\Program Files\Common Files
COMPUTERNAME=T-W864-IX-115
COMSPEC=C:\windows\system32\cmd.exe
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld.T-W864-IX-115
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Local
LOGONSERVER=\\T-W864-IX-115
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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_ARCHITEW6432=AMD64
PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files (x86)
PROGRAMFILES(X86)=C:\Program Files (x86)
PROGRAMW6432=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W864-IX-115
USERDOMAIN_ROAMINGPROFILE=T-W864-IX-115
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W864-IX-115
WINDIR=C:\windows
XPCOM_DEBUG_BREAK=warn
using PTY: False
--17:57:42-- 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% 5.53 MB/s
17:57:43 (5.53 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.652000
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:44.248867) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2015-10-23 17:57:44.249227) =========
'rm' '-rf' 'scripts'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
COMMONPROGRAMW6432=C:\Program Files\Common Files
COMPUTERNAME=T-W864-IX-115
COMSPEC=C:\windows\system32\cmd.exe
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld.T-W864-IX-115
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Local
LOGONSERVER=\\T-W864-IX-115
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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_ARCHITEW6432=AMD64
PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files (x86)
PROGRAMFILES(X86)=C:\Program Files (x86)
PROGRAMW6432=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W864-IX-115
USERDOMAIN_ROAMINGPROFILE=T-W864-IX-115
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W864-IX-115
WINDIR=C:\windows
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=3.113000
========= Finished 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2015-10-23 17:57:53.937937) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-23 17:57:53.938316) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 335ea7a95492650e23c9ba14648d4f43620babab --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 335ea7a95492650e23c9ba14648d4f43620babab --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
COMMONPROGRAMW6432=C:\Program Files\Common Files
COMPUTERNAME=T-W864-IX-115
COMSPEC=C:\windows\system32\cmd.exe
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld.T-W864-IX-115
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Local
LOGONSERVER=\\T-W864-IX-115
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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_ARCHITEW6432=AMD64
PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files (x86)
PROGRAMFILES(X86)=C:\Program Files (x86)
PROGRAMW6432=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W864-IX-115
USERDOMAIN_ROAMINGPROFILE=T-W864-IX-115
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W864-IX-115
WINDIR=C:\windows
XPCOM_DEBUG_BREAK=warn
using PTY: False
2015-10-23 17:57:53,474 truncating revision to first 12 chars
2015-10-23 17:57:53,474 Setting DEBUG logging.
2015-10-23 17:57:53,474 attempt 1/10
2015-10-23 17:57:53,474 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/335ea7a95492?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-10-23 17:57:53,953 unpacking tar archive at: fx-team-335ea7a95492/testing/mozharness/
program finished with exit code 0
elapsedTime=1.500000
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-23 17:57:55.729355) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:55.729724) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:55.745929) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:55.746315) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 17:57:55.747348) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 36 secs) (at 2015-10-23 17:57:55.747663) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--system-bits' '64' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-Non-PGO'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
COMMONPROGRAMW6432=C:\Program Files\Common Files
COMPUTERNAME=T-W864-IX-115
COMSPEC=C:\windows\system32\cmd.exe
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld.T-W864-IX-115
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Local
LOGONSERVER=\\T-W864-IX-115
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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_ARCHITEW6432=AMD64
PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files (x86)
PROGRAMFILES(X86)=C:\Program Files (x86)
PROGRAMW6432=C:\Program Files
PROMPT=$P$G
PROPERTIES_FILE=C:\slave\test/buildprops.json
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W864-IX-115
USERDOMAIN_ROAMINGPROFILE=T-W864-IX-115
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W864-IX-115
WINDIR=C:\windows
XPCOM_DEBUG_BREAK=warn
using PTY: False
17:57:55 INFO - MultiFileLogger online at 20151023 17:57:55 in C:\slave\test
17:57:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO
17:57:55 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
17:57:55 INFO - {'append_to_log': False,
17:57:55 INFO - 'base_work_dir': 'C:\\slave\\test',
17:57:55 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO',
17:57:55 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
17:57:55 INFO - 'branch': 'Fx-Team-Non-PGO',
17:57:55 INFO - 'buildbot_json_path': 'buildprops.json',
17:57:55 INFO - 'config_files': ('talos/windows_config.py',),
17:57:55 INFO - 'default_actions': ('clobber',
17:57:55 INFO - 'read-buildbot-config',
17:57:55 INFO - 'download-and-extract',
17:57:55 INFO - 'populate-webroot',
17:57:55 INFO - 'create-virtualenv',
17:57:55 INFO - 'install',
17:57:55 INFO - 'run-tests'),
17:57:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
17:57:55 INFO - 'download_minidump_stackwalk': True,
17:57:55 INFO - 'download_symbols': 'ondemand',
17:57:55 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
17:57:55 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
17:57:55 INFO - 'hg': 'c:/mozilla-build/hg/hg',
17:57:55 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
17:57:55 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
17:57:55 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
17:57:55 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
17:57:55 INFO - 'C:/mozilla-build/tooltool.py'),
17:57:55 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
17:57:55 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
17:57:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
17:57:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
17:57:55 INFO - 'installer_path': 'installer.exe',
17:57:55 INFO - 'log_level': 'info',
17:57:55 INFO - 'log_name': 'talos',
17:57:55 INFO - 'log_to_console': True,
17:57:55 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
17:57:55 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
17:57:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
17:57:55 INFO - 'opt_config_files': (),
17:57:55 INFO - 'pip_index': False,
17:57:55 INFO - 'sps_profile': False,
17:57:55 INFO - 'sps_profile_interval': 0,
17:57:55 INFO - 'suite': 'dromaeojs',
17:57:55 INFO - 'system_bits': '64',
17:57:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
17:57:55 INFO - 'title': 'T-W864-IX-115',
17:57:55 INFO - 'use_talos_json': True,
17:57:55 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
17:57:55 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
17:57:55 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
17:57:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
17:57:55 INFO - 'work_dir': 'build'}
17:57:55 INFO - #####
17:57:55 INFO - ##### Running clobber step.
17:57:55 INFO - #####
17:57:55 INFO - Running pre-action listener: _resource_record_pre_action
17:57:55 INFO - Running main action method: clobber
17:57:55 INFO - rmtree: C:\slave\test\build
17:57:55 INFO - Using _rmtree_windows ...
17:57:55 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
18:00:10 INFO - Running post-action listener: _resource_record_post_action
18:00:10 INFO - #####
18:00:10 INFO - ##### Running read-buildbot-config step.
18:00:10 INFO - #####
18:00:10 INFO - Running pre-action listener: _resource_record_pre_action
18:00:10 INFO - Running main action method: read_buildbot_config
18:00:10 INFO - Using buildbot properties:
18:00:10 INFO - {
18:00:10 INFO - "properties": {
18:00:10 INFO - "buildnumber": 795,
18:00:10 INFO - "product": "firefox",
18:00:10 INFO - "script_repo_revision": "production",
18:00:10 INFO - "builddir": "fx-team_win8_64_test-dromaeojs",
18:00:10 INFO - "repository": "",
18:00:10 INFO - "buildername": "Windows 8 64-bit fx-team talos dromaeojs",
18:00:10 INFO - "buildid": "20151023160116",
18:00:10 INFO - "slavename": "t-w864-ix-115",
18:00:10 INFO - "pgo_build": "False",
18:00:10 INFO - "basedir": "C:\\slave\\test",
18:00:10 INFO - "project": "",
18:00:10 INFO - "platform": "win8_64",
18:00:10 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
18:00:10 INFO - "slavebuilddir": "test",
18:00:10 INFO - "scheduler": "tests-fx-team-win64-talos",
18:00:10 INFO - "branch": "fx-team",
18:00:10 INFO - "repo_path": "integration/fx-team",
18:00:10 INFO - "stage_platform": "win64",
18:00:10 INFO - "builduid": "1f85eb4b8fb1499fa9e5ebcc3bf98531",
18:00:10 INFO - "revision": "335ea7a95492650e23c9ba14648d4f43620babab"
18:00:10 INFO - },
18:00:10 INFO - "sourcestamp": {
18:00:10 INFO - "repository": "",
18:00:10 INFO - "hasPatch": false,
18:00:10 INFO - "project": "",
18:00:10 INFO - "branch": "fx-team-win64-talos",
18:00:10 INFO - "changes": [
18:00:10 INFO - {
18:00:10 INFO - "category": null,
18:00:10 INFO - "files": [
18:00:10 INFO - {
18:00:10 INFO - "url": null,
18:00:10 INFO - "name": "https://queue.taskcluster.net/v1/task/i2_5MrvhS3u7YgjEFjp4fQ/artifacts/public/build/firefox-44.0a1.en-US.win64.zip"
18:00:10 INFO - }
18:00:10 INFO - ],
18:00:10 INFO - "repository": "",
18:00:10 INFO - "rev": "0c8bf0c2cc6a3383e897f65845461d7c0f169eac",
18:00:10 INFO - "who": "kwierso@gmail.com",
18:00:10 INFO - "when": 1445647337,
18:00:10 INFO - "number": 6569042,
18:00:10 INFO - "comments": "Merge m-c to fx-team, a=merge",
18:00:10 INFO - "project": "",
18:00:10 INFO - "at": "Fri 23 Oct 2015 17:42:17",
18:00:10 INFO - "branch": "fx-team-win64-talos",
18:00:10 INFO - "revlink": "",
18:00:10 INFO - "properties": [
18:00:10 INFO - [
18:00:10 INFO - "buildid",
18:00:10 INFO - "20151023155652",
18:00:10 INFO - "Change"
18:00:10 INFO - ],
18:00:10 INFO - [
18:00:10 INFO - "builduid",
18:00:10 INFO - "7a19177c46d54de980ac3003a890a2fd",
18:00:10 INFO - "Change"
18:00:10 INFO - ],
18:00:10 INFO - [
18:00:10 INFO - "pgo_build",
18:00:10 INFO - "False",
18:00:10 INFO - "Change"
18:00:10 INFO - ]
18:00:10 INFO - ],
18:00:10 INFO - "revision": "0c8bf0c2cc6a3383e897f65845461d7c0f169eac"
18:00:10 INFO - },
18:00:10 INFO - {
18:00:10 INFO - "category": null,
18:00:10 INFO - "files": [
18:00:10 INFO - {
18:00:10 INFO - "url": null,
18:00:10 INFO - "name": "https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip"
18:00:10 INFO - }
18:00:10 INFO - ],
18:00:10 INFO - "repository": "",
18:00:10 INFO - "rev": "335ea7a95492650e23c9ba14648d4f43620babab",
18:00:10 INFO - "who": "jsantell@mozilla.com",
18:00:10 INFO - "when": 1445647907,
18:00:10 INFO - "number": 6569076,
18:00:10 INFO - "comments": "Bug 960776 - Render a tree widget to display heap data. r=fitzgen\n",
18:00:10 INFO - "project": "",
18:00:10 INFO - "at": "Fri 23 Oct 2015 17:51:47",
18:00:10 INFO - "branch": "fx-team-win64-talos",
18:00:10 INFO - "revlink": "",
18:00:10 INFO - "properties": [
18:00:10 INFO - [
18:00:10 INFO - "buildid",
18:00:10 INFO - "20151023160116",
18:00:10 INFO - "Change"
18:00:10 INFO - ],
18:00:10 INFO - [
18:00:10 INFO - "builduid",
18:00:10 INFO - "1f85eb4b8fb1499fa9e5ebcc3bf98531",
18:00:10 INFO - "Change"
18:00:10 INFO - ],
18:00:10 INFO - [
18:00:10 INFO - "pgo_build",
18:00:10 INFO - "False",
18:00:10 INFO - "Change"
18:00:10 INFO - ]
18:00:10 INFO - ],
18:00:10 INFO - "revision": "335ea7a95492650e23c9ba14648d4f43620babab"
18:00:10 INFO - }
18:00:10 INFO - ],
18:00:10 INFO - "revision": "335ea7a95492650e23c9ba14648d4f43620babab"
18:00:10 INFO - }
18:00:10 INFO - }
18:00:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip.
18:00:10 INFO - Running post-action listener: _resource_record_post_action
18:00:10 INFO - #####
18:00:10 INFO - ##### Running download-and-extract step.
18:00:10 INFO - #####
18:00:10 INFO - Running pre-action listener: _resource_record_pre_action
18:00:10 INFO - Running main action method: download_and_extract
18:00:10 INFO - mkdir: C:\slave\test\build\tests
18:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:00:10 INFO - https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
18:00:11 INFO - trying https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/test_packages.json
18:00:11 INFO - Downloading https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json
18:00:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1
18:00:11 INFO - Downloaded 1221 bytes.
18:00:11 INFO - Reading from file C:\slave\test\build\test_packages.json
18:00:11 INFO - Using the following test package requirements:
18:00:11 INFO - {u'common': [u'firefox-44.0a1.en-US.win64.common.tests.zip'],
18:00:11 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip',
18:00:11 INFO - u'firefox-44.0a1.en-US.win64.cppunittest.tests.zip'],
18:00:11 INFO - u'jittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip',
18:00:11 INFO - u'jsshell-win64.zip'],
18:00:11 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win64.common.tests.zip',
18:00:11 INFO - u'firefox-44.0a1.en-US.win64.mochitest.tests.zip'],
18:00:11 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win64.common.tests.zip'],
18:00:11 INFO - u'reftest': [u'firefox-44.0a1.en-US.win64.common.tests.zip',
18:00:11 INFO - u'firefox-44.0a1.en-US.win64.reftest.tests.zip'],
18:00:11 INFO - u'talos': [u'firefox-44.0a1.en-US.win64.common.tests.zip',
18:00:11 INFO - u'firefox-44.0a1.en-US.win64.talos.tests.zip'],
18:00:11 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win64.common.tests.zip',
18:00:11 INFO - u'firefox-44.0a1.en-US.win64.web-platform.tests.zip'],
18:00:11 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win64.common.tests.zip'],
18:00:11 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win64.common.tests.zip',
18:00:11 INFO - u'firefox-44.0a1.en-US.win64.xpcshell.tests.zip']}
18:00:11 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win64.common.tests.zip'] for test suite category: common
18:00: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')]}
18:00:11 INFO - https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net
18:00:11 INFO - trying https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip
18:00:11 INFO - Downloading https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win64.common.tests.zip
18:00:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'}, attempt #1
ProcessManager NOT managing child processes
18:00:14 INFO - Downloaded 18102633 bytes.
18:00:14 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] in C:\slave\test\build\tests
18:00:14 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win64.common.tests.zip
18:00:14 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] with output_timeout 1760
ProcessManager UNABLE to use job objects to manage child processes
18:00:42 INFO - Return code: 0
18:00:42 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win64.common.tests.zip', u'firefox-44.0a1.en-US.win64.talos.tests.zip'] for test suite category: talos
18:00:42 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')]}
18:00:42 INFO - https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net
18:00:42 INFO - trying https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip
18:00:42 INFO - Downloading https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win64.common.tests.zip
18:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'}, attempt #1
ProcessManager NOT managing child processes
18:00:45 INFO - Downloaded 18102633 bytes.
18:00:45 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] in C:\slave\test\build\tests
18:00:45 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win64.common.tests.zip
18:00:45 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] with output_timeout 1760
ProcessManager UNABLE to use job objects to manage child processes
18:01:08 INFO - Return code: 0
18:01: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')]}
18:01:08 INFO - https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip matches https://queue.taskcluster.net
18:01:08 INFO - trying https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip
18:01:08 INFO - Downloading https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win64.talos.tests.zip
18:01:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.talos.tests.zip'}, attempt #1
ProcessManager NOT managing child processes
18:01:11 INFO - Downloaded 11131307 bytes.
18:01:11 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.talos.tests.zip'] in C:\slave\test\build\tests
18:01:11 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win64.talos.tests.zip
18:01:11 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.talos.tests.zip'] with output_timeout 1760
ProcessManager UNABLE to use job objects to manage child processes
18:01:11 INFO - Return code: 0
18:01: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')]}
18:01:11 INFO - https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip matches https://queue.taskcluster.net
18:01:11 INFO - trying https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip
18:01:11 INFO - Downloading https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip to C:\slave\test\build\firefox-44.0a1.en-US.win64.zip
18:01:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.zip'}, attempt #1
18:01:16 INFO - Downloaded 54765281 bytes.
18:01:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip
18:01:16 INFO - mkdir: C:\slave\test\properties
18:01:16 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
18:01:16 INFO - Writing to file C:\slave\test\properties\build_url
18:01:16 INFO - Contents:
18:01:16 INFO - build_url:https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip
18:01:16 INFO - Running post-action listener: _resource_record_post_action
18:01:16 INFO - Running post-action listener: set_extra_try_arguments
18:01:16 INFO - #####
18:01:16 INFO - ##### Running populate-webroot step.
18:01:16 INFO - #####
18:01:16 INFO - Running pre-action listener: _resource_record_pre_action
18:01:16 INFO - Running main action method: populate_webroot
18:01:16 INFO - Guessing talos json url...
18:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:01:16 INFO - https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt matches https://queue.taskcluster.net
18:01:16 INFO - trying https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt
18:01:16 INFO - Downloading https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt to C:\slave\test\build\firefox-44.0a1.en-US.win64.txt
18:01:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt', 'file_name': 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.txt'}, attempt #1
18:01:17 INFO - Downloaded 103 bytes.
18:01:17 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win64.txt
18:01:17 INFO - Contents:
18:01:17 INFO - 20151023160116
18:01:17 INFO - https://hg.mozilla.org/integration/fx-team/rev/335ea7a95492650e23c9ba14648d4f43620babab
18:01:17 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')]}
18:01:17 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/335ea7a954/testing/talos/talos.json
18:01:17 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/335ea7a954/testing/talos/talos.json to C:\slave\test\build\talos.json
18:01:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/335ea7a954/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1
18:01:17 INFO - Downloaded 4264 bytes.
18:01:17 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
18:01:17 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
18:01:17 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
18:01:17 INFO - u'../fennec_ids.txt'],
18:01:17 INFO - u'tests': [u'tcheck2']},
18:01:17 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
18:01:17 INFO - u'--tppagecycles',
18:01:17 INFO - u'7'],
18:01:17 INFO - u'tests': [u'tsvgm']}},
18:01:17 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
18:01:17 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
18:01:17 INFO - u'tests': [u'tresize', u'tcanvasmark']},
18:01:17 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
18:01:17 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
18:01:17 INFO - u'tests': [u'dromaeo_css',
18:01:17 INFO - u'kraken',
18:01:17 INFO - u'v8_7']},
18:01:17 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:01:17 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
18:01:17 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:01:17 INFO - u'talos_options': [u'--e10s'],
18:01:17 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
18:01:17 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:01:17 INFO - u'tests': [u'damp', u'tps']},
18:01:17 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:01:17 INFO - u'talos_options': [u'--e10s'],
18:01:17 INFO - u'tests': [u'damp', u'tps']},
18:01:17 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
18:01:17 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
18:01:17 INFO - u'other': {u'tests': [u'a11yr',
18:01:17 INFO - u'ts_paint',
18:01:17 INFO - u'tpaint',
18:01:17 INFO - u'sessionrestore',
18:01:17 INFO - u'sessionrestore_no_auto_restore']},
18:01:17 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
18:01:17 INFO - u'tests': [u'a11yr',
18:01:17 INFO - u'ts_paint',
18:01:17 INFO - u'tpaint',
18:01:17 INFO - u'sessionrestore',
18:01:17 INFO - u'sessionrestore_no_auto_restore']},
18:01:17 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
18:01:17 INFO - u'tests': [u'a11yr',
18:01:17 INFO - u'ts_paint',
18:01:17 INFO - u'tpaint',
18:01:17 INFO - u'sessionrestore',
18:01:17 INFO - u'sessionrestore_no_auto_restore']},
18:01:17 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
18:01:17 INFO - u'tests': [u'a11yr',
18:01:17 INFO - u'ts_paint',
18:01:17 INFO - u'tpaint',
18:01:17 INFO - u'sessionrestore',
18:01:17 INFO - u'sessionrestore_no_auto_restore']},
18:01:17 INFO - u'other_l64': {u'tests': [u'a11yr',
18:01:17 INFO - u'ts_paint',
18:01:17 INFO - u'tpaint',
18:01:17 INFO - u'sessionrestore',
18:01:17 INFO - u'sessionrestore_no_auto_restore']},
18:01:17 INFO - u'other_nol64': {u'tests': [u'a11yr',
18:01:17 INFO - u'ts_paint',
18:01:17 INFO - u'tpaint',
18:01:17 INFO - u'sessionrestore',
18:01:17 INFO - u'sessionrestore_no_auto_restore']},
18:01:17 INFO - u'svgr': {u'tests': [u'tsvgx',
18:01:17 INFO - u'tsvgr_opacity',
18:01:17 INFO - u'tart',
18:01:17 INFO - u'tscrollx',
18:01:17 INFO - u'cart']},
18:01:17 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
18:01:17 INFO - u'tests': [u'tsvgx',
18:01:17 INFO - u'tsvgr_opacity',
18:01:17 INFO - u'tart',
18:01:17 INFO - u'tscrollx',
18:01:17 INFO - u'cart']},
18:01:17 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:01:17 INFO - u'tests': [u'tp5o']},
18:01:17 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:01:17 INFO - u'talos_options': [u'--e10s'],
18:01:17 INFO - u'tests': [u'tp5o']},
18:01:17 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:01:17 INFO - u'talos_options': [u'--xperf_path',
18:01:17 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
18:01:17 INFO - u'tests': [u'tp5n']},
18:01:17 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:01:17 INFO - u'talos_options': [u'--e10s',
18:01:17 INFO - u'--xperf_path',
18:01:17 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
18:01:17 INFO - u'C:/slave/talos-data/talos/xperf.config'],
18:01:17 INFO - u'tests': [u'tp5n']}},
18:01:17 INFO - u'talos.zip': {u'path': u'',
18:01:17 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
18:01:17 INFO - Running post-action listener: _resource_record_post_action
18:01:17 INFO - #####
18:01:17 INFO - ##### Running create-virtualenv step.
18:01:17 INFO - #####
18:01:17 INFO - Running pre-action listener: _resource_record_pre_action
18:01:17 INFO - Running main action method: create_virtualenv
18:01:17 INFO - Creating virtualenv C:\slave\test\build/venv
18:01:17 INFO - mkdir: C:\slave\test\build\venv\Scripts
18:01:17 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
18:01:17 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'!
18:01:17 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build
18:01:17 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
18:01:20 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
18:01:23 INFO - Installing distribute......................................................................................................................................................................................done.
18:01:24 INFO - Return code: 0
18:01:24 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
18:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:01:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:01:24 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\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 0x02312B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022D1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023CF9E8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D08B48>, '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 0x01D2A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027B3660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-115', 'USERDOMAIN': 'T-W864-IX-115', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-115', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
18:01:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\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\build
18:01:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\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
18:01:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:01:24 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming',
18:01:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
18:01:24 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
18:01:24 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
18:01:24 INFO - 'COMPUTERNAME': 'T-W864-IX-115',
18:01:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:01:24 INFO - 'DCLOCATION': 'SCL3',
18:01:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:01:24 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:01:24 INFO - 'HOMEDRIVE': 'C:',
18:01:24 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115',
18:01:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:01:24 INFO - 'KTS_VERSION': '1.19c',
18:01:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local',
18:01:24 INFO - 'LOGONSERVER': '\\\\T-W864-IX-115',
18:01:24 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:01:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:01:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:01:24 INFO - 'MOZ_NO_REMOTE': '1',
18:01:24 INFO - 'NO_EM_RESTART': '1',
18:01:24 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:01:24 INFO - 'OS': 'Windows_NT',
18:01:24 INFO - 'OURDRIVE': 'C:',
18:01:24 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg',
18:01:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:01:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:01:24 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64',
18:01:24 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
18:01:24 INFO - 'PROCESSOR_LEVEL': '6',
18:01:24 INFO - 'PROCESSOR_REVISION': '1e05',
18:01:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:01:24 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)',
18:01:24 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
18:01:24 INFO - 'PROGRAMW6432': 'C:\\Program Files',
18:01:24 INFO - 'PROMPT': '$P$G',
18:01:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:01:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:01:24 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:01:24 INFO - 'PWD': 'C:\\slave\\test',
18:01:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:01:24 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:01:24 INFO - 'SYSTEMDRIVE': 'C:',
18:01:24 INFO - 'SYSTEMROOT': 'C:\\windows',
18:01:24 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:24 INFO - 'TEST1': 'testie',
18:01:24 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:24 INFO - 'USERDOMAIN': 'T-W864-IX-115',
18:01:24 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115',
18:01:24 INFO - 'USERNAME': 'cltbld',
18:01:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115',
18:01:24 INFO - 'WINDIR': 'C:\\windows',
18:01:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:01:25 INFO - Ignoring indexes: http://pypi.python.org/simple/
18:01:25 INFO - Downloading/unpacking pip>=1.5
18:01:25 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache
18:01:30 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz
18:01:30 INFO - Running setup.py egg_info for package pip
18:01:30 INFO - warning: no files found matching 'pip\cacert.pem'
18:01:30 INFO - warning: no files found matching '*.html' under directory 'docs'
18:01:30 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
18:01:30 INFO - no previously-included directories found matching 'docs\_build\_sources'
18:01:30 INFO - Installing collected packages: pip
18:01:30 INFO - Found existing installation: pip 0.8.2
18:01:30 INFO - Uninstalling pip:
18:01:30 INFO - Successfully uninstalled pip
18:01:30 INFO - Running setup.py install for pip
18:01:30 INFO - warning: no files found matching 'pip\cacert.pem'
18:01:30 INFO - warning: no files found matching '*.html' under directory 'docs'
18:01:30 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
18:01:30 INFO - no previously-included directories found matching 'docs\_build\_sources'
18:01:30 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
18:01:30 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
18:01:30 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
18:01:30 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
18:01:30 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
18:01:30 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
18:01:30 INFO - Successfully installed pip
18:01:30 INFO - Cleaning up...
18:01:30 INFO - Return code: 0
18:01:30 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv
18:01:30 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')]}
18:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:01:30 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')]}
18:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:01:30 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\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 0x02312B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022D1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023CF9E8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D08B48>, '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 0x01D2A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027B3660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-115', 'USERDOMAIN': 'T-W864-IX-115', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-115', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
18:01:30 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\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\build
18:01:30 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\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
18:01:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:01:30 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming',
18:01:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
18:01:30 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
18:01:30 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
18:01:30 INFO - 'COMPUTERNAME': 'T-W864-IX-115',
18:01:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:01:30 INFO - 'DCLOCATION': 'SCL3',
18:01:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:01:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:01:30 INFO - 'HOMEDRIVE': 'C:',
18:01:30 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115',
18:01:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:01:30 INFO - 'KTS_VERSION': '1.19c',
18:01:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local',
18:01:30 INFO - 'LOGONSERVER': '\\\\T-W864-IX-115',
18:01:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:01:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:01:30 INFO - 'MOZ_NO_REMOTE': '1',
18:01:30 INFO - 'NO_EM_RESTART': '1',
18:01:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:01:30 INFO - 'OS': 'Windows_NT',
18:01:30 INFO - 'OURDRIVE': 'C:',
18:01:30 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg',
18:01:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:01:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:01:30 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64',
18:01:30 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
18:01:30 INFO - 'PROCESSOR_LEVEL': '6',
18:01:30 INFO - 'PROCESSOR_REVISION': '1e05',
18:01:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:01:30 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)',
18:01:30 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
18:01:30 INFO - 'PROGRAMW6432': 'C:\\Program Files',
18:01:30 INFO - 'PROMPT': '$P$G',
18:01:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:01:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:01:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:01:30 INFO - 'PWD': 'C:\\slave\\test',
18:01:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:01:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:01:30 INFO - 'SYSTEMDRIVE': 'C:',
18:01:30 INFO - 'SYSTEMROOT': 'C:\\windows',
18:01:30 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:30 INFO - 'TEST1': 'testie',
18:01:30 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:30 INFO - 'USERDOMAIN': 'T-W864-IX-115',
18:01:30 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115',
18:01:30 INFO - 'USERNAME': 'cltbld',
18:01:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115',
18:01:30 INFO - 'WINDIR': 'C:\\windows',
18:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:01:31 INFO - Downloading/unpacking psutil>=0.7.1
18:01: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
18:01: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
18:01:32 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl
18:01:32 INFO - Installing collected packages: psutil
18:01:32 INFO - Successfully installed psutil
18:01:32 INFO - Cleaning up...
18:01:33 INFO - Return code: 0
18:01:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv
18:01: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')]}
18:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:01: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')]}
18:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:01:33 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\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 0x02312B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022D1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023CF9E8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D08B48>, '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 0x01D2A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027B3660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-115', 'USERDOMAIN': 'T-W864-IX-115', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-115', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
18:01:33 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\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\build
18:01:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\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
18:01:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:01:33 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming',
18:01:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
18:01:33 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
18:01:33 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
18:01:33 INFO - 'COMPUTERNAME': 'T-W864-IX-115',
18:01:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:01:33 INFO - 'DCLOCATION': 'SCL3',
18:01:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:01:33 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:01:33 INFO - 'HOMEDRIVE': 'C:',
18:01:33 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115',
18:01:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:01:33 INFO - 'KTS_VERSION': '1.19c',
18:01:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local',
18:01:33 INFO - 'LOGONSERVER': '\\\\T-W864-IX-115',
18:01:33 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:01:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:01:33 INFO - 'MOZ_NO_REMOTE': '1',
18:01:33 INFO - 'NO_EM_RESTART': '1',
18:01:33 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:01:33 INFO - 'OS': 'Windows_NT',
18:01:33 INFO - 'OURDRIVE': 'C:',
18:01:33 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg',
18:01:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:01:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:01:33 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64',
18:01:33 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
18:01:33 INFO - 'PROCESSOR_LEVEL': '6',
18:01:33 INFO - 'PROCESSOR_REVISION': '1e05',
18:01:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:01:33 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)',
18:01:33 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
18:01:33 INFO - 'PROGRAMW6432': 'C:\\Program Files',
18:01:33 INFO - 'PROMPT': '$P$G',
18:01:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:01:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:01:33 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:01:33 INFO - 'PWD': 'C:\\slave\\test',
18:01:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:01:33 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:01:33 INFO - 'SYSTEMDRIVE': 'C:',
18:01:33 INFO - 'SYSTEMROOT': 'C:\\windows',
18:01:33 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:33 INFO - 'TEST1': 'testie',
18:01:33 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:33 INFO - 'USERDOMAIN': 'T-W864-IX-115',
18:01:33 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115',
18:01:33 INFO - 'USERNAME': 'cltbld',
18:01:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115',
18:01:33 INFO - 'WINDIR': 'C:\\windows',
18:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:01:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
18:01: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
18:01: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
18:01:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz
18:01:34 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz
18:01:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
18:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0)
18:01:34 INFO - Installing collected packages: mozsystemmonitor
18:01:34 INFO - Running setup.py install for mozsystemmonitor
18:01:34 INFO - Successfully installed mozsystemmonitor
18:01:34 INFO - Cleaning up...
18:01:34 INFO - Return code: 0
18:01:34 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
18:01: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')]}
18:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:01: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')]}
18:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:01:34 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\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 0x02312B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022D1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023CF9E8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D08B48>, '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 0x01D2A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027B3660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-115', 'USERDOMAIN': 'T-W864-IX-115', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-115', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
18:01:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\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\build
18:01:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\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
18:01:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:01:34 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming',
18:01:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
18:01:34 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
18:01:34 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
18:01:34 INFO - 'COMPUTERNAME': 'T-W864-IX-115',
18:01:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:01:34 INFO - 'DCLOCATION': 'SCL3',
18:01:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:01:34 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:01:34 INFO - 'HOMEDRIVE': 'C:',
18:01:34 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115',
18:01:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:01:34 INFO - 'KTS_VERSION': '1.19c',
18:01:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local',
18:01:34 INFO - 'LOGONSERVER': '\\\\T-W864-IX-115',
18:01:34 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:01:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:01:34 INFO - 'MOZ_NO_REMOTE': '1',
18:01:34 INFO - 'NO_EM_RESTART': '1',
18:01:34 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:01:34 INFO - 'OS': 'Windows_NT',
18:01:34 INFO - 'OURDRIVE': 'C:',
18:01:34 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg',
18:01:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:01:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:01:34 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64',
18:01:34 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
18:01:34 INFO - 'PROCESSOR_LEVEL': '6',
18:01:34 INFO - 'PROCESSOR_REVISION': '1e05',
18:01:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:01:34 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)',
18:01:34 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
18:01:34 INFO - 'PROGRAMW6432': 'C:\\Program Files',
18:01:34 INFO - 'PROMPT': '$P$G',
18:01:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:01:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:01:34 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:01:34 INFO - 'PWD': 'C:\\slave\\test',
18:01:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:01:34 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:01:34 INFO - 'SYSTEMDRIVE': 'C:',
18:01:34 INFO - 'SYSTEMROOT': 'C:\\windows',
18:01:34 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:34 INFO - 'TEST1': 'testie',
18:01:34 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:34 INFO - 'USERDOMAIN': 'T-W864-IX-115',
18:01:34 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115',
18:01:34 INFO - 'USERNAME': 'cltbld',
18:01:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115',
18:01:34 INFO - 'WINDIR': 'C:\\windows',
18:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:01:35 INFO - Downloading/unpacking blobuploader==1.2.4
18:01:35 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
18:01:35 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
18:01:36 INFO - Downloading blobuploader-1.2.4.tar.gz
18:01:36 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz
18:01:36 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
18:01:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
18:01:36 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
18:01:36 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
18:01:36 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz
18:01:36 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
18:01:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
18:01:37 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
18:01:37 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
18:01:37 INFO - Downloading docopt-0.6.1.tar.gz
18:01:37 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz
18:01:37 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
18:01:37 INFO - Installing collected packages: blobuploader, requests, docopt
18:01:37 INFO - Running setup.py install for blobuploader
18:01:37 INFO - Running setup.py install for requests
18:01:38 INFO - Running setup.py install for docopt
18:01:38 INFO - Successfully installed blobuploader requests docopt
18:01:38 INFO - Cleaning up...
18:01:39 INFO - Return code: 0
18:01:39 INFO - Installing None into virtualenv C:\slave\test\build/venv
18:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:01:39 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02312B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022D1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023CF9E8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D08B48>, '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 0x01D2A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027B3660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-115', 'USERDOMAIN': 'T-W864-IX-115', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-115', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
18:01:39 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
18:01:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
18:01:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:01:39 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming',
18:01:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
18:01:39 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
18:01:39 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
18:01:39 INFO - 'COMPUTERNAME': 'T-W864-IX-115',
18:01:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:01:39 INFO - 'DCLOCATION': 'SCL3',
18:01:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:01:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:01:39 INFO - 'HOMEDRIVE': 'C:',
18:01:39 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115',
18:01:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:01:39 INFO - 'KTS_VERSION': '1.19c',
18:01:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local',
18:01:39 INFO - 'LOGONSERVER': '\\\\T-W864-IX-115',
18:01:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:01:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:01:39 INFO - 'MOZ_NO_REMOTE': '1',
18:01:39 INFO - 'NO_EM_RESTART': '1',
18:01:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:01:39 INFO - 'OS': 'Windows_NT',
18:01:39 INFO - 'OURDRIVE': 'C:',
18:01:39 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg',
18:01:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:01:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:01:39 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64',
18:01:39 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
18:01:39 INFO - 'PROCESSOR_LEVEL': '6',
18:01:39 INFO - 'PROCESSOR_REVISION': '1e05',
18:01:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:01:39 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)',
18:01:39 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
18:01:39 INFO - 'PROGRAMW6432': 'C:\\Program Files',
18:01:39 INFO - 'PROMPT': '$P$G',
18:01:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:01:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:01:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:01:39 INFO - 'PWD': 'C:\\slave\\test',
18:01:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:01:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:01:39 INFO - 'SYSTEMDRIVE': 'C:',
18:01:39 INFO - 'SYSTEMROOT': 'C:\\windows',
18:01:39 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:39 INFO - 'TEST1': 'testie',
18:01:39 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:39 INFO - 'USERDOMAIN': 'T-W864-IX-115',
18:01:39 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115',
18:01:39 INFO - 'USERNAME': 'cltbld',
18:01:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115',
18:01:39 INFO - 'WINDIR': 'C:\\windows',
18:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:01:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
18:01:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-wavtgx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
18:01:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
18:01:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-wlmckh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
18:01:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
18:01:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-oal_s_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
18:01:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-lkcyvn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
18:01:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-avxugz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
18:01:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-fnvzy5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
18:01:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-bkuhik-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
18:01:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-c4znbt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
18:01:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-b0nyja-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
18:01:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-6r788d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
18:01:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-f72nr6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
18:01:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-cxwn7n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
18:01:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
18:01:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ngvhwi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
18:01:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
18:01:41 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ervkmn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
18:01:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
18:01:41 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-svf8yw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
18:01:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
18:01:41 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-8l4mhl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
18:01:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
18:01:41 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-2dbcdu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
18:01:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
18:01:41 INFO - Running setup.py install for manifestparser
18:01:41 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
18:01:41 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
18:01:42 INFO - Running setup.py install for mozcrash
18:01:42 INFO - Running setup.py install for mozdebug
18:01:43 INFO - Running setup.py install for mozdevice
18:01:43 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
18:01:43 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
18:01:43 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
18:01:43 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
18:01:43 INFO - Running setup.py install for mozfile
18:01:44 INFO - Running setup.py install for mozhttpd
18:01:44 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
18:01:44 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
18:01:44 INFO - Running setup.py install for mozinfo
18:01:44 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
18:01:44 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
18:01:45 INFO - Running setup.py install for mozInstall
18:01:45 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
18:01:45 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
18:01:45 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
18:01:45 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
18:01:45 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
18:01:45 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
18:01:45 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
18:01:45 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
18:01:45 INFO - Running setup.py install for mozleak
18:01:45 INFO - Running setup.py install for mozlog
18:01:46 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
18:01:46 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
18:01:46 INFO - Running setup.py install for moznetwork
18:01:46 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
18:01:46 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
18:01:47 INFO - Running setup.py install for mozprocess
18:01:47 INFO - Running setup.py install for mozprofile
18:01:48 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
18:01:48 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
18:01:48 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
18:01:48 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
18:01:48 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
18:01:48 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
18:01:48 INFO - Running setup.py install for mozrunner
18:01:48 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
18:01:48 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
18:01:49 INFO - Running setup.py install for mozscreenshot
18:01:49 INFO - Running setup.py install for moztest
18:01:49 INFO - Running setup.py install for mozversion
18:01:49 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
18:01:49 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
18:01:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
18:01:50 INFO - Cleaning up...
18:01:50 INFO - Return code: 0
18:01:50 INFO - Installing None into virtualenv C:\slave\test\build/venv
18:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:01:50 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02312B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022D1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023CF9E8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D08B48>, '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 0x01D2A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027B3660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-115', 'USERDOMAIN': 'T-W864-IX-115', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-115', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
18:01:50 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
18:01:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
18:01:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:01:50 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming',
18:01:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
18:01:50 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
18:01:50 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
18:01:50 INFO - 'COMPUTERNAME': 'T-W864-IX-115',
18:01:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:01:50 INFO - 'DCLOCATION': 'SCL3',
18:01:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:01:50 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:01:50 INFO - 'HOMEDRIVE': 'C:',
18:01:50 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115',
18:01:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:01:50 INFO - 'KTS_VERSION': '1.19c',
18:01:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local',
18:01:50 INFO - 'LOGONSERVER': '\\\\T-W864-IX-115',
18:01:50 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:01:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:01:50 INFO - 'MOZ_NO_REMOTE': '1',
18:01:50 INFO - 'NO_EM_RESTART': '1',
18:01:50 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:01:50 INFO - 'OS': 'Windows_NT',
18:01:50 INFO - 'OURDRIVE': 'C:',
18:01:50 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg',
18:01:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:01:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:01:50 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64',
18:01:50 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
18:01:50 INFO - 'PROCESSOR_LEVEL': '6',
18:01:50 INFO - 'PROCESSOR_REVISION': '1e05',
18:01:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:01:50 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)',
18:01:50 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
18:01:50 INFO - 'PROGRAMW6432': 'C:\\Program Files',
18:01:50 INFO - 'PROMPT': '$P$G',
18:01:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:01:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:01:50 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:01:50 INFO - 'PWD': 'C:\\slave\\test',
18:01:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:01:50 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:01:50 INFO - 'SYSTEMDRIVE': 'C:',
18:01:50 INFO - 'SYSTEMROOT': 'C:\\windows',
18:01:50 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:50 INFO - 'TEST1': 'testie',
18:01:50 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:50 INFO - 'USERDOMAIN': 'T-W864-IX-115',
18:01:50 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115',
18:01:50 INFO - 'USERNAME': 'cltbld',
18:01:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115',
18:01:50 INFO - 'WINDIR': 'C:\\windows',
18:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:01:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
18:01:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-mf6ahf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
18:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
18:01:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
18:01:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-h8ujxc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
18:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
18:01:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
18:01:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-q6n7ub-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
18:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
18:01:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
18:01:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ptlyo7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
18:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
18:01:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
18:01:51 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-mdhvu2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
18:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5))
18:01:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
18:01:51 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-p0l9dx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
18:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6))
18:01:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
18:01:51 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-yvxta0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
18:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
18:01:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
18:01:51 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-1jn94a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
18:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8))
18:01:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
18:01:51 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-68oase-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
18:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
18:01:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
18:01:51 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-dvvomk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
18:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
18:01:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
18:01:51 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-lmt0se-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
18:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11))
18:01:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
18:01:51 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-kgll_o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12))
18:01:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
18:01:52 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-cvhujf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13))
18:01:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
18:01:52 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-kq3gzf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
18:01:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
18:01:52 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-9dywuq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
18:01:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
18:01:52 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-sbyihx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
18:01:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
18:01:52 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ebspek-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
18:01:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
18:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:01:53 INFO - Downloading blessings-1.5.1.tar.gz
18:01:53 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz
18:01:53 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
18:01:53 INFO - Installing collected packages: blessings
18:01:53 INFO - Running setup.py install for blessings
18:01:54 INFO - Successfully installed blessings
18:01:54 INFO - Cleaning up...
18:01:56 INFO - Return code: 0
18:01:56 INFO - Done creating virtualenv C:\slave\test\build/venv.
18:01:56 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
18:01:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
18:01:57 INFO - Reading from file tmpfile_stdout
18:01:57 INFO - Using _rmtree_windows ...
18:01:57 INFO - Using _rmtree_windows ...
18:01:57 INFO - Current package versions:
18:01:57 INFO - blessings == 1.5.1
18:01:57 INFO - blobuploader == 1.2.4
18:01:57 INFO - docopt == 0.6.1
18:01:57 INFO - manifestparser == 1.1
18:01:57 INFO - mozInstall == 1.12
18:01:57 INFO - mozcrash == 0.16
18:01:57 INFO - mozdebug == 0.1
18:01:57 INFO - mozdevice == 0.46
18:01:57 INFO - mozfile == 1.2
18:01:57 INFO - mozhttpd == 0.7
18:01:57 INFO - mozinfo == 0.8
18:01:57 INFO - mozleak == 0.1
18:01:57 INFO - mozlog == 3.0
18:01:57 INFO - moznetwork == 0.27
18:01:57 INFO - mozprocess == 0.22
18:01:57 INFO - mozprofile == 0.27
18:01:57 INFO - mozrunner == 6.11
18:01:57 INFO - mozscreenshot == 0.1
18:01:57 INFO - mozsystemmonitor == 0.0
18:01:57 INFO - moztest == 0.7
18:01:57 INFO - mozversion == 1.4
18:01:57 INFO - psutil == 3.1.1
18:01:57 INFO - requests == 1.2.3
18:01:57 INFO - Installing None into virtualenv C:\slave\test\build/venv
18:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:01:57 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02312B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022D1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023CF9E8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D08B48>, '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 0x01D2A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027B3660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-115', 'USERDOMAIN': 'T-W864-IX-115', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-115', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
18:01:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos
18:01:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
18:01:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:01:57 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming',
18:01:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
18:01:57 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
18:01:57 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
18:01:57 INFO - 'COMPUTERNAME': 'T-W864-IX-115',
18:01:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:01:57 INFO - 'DCLOCATION': 'SCL3',
18:01:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:01:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:01:57 INFO - 'HOMEDRIVE': 'C:',
18:01:57 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115',
18:01:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:01:57 INFO - 'KTS_VERSION': '1.19c',
18:01:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local',
18:01:57 INFO - 'LOGONSERVER': '\\\\T-W864-IX-115',
18:01:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:01:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:01:57 INFO - 'MOZ_NO_REMOTE': '1',
18:01:57 INFO - 'NO_EM_RESTART': '1',
18:01:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:01:57 INFO - 'OS': 'Windows_NT',
18:01:57 INFO - 'OURDRIVE': 'C:',
18:01:57 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg',
18:01:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:01:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:01:57 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64',
18:01:57 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
18:01:57 INFO - 'PROCESSOR_LEVEL': '6',
18:01:57 INFO - 'PROCESSOR_REVISION': '1e05',
18:01:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:01:57 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)',
18:01:57 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
18:01:57 INFO - 'PROGRAMW6432': 'C:\\Program Files',
18:01:57 INFO - 'PROMPT': '$P$G',
18:01:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:01:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:01:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:01:57 INFO - 'PWD': 'C:\\slave\\test',
18:01:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:01:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:01:57 INFO - 'SYSTEMDRIVE': 'C:',
18:01:57 INFO - 'SYSTEMROOT': 'C:\\windows',
18:01:57 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:57 INFO - 'TEST1': 'testie',
18:01:57 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:01:57 INFO - 'USERDOMAIN': 'T-W864-IX-115',
18:01:57 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115',
18:01:57 INFO - 'USERNAME': 'cltbld',
18:01:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115',
18:01:57 INFO - 'WINDIR': 'C:\\windows',
18:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 3))
18:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
18:01:57 INFO - Cleaning up...
18:01:57 INFO - Return code: 0
18:01:57 INFO - Running post-action listener: _resource_record_post_action
18:01:57 INFO - Running post-action listener: _start_resource_monitoring
18:01:57 INFO - Starting resource monitoring.
18:01:57 INFO - #####
18:01:57 INFO - ##### Running install step.
18:01:57 INFO - #####
18:01:57 INFO - Running pre-action listener: _resource_record_pre_action
18:01:57 INFO - Running main action method: install
18:01:57 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
18:01:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
18:01:58 INFO - Reading from file tmpfile_stdout
18:01:58 INFO - Using _rmtree_windows ...
18:01:58 INFO - Using _rmtree_windows ...
18:01:58 INFO - Detecting whether we're running mozinstall >=1.0...
18:01:58 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
18:01:58 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
18:01:58 INFO - Reading from file tmpfile_stdout
18:01:58 INFO - Output received:
18:01:58 INFO - Usage: mozinstall-script.py [options] installer
18:01:58 INFO - Options:
18:01:58 INFO - -h, --help show this help message and exit
18:01:58 INFO - -d DEST, --destination=DEST
18:01:58 INFO - Directory to install application into. [default:
18:01:58 INFO - "C:\slave\test"]
18:01:58 INFO - --app=APP Application being installed. [default: firefox]
18:01:58 INFO - Using _rmtree_windows ...
18:01:58 INFO - Using _rmtree_windows ...
18:01:58 INFO - mkdir: C:\slave\test\build\application
18:01:58 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.zip', '--destination', 'C:\\slave\\test\\build\\application']
18:01:58 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-44.0a1.en-US.win64.zip --destination C:\slave\test\build\application
18:01:59 INFO - Reading from file tmpfile_stdout
18:01:59 INFO - Output received:
18:01:59 INFO - C:\slave\test\build\application\firefox\firefox.exe
18:01:59 INFO - Using _rmtree_windows ...
18:01:59 INFO - Using _rmtree_windows ...
18:01:59 INFO - Running post-action listener: _resource_record_post_action
18:01:59 INFO - #####
18:01:59 INFO - ##### Running run-tests step.
18:01:59 INFO - #####
18:01:59 INFO - Running pre-action listener: _resource_record_pre_action
18:01:59 INFO - Running main action method: run_tests
18:01:59 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
18:01:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
18:01:59 INFO - Python 2.7.3
18:01:59 INFO - Return code: 0
18:01:59 INFO - grabbing minidump binary from tooltool
18:01:59 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')]}
18:01:59 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\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D08B48>, '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 0x01D2A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027B3660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
18:01:59 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\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
18:01:59 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\build\tests\config/tooltool-manifests/win32/releng.manifest -o
18:01:59 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
18:02:00 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp7kxhhy
18:02:00 INFO - INFO - File integrity verified, renaming tmp7kxhhy to win32-minidump_stackwalk.exe
18:02:00 INFO - Return code: 0
18:02:00 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
18:02:00 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
18:02:00 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
18:02:00 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
18:02:00 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
18:02:00 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-115', '--webServer', 'localhost'] in C:\slave\test\build
18:02:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip --title T-W864-IX-115 --webServer localhost
18:02:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
18:02:00 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Roaming',
18:02:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
18:02:00 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
18:02:00 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
18:02:00 INFO - 'COMPUTERNAME': 'T-W864-IX-115',
18:02:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
18:02:00 INFO - 'DCLOCATION': 'SCL3',
18:02:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:02:00 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:02:00 INFO - 'HOMEDRIVE': 'C:',
18:02:00 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-115',
18:02:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:02:00 INFO - 'KTS_VERSION': '1.19c',
18:02:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-115\\AppData\\Local',
18:02:00 INFO - 'LOGONSERVER': '\\\\T-W864-IX-115',
18:02:00 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
18:02:00 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
18:02:00 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:02:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:02:00 INFO - 'MOZ_NO_REMOTE': '1',
18:02:00 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
18:02:00 INFO - 'NO_EM_RESTART': '1',
18:02:00 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:02:00 INFO - 'OS': 'Windows_NT',
18:02:00 INFO - 'OURDRIVE': 'C:',
18:02:00 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg',
18:02:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
18:02:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:02:00 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64',
18:02:00 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
18:02:00 INFO - 'PROCESSOR_LEVEL': '6',
18:02:00 INFO - 'PROCESSOR_REVISION': '1e05',
18:02:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
18:02:00 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)',
18:02:00 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
18:02:00 INFO - 'PROGRAMW6432': 'C:\\Program Files',
18:02:00 INFO - 'PROMPT': '$P$G',
18:02:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:02:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
18:02:00 INFO - 'PUBLIC': 'C:\\Users\\Public',
18:02:00 INFO - 'PWD': 'C:\\slave\\test',
18:02:00 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
18:02:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:02:00 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:02:00 INFO - 'SYSTEMDRIVE': 'C:',
18:02:00 INFO - 'SYSTEMROOT': 'C:\\windows',
18:02:00 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:02:00 INFO - 'TEST1': 'testie',
18:02:00 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
18:02:00 INFO - 'USERDOMAIN': 'T-W864-IX-115',
18:02:00 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-115',
18:02:00 INFO - 'USERNAME': 'cltbld',
18:02:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-115',
18:02:00 INFO - 'WINDIR': 'C:\\windows',
18:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:02:00 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-115', '--webServer', 'localhost'] with output_timeout 3600
ProcessManager UNABLE to use job objects to manage child processes
ProcessManager NOT managing child processes
18:02:01 INFO - mozversion INFO | application_buildid: 20151023160116
18:02:01 INFO - mozversion INFO | application_changeset: 335ea7a95492650e23c9ba14648d4f43620babab
18:02:01 INFO - mozversion INFO | application_display_name: Nightly
18:02:01 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
18:02:01 INFO - mozversion INFO | application_name: Firefox
18:02:01 INFO - mozversion INFO | application_remotingname: firefox
18:02:01 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team
18:02:01 INFO - mozversion INFO | application_vendor: Mozilla
18:02:01 INFO - mozversion INFO | application_version: 44.0a1
18:02:01 INFO - mozversion INFO | platform_buildid: 20151023160116
18:02:01 INFO - mozversion INFO | platform_changeset: 335ea7a95492650e23c9ba14648d4f43620babab
18:02:01 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team
18:02:01 INFO - mozversion INFO | platform_version: 44.0a1
18:02:01 INFO - 2015-10-23 18:02:01,500 DEBUG : using testdate: 1445648521
18:02:01 INFO - 2015-10-23 18:02:01,500 DEBUG : actual date: 1445648521
18:02:01 INFO - 2015-10-23 18:02:01,631 INFO : starting webserver on 'localhost:49278'
18:02:01 INFO - 2015-10-23 18:02:01,632 INFO : Starting test suite T-W864-IX-115
18:02:01 INFO - 2015-10-23 18:02:01,634 INFO : Starting test dromaeo_css
18:02:01 INFO - 2015-10-23 18:02:01,634 DEBUG : operating with platform_type : w8_
18:02:01 INFO - 2015-10-23 18:02:01,634 INFO : Initialising browser for dromaeo_css test...
18:02:01 INFO - 2015-10-23 18:02:01,740 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpg2stpc\profile http://localhost:49278/getInfo.html
18:02:10 INFO - 2015-10-23 18:02:10,803 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
18:02:10 INFO - 2015-10-23 18:02:10,803 DEBUG : BROWSER_OUTPUT: colorDepth:24
18:02:10 INFO - 2015-10-23 18:02:10,803 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
18:02:10 INFO - 2015-10-23 18:02:10,803 DEBUG : BROWSER_OUTPUT: __metrics
18:02:10 INFO - 2015-10-23 18:02:10,986 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: console.error:
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no]
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: Stack:
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12
18:02:12 INFO - 2015-10-23 18:02:12,457 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23
18:20:51 INFO - 2015-10-23 18:20:51,086 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
18:20:51 INFO - 2015-10-23 18:20:51,086 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
18:20:51 INFO - 2015-10-23 18:20:51,086 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
18:20:51 INFO - 2015-10-23 18:20:51,086 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
18:20:51 INFO - 2015-10-23 18:20:51,086 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13
18:20:51 INFO - 2015-10-23 18:20:51,086 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
18:20:51 INFO - 2015-10-23 18:20:51,088 DEBUG : BROWSER_OUTPUT:
18:20:51 INFO - 2015-10-23 18:20:51,874 INFO : Browser initialized.
18:20:51 INFO - 2015-10-23 18:20:51,874 INFO : Running cycle 1/1 for kraken test...
18:20:51 INFO - 2015-10-23 18:20:51,874 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpe_zarj\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
18:21:03 INFO - 2015-10-23 18:21:03,453 DEBUG : BROWSER_OUTPUT: RSS: Main: 183169024
18:21:03 INFO - 2015-10-23 18:21:03,453 DEBUG : BROWSER_OUTPUT:
18:21:06 INFO - 2015-10-23 18:21:06,190 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection)
18:21:06 INFO - 2015-10-23 18:21:06,773 DEBUG : BROWSER_OUTPUT: RSS: Main: 219537408
18:21:06 INFO - 2015-10-23 18:21:06,773 DEBUG : BROWSER_OUTPUT:
18:21:09 INFO - 2015-10-23 18:21:09,467 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft)
18:21:10 INFO - 2015-10-23 18:21:10,040 DEBUG : BROWSER_OUTPUT: RSS: Main: 220512256
18:21:10 INFO - 2015-10-23 18:21:10,040 DEBUG : BROWSER_OUTPUT:
18:21:12 INFO - 2015-10-23 18:21:12,756 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft)
18:21:13 INFO - 2015-10-23 18:21:13,338 DEBUG : BROWSER_OUTPUT: RSS: Main: 204869632
18:21:13 INFO - 2015-10-23 18:21:13,338 DEBUG : BROWSER_OUTPUT:
18:21:15 INFO - 2015-10-23 18:21:15,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator)
18:21:15 INFO - 2015-10-23 18:21:15,924 DEBUG : BROWSER_OUTPUT: RSS: Main: 207593472
18:21:15 INFO - 2015-10-23 18:21:15,924 DEBUG : BROWSER_OUTPUT:
18:21:18 INFO - 2015-10-23 18:21:18,132 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur)
18:21:18 INFO - 2015-10-23 18:21:18,697 DEBUG : BROWSER_OUTPUT: RSS: Main: 205078528
18:21:18 INFO - 2015-10-23 18:21:18,697 DEBUG : BROWSER_OUTPUT:
18:21:21 INFO - 2015-10-23 18:21:21,786 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom)
18:21:22 INFO - 2015-10-23 18:21:22,371 DEBUG : BROWSER_OUTPUT: RSS: Main: 200495104
18:21:22 INFO - 2015-10-23 18:21:22,371 DEBUG : BROWSER_OUTPUT:
18:21:25 INFO - 2015-10-23 18:21:25,821 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate)
18:21:26 INFO - 2015-10-23 18:21:26,404 DEBUG : BROWSER_OUTPUT: RSS: Main: 199155712
18:21:26 INFO - 2015-10-23 18:21:26,404 DEBUG : BROWSER_OUTPUT:
18:21:29 INFO - 2015-10-23 18:21:29,279 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial)
18:21:29 INFO - 2015-10-23 18:21:29,848 DEBUG : BROWSER_OUTPUT: RSS: Main: 196390912
18:21:29 INFO - 2015-10-23 18:21:29,848 DEBUG : BROWSER_OUTPUT:
18:21:31 INFO - 2015-10-23 18:21:31,624 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox)
18:21:32 INFO - 2015-10-23 18:21:32,187 DEBUG : BROWSER_OUTPUT: RSS: Main: 225484800
18:21:32 INFO - 2015-10-23 18:21:32,187 DEBUG : BROWSER_OUTPUT:
18:21:34 INFO - 2015-10-23 18:21:34,086 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes)
18:21:34 INFO - 2015-10-23 18:21:34,661 DEBUG : BROWSER_OUTPUT: RSS: Main: 227581952
18:21:34 INFO - 2015-10-23 18:21:34,661 DEBUG : BROWSER_OUTPUT:
18:21:36 INFO - 2015-10-23 18:21:36,957 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm)
18:21:37 INFO - 2015-10-23 18:21:37,539 DEBUG : BROWSER_OUTPUT: RSS: Main: 232624128
18:21:37 INFO - 2015-10-23 18:21:37,539 DEBUG : BROWSER_OUTPUT:
18:21:40 INFO - 2015-10-23 18:21:40,105 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
18:21:40 INFO - 2015-10-23 18:21:40,690 DEBUG : BROWSER_OUTPUT: RSS: Main: 235868160
18:21:40 INFO - 2015-10-23 18:21:40,690 DEBUG : BROWSER_OUTPUT:
18:21:43 INFO - 2015-10-23 18:21:43,438 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
18:21:44 INFO - 2015-10-23 18:21:44,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 236699648
18:21:44 INFO - 2015-10-23 18:21:44,023 DEBUG : BROWSER_OUTPUT:
18:21:45 INFO - 2015-10-23 18:21:45,855 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar)
18:21:46 INFO - 2015-10-23 18:21:46,107 DEBUG : BROWSER_OUTPUT: RSS: Main: 291737600
18:21:46 INFO - 2015-10-23 18:21:46,107 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: __start_tp_report
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;113;107;112;113;112;112;112;106;111
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;136;133;150;160;131;134;137;132;161;133
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |2;audio-dft;141;141;140;147;148;139;140;143;140;139
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |3;audio-fft;69;72;71;97;73;69;69;72;101;67
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;112;91;91;92;121;91;91;117
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;113;110;111;112;110;110;111;111;110;112
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;132;132;132;134;133;132;131;131;132;131
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;96;96;104;97;96;97;96
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;69;71;71;71;70;79;70;83;70;69
18:21:46 INFO - 2015-10-23 18:21:46,108 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;67;68;68;71;68;68;68
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;92;93;93;93;92;93;92;93;93;91
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;124;128;125;137;127;133;124;132;125
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;150;153;160;159;161;151;160;160;159;160
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;76;75;76;75;75;75;75;75;75;76
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT: __end_tp_report
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT: __start_cc_report
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,967
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT: __end_cc_report
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT: __startTimestamp1445649706108__endTimestamp
18:21:46 INFO - 2015-10-23 18:21:46,109 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,111 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
18:21:46 INFO - 2015-10-23 18:21:46,111 DEBUG : BROWSER_OUTPUT: Number of tests: 14
18:21:46 INFO - 2015-10-23 18:21:46,112 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,112 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.00 Median:112.00 stddev:2.45 (2.2%) stddev-sans-first:2.57
18:21:46 INFO - 2015-10-23 18:21:46,112 DEBUG : BROWSER_OUTPUT: Values: 112.0 113.0 107.0 112.0 113.0 112.0 112.0 112.0 106.0 111.0
18:21:46 INFO - 2015-10-23 18:21:46,112 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,112 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.70 Median:136.50 stddev:11.74 (8.6%) stddev-sans-first:12.33
18:21:46 INFO - 2015-10-23 18:21:46,112 DEBUG : BROWSER_OUTPUT: Values: 136.0 133.0 150.0 160.0 131.0 134.0 137.0 132.0 161.0 133.0
18:21:46 INFO - 2015-10-23 18:21:46,112 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,114 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:141.80 Median:141.00 stddev:3.22 (2.3%) stddev-sans-first:3.41
18:21:46 INFO - 2015-10-23 18:21:46,114 DEBUG : BROWSER_OUTPUT: Values: 141.0 141.0 140.0 147.0 148.0 139.0 140.0 143.0 140.0 139.0
18:21:46 INFO - 2015-10-23 18:21:46,114 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,114 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:76.00 Median:71.50 stddev:12.29 (17.2%) stddev-sans-first:12.77
18:21:46 INFO - 2015-10-23 18:21:46,114 DEBUG : BROWSER_OUTPUT: Values: 69.0 72.0 71.0 97.0 73.0 69.0 69.0 72.0 101.0 67.0
18:21:46 INFO - 2015-10-23 18:21:46,114 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,114 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:99.00 Median:91.00 stddev:12.38 (13.6%) stddev-sans-first:12.87
18:21:46 INFO - 2015-10-23 18:21:46,114 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 112.0 91.0 91.0 92.0 121.0 91.0 91.0 117.0
18:21:46 INFO - 2015-10-23 18:21:46,115 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,115 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:111.00 Median:111.00 stddev:1.05 (0.9%) stddev-sans-first:0.83
18:21:46 INFO - 2015-10-23 18:21:46,115 DEBUG : BROWSER_OUTPUT: Values: 113.0 110.0 111.0 112.0 110.0 110.0 111.0 111.0 110.0 112.0
18:21:46 INFO - 2015-10-23 18:21:46,115 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,115 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:132.00 Median:132.00 stddev:0.94 (0.7%) stddev-sans-first:1.00
18:21:46 INFO - 2015-10-23 18:21:46,115 DEBUG : BROWSER_OUTPUT: Values: 132.0 132.0 132.0 134.0 133.0 132.0 131.0 131.0 132.0 131.0
18:21:46 INFO - 2015-10-23 18:21:46,115 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,117 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:97.10 Median:96.00 stddev:2.47 (2.6%) stddev-sans-first:2.59
18:21:46 INFO - 2015-10-23 18:21:46,117 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 96.0 96.0 104.0 97.0 96.0 97.0 96.0
18:21:46 INFO - 2015-10-23 18:21:46,117 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,117 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:72.30 Median:71.00 stddev:4.74 (6.7%) stddev-sans-first:4.87
18:21:46 INFO - 2015-10-23 18:21:46,118 DEBUG : BROWSER_OUTPUT: Values: 69.0 71.0 71.0 71.0 70.0 79.0 70.0 83.0 70.0 69.0
18:21:46 INFO - 2015-10-23 18:21:46,118 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,118 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:1.12
18:21:46 INFO - 2015-10-23 18:21:46,118 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 67.0 68.0 68.0 71.0 68.0 68.0 68.0
18:21:46 INFO - 2015-10-23 18:21:46,118 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,118 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:93.00 stddev:0.71 (0.8%) stddev-sans-first:0.73
18:21:46 INFO - 2015-10-23 18:21:46,118 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 93.0 93.0 92.0 93.0 92.0 93.0 93.0 91.0
18:21:46 INFO - 2015-10-23 18:21:46,119 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,119 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:127.50 stddev:4.50 (3.5%) stddev-sans-first:4.64
18:21:46 INFO - 2015-10-23 18:21:46,119 DEBUG : BROWSER_OUTPUT: Values: 125.0 124.0 128.0 125.0 137.0 127.0 133.0 124.0 132.0 125.0
18:21:46 INFO - 2015-10-23 18:21:46,121 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,121 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.30 Median:160.00 stddev:4.22 (2.6%) stddev-sans-first:3.55
18:21:46 INFO - 2015-10-23 18:21:46,121 DEBUG : BROWSER_OUTPUT: Values: 150.0 153.0 160.0 159.0 161.0 151.0 160.0 160.0 159.0 160.0
18:21:46 INFO - 2015-10-23 18:21:46,121 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,121 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:75.00 stddev:0.48 (0.6%) stddev-sans-first:0.44
18:21:46 INFO - 2015-10-23 18:21:46,121 DEBUG : BROWSER_OUTPUT: Values: 76.0 75.0 76.0 75.0 75.0 75.0 75.0 75.0 75.0 76.0
18:21:46 INFO - 2015-10-23 18:21:46,121 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
18:21:46 INFO - 2015-10-23 18:21:46,121 DEBUG : BROWSER_OUTPUT:
18:21:46 INFO - 2015-10-23 18:21:46,904 INFO : Browser exited with error code: 0
18:21:47 INFO - 2015-10-23 18:21:47,842 INFO : Completed test kraken (00:01:03)
18:21:47 INFO - 2015-10-23 18:21:47,842 INFO : Starting test v8_7
18:21:47 INFO - 2015-10-23 18:21:47,842 DEBUG : operating with platform_type : w8_
18:21:47 INFO - 2015-10-23 18:21:47,842 INFO : Initialising browser for v8_7 test...
18:21:47 INFO - 2015-10-23 18:21:47,861 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpwqsjhl\profile http://localhost:49278/getInfo.html
18:21:54 INFO - 2015-10-23 18:21:54,059 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
18:21:54 INFO - 2015-10-23 18:21:54,059 DEBUG : BROWSER_OUTPUT: colorDepth:24
18:21:54 INFO - 2015-10-23 18:21:54,059 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
18:21:54 INFO - 2015-10-23 18:21:54,059 DEBUG : BROWSER_OUTPUT: __metrics
18:21:54 INFO - 2015-10-23 18:21:54,157 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:21:54 INFO - 2015-10-23 18:21:54,545 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 121: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code.
18:21:55 INFO - 2015-10-23 18:21:55,536 INFO : Browser initialized.
18:21:55 INFO - 2015-10-23 18:21:55,536 INFO : Running cycle 1/1 for v8_7 test...
18:21:55 INFO - 2015-10-23 18:21:55,536 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpwqsjhl\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\v8_7\v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
18:22:07 INFO - 2015-10-23 18:22:07,105 DEBUG : BROWSER_OUTPUT: RSS: Main: 178495488
18:22:07 INFO - 2015-10-23 18:22:07,105 DEBUG : BROWSER_OUTPUT:
18:22:28 INFO - 2015-10-23 18:22:28,861 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/v8_7/run.html (next: http://localhost:49278/tests/v8_7/run.html)
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 662159360
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: __start_tp_report
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: |0;Richards;164.82610845557937
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;175.34630896019638
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: |2;Encrypt;285.7959416976279
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5045.226130653266
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1567.398119122257
18:22:29 INFO - 2015-10-23 18:22:29,112 DEBUG : BROWSER_OUTPUT: |5;Earley;662.2516556291391
18:22:29 INFO - 2015-10-23 18:22:29,114 DEBUG : BROWSER_OUTPUT: |6;Boyer;12746.835443037975
18:22:29 INFO - 2015-10-23 18:22:29,114 DEBUG : BROWSER_OUTPUT: |7;RegExp;29028.571428571428
18:22:29 INFO - 2015-10-23 18:22:29,114 DEBUG : BROWSER_OUTPUT: |8;Splay;666.6666666666666
18:22:29 INFO - 2015-10-23 18:22:29,114 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4864.077669902913
18:22:29 INFO - 2015-10-23 18:22:29,114 DEBUG : BROWSER_OUTPUT: __end_tp_report
18:22:29 INFO - 2015-10-23 18:22:29,114 DEBUG : BROWSER_OUTPUT: __startTimestamp1445649749112__endTimestamp
18:22:29 INFO - 2015-10-23 18:22:29,114 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT: Number of tests: 10
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.83 Median:164.83 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT: Values: 164.8
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:175.35 Median:175.35 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT: Values: 175.3
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:285.80 Median:285.80 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT: Values: 285.8
18:22:29 INFO - 2015-10-23 18:22:29,124 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5045.23 Median:5045.23 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: Values: 5045.2
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1567.40 Median:1567.40 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: Values: 1567.4
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:662.25 Median:662.25 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: Values: 662.3
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12746.84 Median:12746.84 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: Values: 12746.8
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:29028.57 Median:29028.57 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: Values: 29028.6
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:666.67 Median:666.67 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: Values: 666.7
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4864.08 Median:4864.08 stddev:0.00 (0.0%)
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: Values: 4864.1
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
18:22:29 INFO - 2015-10-23 18:22:29,125 DEBUG : BROWSER_OUTPUT:
18:22:29 INFO - 2015-10-23 18:22:29,869 INFO : Browser exited with error code: 0
18:22:30 INFO - 2015-10-23 18:22:30,578 INFO : Completed test v8_7 (00:00:42)
18:22:30 INFO - 2015-10-23 18:22:30,917 INFO : Completed test suite (00:20:29)
18:22:30 INFO - 2015-10-23 18:22:30,917 DEBUG : Working with test: dromaeo_css
18:22:30 INFO - 2015-10-23 18:22:30,917 DEBUG : Generating results file: dromaeo_css
18:22:30 INFO - 2015-10-23 18:22:30,917 DEBUG : Working with test: kraken
18:22:30 INFO - 2015-10-23 18:22:30,917 DEBUG : Generating results file: kraken
18:22:30 INFO - 2015-10-23 18:22:30,917 INFO : javascript benchmark
18:22:30 INFO - 2015-10-23 18:22:30,917 DEBUG : Working with test: v8_7
18:22:30 INFO - 2015-10-23 18:22:30,917 DEBUG : Generating results file: v8_7
18:22:30 INFO - 2015-10-23 18:22:30,917 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
18:22:30 INFO - 2015-10-23 18:22:30,971 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,31]]
18:22:30 INFO - 2015-10-23 18:22:30,971 DEBUG : process_Request line: dromaeo_css 6154.84 graph.html#tests=[[72,132,31]]
18:22:30 INFO - 2015-10-23 18:22:30,971 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
18:22:31 INFO - 2015-10-23 18:22:31,006 DEBUG : process_Request line: kraken 1502.30 graph.html#tests=[[232,132,31]]
18:22:31 INFO - 2015-10-23 18:22:31,006 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
18:22:31 INFO - 2015-10-23 18:22:31,029 DEBUG : process_Request line: v8_7 20327.07 graph.html#tests=[[230,132,31]]
18:22:31 INFO - 2015-10-23 18:22:31,029 INFO : javascript benchmark
18:22:31 INFO - 2015-10-23 18:22:31,032 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"jquery.html": [333.2418747349779, 334.5851144580719, 337.039437602832, 339.79612232660406, 339.8402750707167, 13071.0, 13419.798703019462, 14825.332860021297, 14905.180215088165, 15630.3747850086, 13185.80221296679, 14563.708725825478, 14719.0, 15252.694946101072, 15282.465113721022, 5060.645754797157, 5199.506046925555, 5205.297284866532, 5205.817796377128, 5220.608454365919, 3952.505936757905, 4045.777482238476, 5208.010478009185, 5224.164133738603, 5747.281589801275, 5862.472377486026, 5868.0, 5875.412458754133, 5889.322727886305, 5889.823305300848, 5686.914696279559, 5824.737886795105, 5828.03837366834, 5833.445822646841, 5867.3252575953675, 5109.642325037249, 5111.718855462948, 5111.974440127813, 5113.284140220372, 5121.103806833827, 17813.643727125516, 17819.198136083778, 18035.909820450946, 18441.0, 18453.815461845345, 1914.2151717795775, 1963.7545306836646, 2068.631041342924, 2160.6758986152045, 2535.934907338922, 12247.081468889779, 12378.566750163702, 12388.070894682845, 12414.56549020789, 12416.0, 1799.4961410804995, 1899.040984302932, 2028.3205126282712, 2045.498852781078, 2051.364077136078, 50514.747426262635, 50984.23523647111, 51082.0, 51651.22523162155, 53800.99999999961, 13469.0, 13553.525626603021, 13606.115602485806, 13619.523316683868, 13658.931705341409, 19453.31913383025, 19667.0, 19854.801451985582, 19873.0, 19910.800891991184, 8210.0, 8237.670493180325, 8250.752477425687, 8281.089080201173, 8299.543525106057, 6948.652567371712, 6971.8605627887155, 7026.964865175743, 7043.401310888529, 7114.395276401564, 7170.605616691132, 7175.354218120394, 7245.9275407246305, 7256.383207427428, 7278.526895751759, 2726.7273272672574, 3100.1784527100335, 3135.0438116374726, 3154.763392745507, 3162.525621156845, 393.9094008378089, 395.839684927599, 409.1938880405596, 416.435313714604, 439.8900274931267, 8650.102215757792, 9495.952520237493, 9496.192823609932, 9590.520473976274, 10472.424016679155, 437.2457510793919, 443.16684632890605, 444.36677734228596, 451.10680851913395, 457.51274892239655, 5623.803166889221, 5766.682832444172, 5786.768529258782, 5847.035239185487, 5877.9118313225335, 411353.0, 414480.0, 414763.0, 415012.9974005211, 420896.0, 59602.0, 60013.0, 60066.39933600696, 60445.69777151086, 60741.0, 431.605081350567, 442.9977850110793, 443.4523363645895, 454.6726357022938, 458.79124998125775, 9547.809043818947, 9686.56410461545, 9770.706878793648, 10072.446015469219, 10131.949340253252, 29195.124146275644, 29325.85337073301, 29396.265093372836, 29619.70380296256, 30257.69742302549, 38245.0, 38397.232055359294, 39051.0, 39099.0, 39107.80446097751, 6881.41507971808, 7110.253423390676, 7197.0, 7227.096612923385, 7257.0, 10329.896701032894, 10468.162546996069, 10517.106045986176, 10671.986161314662, 11040.227184097035, 9497.667581634885, 9582.808343833223, 9680.27397945143, 9681.9031809681, 9702.951485242529, 9175.265978721553, 9258.027907069662, 9341.252699784136, 9381.718548443558, 9448.858267125861, 9446.433214007182, 9569.760755981157, 9662.164188804105, 9724.659636912675, 9819.263555233243, 6825.522213445011, 7006.824829379307, 7016.193137789015, 7038.683259253448, 7087.397571206505, 8314.958425207835, 8472.44929079608, 8625.439346442461, 8764.693235736719, 9072.274218062406], "dojo.html": [2531.5949448088304, 2591.611258311253, 2602.154299852546, 2604.4791041791646, 2607.5176092422903, 4607.723536587807, 4613.215753321935, 4626.421697287839, 4665.4602245965425, 4681.531846815316, 4645.674802763808, 4668.6731928765, 4671.089137618166, 4679.438467383919, 4735.692180008288, 4124.360724087771, 4231.724937879043, 4275.05948691289, 4276.572342765729, 4315.956840431602, 4174.102567947891, 4218.367244913272, 4226.51395089564, 4266.7866606669695, 4336.653067754588, 4237.8516751913685, 4278.080212754257, 4281.614654681078, 4326.870193894189, 4331.047169622678, 4109.2808758467145, 4266.722663026893, 4280.6361459275995, 4289.506706728735, 4293.398924150637, 3968.285708572456, 4208.705390622657, 4239.533651298355, 4249.213895429355, 4274.764887931163, 4563.397994779831, 4610.3315019322345, 4613.215753321943, 4618.676692631517, 4623.30650402439, 4682.1338052460405, 4834.7824347904425, 4885.267209918505, 4886.877828054292, 4943.604511639096, 4797.928031079537, 4845.854624361275, 4873.975630121862, 4880.853574392774, 4936.456989731127, 4508.932366014513, 4534.433195850519, 4534.546545345505, 4561.817527298904, 4589.5639914208095, 4808.687435316693, 4980.427250866197, 4986.975065124651, 5006.674566153189, 5047.318611987334, 4732.95267047332, 4740.004599034172, 4747.074320507468, 4823.927641085386, 4899.951000489949, 4891.559759621651, 4905.5094490550655, 4905.509449055137, 4907.0, 4945.7774400152075, 4670.6497012724185, 4840.927386089212, 4853.927191092136, 4858.878528036827, 4907.09218794517, 4683.086798074411, 4735.905281894343, 4745.715257084586, 4784.186688263004, 4798.04039192163, 3232.725218356419, 3242.4812030075077, 3249.203945033482, 3285.227971426713, 3290.2925870937866, 2919.5328747400313, 2993.4013197360614, 2993.4462124507036, 3011.5482677598534, 3044.3150291184306, 4389.1660584488845, 4506.81972721094, 4511.120331535385, 4543.0, 4554.749488778149, 4923.630727695438, 4930.531599498042, 4940.950590494121, 4946.629002824802, 4982.785290155168, 4393.868183954422, 4579.564941330568, 4579.633629309647, 4589.79345929434, 4628.583427491542, 3971.1065010372436, 4013.8795836124964, 4022.9798851005557, 4030.6573941215324, 4044.838206471708, 4886.0, 4898.853034408973, 4913.11563918491, 4916.262560615936, 4963.280324352949, 4720.740359280273, 4896.804127834846, 4897.657163998486, 4898.853034408973, 4956.083124621957, 4801.471838097877, 4857.077155340474, 4876.21980483121, 4914.36113305268, 4928.926066109083, 4806.831760888352, 4834.709917404967, 4851.320815085962, 4852.296417019442, 4908.877278067934, 4697.318888761179, 4890.0, 4901.387326584177, 4902.313676085279, 4964.453910069961, 4651.744154071558, 4735.0, 4748.643851711205, 4758.714477131383, 4764.928526072043, 4717.268823332389, 4738.170820106398, 4768.070226305837, 4771.928421073756, 4828.565429111397, 4591.449026116888, 4672.48602653701, 4698.9530104698515, 4704.811807527661, 4763.642726795435, 4670.719756814466, 4750.334953106499, 4756.904861902812, 4844.346013288302, 4845.709257444564, 4713.882152946204, 4715.8821029473165, 4720.575148236675, 4729.408823897013, 4800.183968725255], "mootools.html": [1340.3901224942651, 1435.239323158726, 1606.7509536021917, 1663.5508412728564, 1849.4821449994006, 7438.851222975537, 8082.272595466414, 8189.672413103469, 8296.25333719965, 8378.204070613298, 8182.140875208107, 8204.09754926959, 8276.213759692835, 8279.83440331193, 8290.58547072647, 1913.0721600023978, 1951.268274397101, 2069.0689189864547, 2090.393785802123, 2123.9893800531054, 2150.215171462413, 2151.0535364439597, 2153.3001774423296, 2154.1168121070364, 2156.9460763480884, 2118.8199003084756, 2121.1303365620097, 2121.734783152106, 2124.511362386652, 2133.4666333416644, 1885.3966730646198, 1918.2998205654906, 2144.785521447858, 2180.3132013415725, 2197.813185879202, 2116.0, 2133.0934352900113, 2155.3426205007468, 2172.923947661824, 2189.6496560550236, 8279.172082791732, 8337.498750374863, 8353.624086916103, 8356.122607126286, 8385.077641459435, 4373.234683930299, 4378.868633940987, 4401.163778882003, 4451.62161216297, 4454.465464144291, 2659.724804760134, 2669.5728683410757, 2707.0795929384103, 2735.7948153888337, 2754.807163498556, 812.1796985045088, 822.3256929317962, 829.1584042197114, 837.5895811052601, 842.7513883404349, 12130.939345303304, 12153.453094610764, 12156.392180391034, 12166.51333946641, 12174.695632609255, 4164.437800896901, 4242.236397448489, 4298.63461605767, 4305.07440900208, 4338.566143385699, 10197.898021019695, 10210.183185345155, 10213.948930255301, 10221.642242521477, 10223.335483193569, 4598.287265473857, 4607.378003969421, 4609.60818330439, 4618.0, 4629.259318509023, 4078.775667338325, 4082.9387559186634, 4086.6322031017353, 4100.7334523255895, 4102.200070986189, 3721.3859713147567, 3729.2355067211156, 3734.533183352081, 3744.6629803317833, 3774.9433758493647, 1181.3915833345834, 1186.3593659423987, 1190.36315571169, 1194.5162209305245, 1211.7939950208486, 1107.512694414471, 1120.462178154474, 1130.2822707580744, 1130.8473356096986, 1132.6318946342471, 7666.769996900102, 7807.80480487981, 7844.37245020397, 7860.882086768703, 7884.0, 1060.289605964006, 1067.8665166854144, 1077.1867240233573, 1088.4938503595865, 1125.060574420368, 5751.079827227623, 5891.882162356729, 5924.911126333109, 5933.050711886078, 5977.193078934288, 10268.178545716324, 10433.373997560171, 10446.268761186644, 10468.267221294589, 10472.266941314187, 10108.696739097839, 10309.226807989431, 10317.432541210155, 10325.793484130276, 10329.12202462784, 7300.96349518249, 7325.2308507607, 7361.926380736231, 7398.075240594926, 7400.185979542139, 7175.138983322035, 7178.353948144483, 7244.275572442924, 7663.655135518804, 7697.576633284999, 8649.135086491553, 8739.12608739121, 8763.605637746445, 8766.474011559327, 8877.955610222167, 8889.422187557788, 9207.677731279373, 9214.124658157465, 9217.907820921704, 9229.307801915018, 7734.845303094019, 7790.961045194965, 7805.141434442094, 7832.060152781475, 7857.724979625686, 7975.641096150804, 8004.919950800418, 8062.798430039062, 8067.556284404414, 8074.798130046795, 7311.159216690149, 7312.195658477457, 7439.293267139613, 7475.738349157971, 7630.923690763021, 6670.799876003728, 6922.411595014481, 7328.523645962995, 7329.654510556571, 7483.962580187064, 7502.849943001219, 7526.811829704301, 7568.470207085451, 7580.241975802375, 7634.923650763643, 6690.565113267622, 6729.293424190585, 6774.661266936732, 6776.0, 6777.118974533476, 6729.798106056826, 6731.663416829237, 6761.391474767294, 6764.594124352555, 6844.726210951705], "ext.html": [557.4342042826531, 557.8828446026334, 558.5978095771045, 561.6124873837052, 581.4214856218064, 29879.402411951774, 30272.697273027265, 30279.545806812916, 30312.848435757816, 35757.284854302896, 39355.80322098386, 39572.0, 39751.801240993824, 40303.79848100755, 40311.395329070016, 8056.274935255811, 8240.752777416657, 8275.462094963808, 8283.917160828405, 8306.667733290662, 7616.390688744915, 7814.179511151304, 8267.214614611632, 9981.351212171221, 10228.48857557123, 9876.111149996499, 9922.503874806267, 9968.202543796478, 9970.551325190383, 9976.0, 7620.6951721931055, 9736.707898763048, 9750.951245243728, 9806.264530160266, 9818.852717209284, 7546.056742907137, 9748.415095094317, 9965.601375945025, 10073.89926100737, 10075.848862267072, 31767.20581985446, 32135.357292854245, 32432.837835810908, 32467.51298730521, 32501.51247731286, 2743.5747459143863, 2779.221817890994, 2865.6561212654406, 2914.0, 3205.983970080135, 28186.577201341996, 28315.575266371023, 28851.42297154045, 31440.213994650087, 31897.84051079753, 2407.807375409963, 2420.697412823397, 2422.2733180045916, 2453.0, 2486.8010559155223, 218798.0, 220814.0, 223478.0, 223596.0, 224116.0, 47086.76456617763, 48051.5194848054, 54825.55233429955, 54970.450295496536, 59479.0, 44982.55017449784, 45171.32243016357, 45186.09627807491, 45247.32129018068, 45747.0, 14669.193194374198, 14677.77983330251, 14896.478623248351, 14898.851011489747, 14928.104313740992, 8999.955000224956, 9005.954970225106, 9014.23379012778, 9023.052579479192, 9169.312301577409, 18174.818251817313, 18202.90898545525, 18288.359907403177, 18298.542536436697, 18342.174602142935, 8037.156098609678, 8224.203770717984, 8324.209200125979, 8337.458065225863, 8349.624266908004, 914.2183433164681, 934.2292608597799, 963.949295268147, 988.7577543501744, 1102.685734565645, 26888.462230755275, 27189.72810271872, 27309.317267068483, 27335.0, 27472.0, 939.4269495607674, 980.4460479829016, 983.016983016983, 1079.4980334144661, 1126.980083024864, 13808.378622961991, 13948.442062317597, 13954.302284885918, 14007.0, 14180.220087895264, 263373.0, 265044.0, 270568.0, 270684.0, 271379.0, 49135.26297105546, 49292.26061609079, 49387.073551174086, 49857.0, 49933.0, 1237.672016915518, 1261.974760504803, 1265.0006494869135, 1270.339423499775, 1297.506947360016, 23621.0, 30293.848530757205, 31754.682453175174, 31780.841095794374, 32460.0, 44247.11505769802, 44743.776281118386, 44848.77575612101, 44920.0, 44993.0, 236526.0, 243485.0, 243936.0, 244268.0, 245862.0, 37199.62800371962, 37897.24205515819, 38248.0, 38440.0, 40577.59422405738, 37669.62330376661, 38118.0, 38146.42780358352, 38161.80919095387, 38306.2338753229, 36466.27067458583, 36694.44958325681, 37074.258514829016, 37203.44194837025, 37490.0, 29984.550231746976, 35805.283894322485, 36603.750949734116, 36838.26323473569, 39809.60190398175, 36705.26589468249, 37059.25881482409, 37151.656722857566, 38109.0, 38137.42793858039, 31714.524282136248, 33262.168445788084, 33273.500897486076, 33342.333153337284, 33352.33295334128, 25098.62352064757, 26302.86848565745, 27435.588466173424, 27601.861990690722, 27607.0], "prototype.html": [314.05156427588685, 320.0, 322.8176080514509, 331.15059871429764, 331.77770893501344, 5153.510416510426, 5155.922661160085, 5161.896762064756, 5162.793488260474, 5166.922496162556, 4876.244182151763, 4962.478939711333, 5021.748912554376, 5092.9745351273195, 5093.185090385539, 3982.3429134192825, 4011.7392369496033, 4021.6782657387485, 4026.8791936241814, 4040.252553277653, 3882.417637354405, 4038.9596104039024, 4040.272750904836, 4040.3939409088725, 4040.636342729154, 3905.7070719695994, 3958.386450100239, 3986.9800650996704, 3994.6804255659476, 4005.078831868672, 3935.8032098394965, 3959.891230455477, 3992.0, 3993.6405723484736, 3995.1210733638554, 3505.2814173094707, 3531.2407832316176, 3535.522704434894, 3538.062413460435, 3544.379733546619, 4899.681520701143, 4929.433115191763, 4933.555979961785, 4940.53064958832, 4946.08497427977, 4367.388565600819, 4420.160169567772, 4456.153330867125, 4458.353538736865, 4470.926977525403, 5176.55999240065, 5238.842834714965, 5241.397239317489, 5242.580593552507, 5253.474652534755, 3686.9815650921573, 3688.428293614494, 3694.242680250542, 3694.7598406103516, 3703.6481534254203, 5411.053065713484, 5469.371022332403, 5476.644018138848, 5479.808206712786, 5493.752781124819, 3648.5256916600674, 3710.5176327077847, 3740.233252183296, 3823.2926908522013, 3828.406596977473, 3822.7706337619834, 3837.9424308635394, 3889.533256009297, 3894.123822139996, 3898.200868821885, 4420.425344705168, 4427.867163985085, 4433.4236549249035, 4436.578525040116, 4450.2434586120435, 5230.293910322058, 5233.162693969023, 5233.476652334813, 5234.2933703949475, 5253.44838791929, 1371.4377105386743, 1376.779715245556, 1392.6518370407398, 1394.5188909826093, 1395.3860301467525, 927.7778877953124, 954.8997355277735, 961.6153538584482, 962.9085236902483, 965.8164948659733, 4985.152524070917, 4989.55094041538, 5021.0, 5023.572996295283, 5025.447200807908, 5439.075357189287, 5439.945600543944, 5442.265294185234, 5448.618596698272, 5457.972710136424, 4918.188498897642, 5013.649044566918, 5025.170846810309, 5071.721055341918, 5078.0, 2970.212893583202, 3009.6689364169924, 3039.969600304013, 3043.1022848259818, 3045.8781648733802, 5508.118701007819, 5515.669059856422, 5516.448355164532, 5527.115661494142, 5538.47384498472, 5626.2967129108865, 5628.521575666115, 5628.774849006076, 5634.3802181759975, 5639.013172694762, 5498.037843377393, 5512.228288039598, 5513.8345849624575, 5522.779088836402, 5535.03136951032, 5401.2168235606405, 5441.5374693151525, 5506.394296627448, 5507.7246137693755, 5511.200875873056, 5299.788008479772, 5344.4121146674615, 5361.946380536145, 5368.1679339702405, 5372.570194384518, 5502.532284755841, 5506.779728810963, 5512.80705175317, 5519.227308176778, 5525.336959564878, 3878.573356930679, 3912.3544615138185, 3945.1320709443876, 3960.6039396060164, 3963.623455771697, 5319.335083114611, 5330.253764472899, 5414.864628384322, 5512.614117011771, 5516.475934786189, 5314.867128321668, 5322.733863306897, 5328.17413300939, 5328.49379308965, 5332.33345831771, 5284.7886084556185, 5302.7083510405755, 5324.988252232063, 5325.840224793263, 5326.707031113171, 5101.69389836611, 5196.376434827694, 5220.608454365862, 5224.268602395591, 5238.607104467256, 5293.761780719954, 5334.78660853577, 5335.946640533545, 5337.279467272024, 5343.492368225013, 5170.629783107554, 5172.948270517247, 5194.1429664105, 5220.765065571983, 5234.398044224963], "yui.html": [775.685847231871, 778.9367513344287, 788.8304014636852, 799.7240951871604, 800.0679208721839, 11299.322040677554, 11540.365279909613, 11564.306141631509, 11573.826392604118, 11584.826227606582, 9648.95175524123, 9682.951585242081, 9714.56284467199, 9743.2692548059, 9744.31789774716, 665.0921492163199, 674.6727836999052, 799.0770659887838, 810.0481933727846, 810.6757297081156, 749.6776386153952, 805.0741647105846, 825.793551612097, 830.9169083091703, 831.4678605692362, 688.2394953576302, 697.5396238482602, 859.3726579596898, 861.7974775927652, 865.7619154732467, 853.4196746212573, 865.5499140446932, 865.7316231968047, 866.332923648787, 867.0, 641.8748344072908, 643.2409756487343, 643.2602507116807, 643.3148696638066, 643.6974621927686, 10853.891461085366, 10924.0, 10966.396848173346, 10975.396353200571, 10976.615818446317, 9496.525173741355, 9672.742543469374, 10117.544710488046, 10121.746956326077, 10345.379277243313, 8667.869981950276, 8678.56607169639, 8679.175478329611, 8719.869201961976, 8735.868961965576, 3331.28377398858, 3356.848941797625, 3367.8316084195694, 3388.8813891513923, 3409.352223077632, 29542.636589073038, 29598.85200574005, 29905.40189196204, 29948.70051299481, 29987.0, 8277.420580559423, 8311.792205194797, 8350.373721970876, 8414.537200453911, 8505.362097842686, 13995.020348575503, 14074.437022519189, 14233.217173055375, 14266.785998210034, 14280.07179533327, 5915.231019967463, 6068.575199736064, 6077.36187700294, 6084.60450070744, 6315.463185629273, 3576.3741345264475, 3588.5873124590485, 3684.8157592120288, 3739.513863197767, 3864.632859878307, 2458.188797696756, 2484.018812569061, 2487.017628036915, 2488.5147396257917, 2504.649349091129, 391.0399968078361, 393.7066885170509, 397.2075708960642, 398.26718837339433, 411.4548223603737, 451.44021413447086, 453.4807645246241, 469.5421963585477, 475.81443237137546, 476.94753577107184, 7030.683619237147, 7292.015577896916, 7347.485676002629, 7464.589447580327, 7521.8119547010665, 534.4335004894824, 538.9407165211824, 564.8051422259314, 566.6955124242799, 569.6041251330286, 3625.2930678517964, 3745.7190710696805, 3775.226078653924, 3840.7695538267794, 3865.109085393453, 35519.289614208086, 35667.0, 35771.28457430837, 35962.460563091576, 36074.27851443009, 32775.50836737451, 32796.18009549733, 33052.504212436834, 33308.50037249443, 33867.491987620204, 29141.271468213465, 29420.11739647814, 29553.113406597407, 29595.85202073976, 29809.701902981127, 7067.893981590383, 7125.5368401053765, 7158.785236442706, 7437.776866694008, 7451.2921272479025, 21295.254666087232, 21577.0, 21579.784202157778, 21692.24077157292, 21885.234016809336, 27556.862215688794, 27635.30911727223, 27759.0, 27800.0, 27955.440891181657, 6448.9032664511005, 6513.609183448819, 6528.836779080561, 6659.733610655519, 6705.832354191184, 10039.0, 10220.795584088426, 10354.71909326234, 10496.632617858339, 10525.263231573716, 10009.599616015277, 10155.492225388552, 10159.441230732085, 10385.117265032255, 10531.05220530156, 9519.57161927701, 9952.651657191964, 9961.651342202988, 9992.450415227233, 10144.391336519833, 7479.326860582356, 9349.298802589698, 9384.436933783994, 9832.852507212541, 9837.9016209837, 8187.345012399113, 8310.584470776557, 8428.789280268042, 8435.240828325235, 8538.40231183836, 8246.257836794717, 8360.163983601591, 8390.0, 8397.874031889649, 8428.241458268785]}, "summary": {"suite": 6154.842048682604, "subtests": {"jquery.html": {"filtered": 7459.402226178863, "value": 7459.402226178863}, "dojo.html": {"filtered": 4459.788306438808, "value": 4459.788306438808}, "mootools.html": {"filtered": 4548.881746554248, "value": 4548.881746554248}, "ext.html": {"filtered": 17382.41824531465, "value": 17382.41824531465}, "prototype.html": {"filtered": 4054.880775959799, "value": 4054.880775959799}, "yui.html": {"filtered": 5096.185997953827, "value": 5096.185997953827}}}, "test_machine": {"platform": "x86_64", "osversion": "6.2.9200", "os": "win", "name": "T-W864-IX-115"}, "testrun": {"date": 1445648521, "suite": "dromaeo_css", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151023160116", "branch": "Fx-Team-Non-PGO", "revision": "335ea7a95492650e23c9ba14648d4f43620babab"}}, {"talos_counters": {}, "results": {"audio-dft": [141.0, 141.0, 140.0, 147.0, 148.0, 139.0, 140.0, 143.0, 140.0, 139.0], "ai-astar": [112.0, 113.0, 107.0, 112.0, 113.0, 112.0, 112.0, 112.0, 106.0, 111.0], "imaging-gaussian-blur": [113.0, 110.0, 111.0, 112.0, 110.0, 110.0, 111.0, 111.0, 110.0, 112.0], "audio-beat-detection": [136.0, 133.0, 150.0, 160.0, 131.0, 134.0, 137.0, 132.0, 161.0, 133.0], "json-stringify-tinderbox": [68.0, 69.0, 68.0, 67.0, 68.0, 68.0, 71.0, 68.0, 68.0, 68.0], "stanford-crypto-pbkdf2": [150.0, 153.0, 160.0, 159.0, 161.0, 151.0, 160.0, 160.0, 159.0, 160.0], "json-parse-financial": [69.0, 71.0, 71.0, 71.0, 70.0, 79.0, 70.0, 83.0, 70.0, 69.0], "stanford-crypto-sha256-iterative": [76.0, 75.0, 76.0, 75.0, 75.0, 75.0, 75.0, 75.0, 75.0, 76.0], "audio-fft": [69.0, 72.0, 71.0, 97.0, 73.0, 69.0, 69.0, 72.0, 101.0, 67.0], "imaging-darkroom": [132.0, 132.0, 132.0, 134.0, 133.0, 132.0, 131.0, 131.0, 132.0, 131.0], "stanford-crypto-ccm": [125.0, 124.0, 128.0, 125.0, 137.0, 127.0, 133.0, 124.0, 132.0, 125.0], "audio-oscillator": [92.0, 92.0, 112.0, 91.0, 91.0, 92.0, 121.0, 91.0, 91.0, 117.0], "stanford-crypto-aes": [92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 92.0, 93.0, 93.0, 91.0], "imaging-desaturate": [96.0, 97.0, 96.0, 96.0, 96.0, 104.0, 97.0, 96.0, 97.0, 96.0]}, "summary": {"suite": 1502.3, "subtests": {"audio-fft": {"filtered": 76.0, "value": 76.0}, "stanford-crypto-pbkdf2": {"filtered": 157.3, "value": 157.3}, "audio-beat-detection": {"filtered": 140.7, "value": 140.7}, "stanford-crypto-ccm": {"filtered": 128.0, "value": 128.0}, "imaging-darkroom": {"filtered": 132.0, "value": 132.0}, "json-parse-financial": {"filtered": 72.3, "value": 72.3}, "audio-oscillator": {"filtered": 99.0, "value": 99.0}, "ai-astar": {"filtered": 111.0, "value": 111.0}, "audio-dft": {"filtered": 141.8, "value": 141.8}, "stanford-crypto-sha256-iterative": {"filtered": 75.3, "value": 75.3}, "json-stringify-tinderbox": {"filtered": 68.3, "value": 68.3}, "imaging-gaussian-blur": {"filtered": 111.0, "value": 111.0}, "stanford-crypto-aes": {"filtered": 92.5, "value": 92.5}, "imaging-desaturate": {"filtered": 97.1, "value": 97.1}}}, "test_machine": {"platform": "x86_64", "osversion": "6.2.9200", "os": "win", "name": "T-W864-IX-115"}, "testrun": {"date": 1445648521, "suite": "kraken", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151023160116", "branch": "Fx-Team-Non-PGO", "revision": "335ea7a95492650e23c9ba14648d4f43620babab"}}, {"talos_counters": {}, "results": {"DeltaBlue": [175.34630896019638], "Encrypt": [285.7959416976279], "NavierStokes": [4864.077669902913], "Decrypt": [5045.226130653266], "Splay": [666.6666666666666], "Richards": [164.82610845557937], "RegExp": [29028.571428571428], "Earley": [662.2516556291391], "Boyer": [12746.835443037975], "RayTrace": [1567.398119122257]}, "summary": {"suite": 20327.070444577246, "subtests": {"DeltaBlue": {"filtered": 377.0709539999999, "value": 377.0709539999999}, "Encrypt": {"filtered": 931.3673189999998, "value": 931.3673189999998}, "NavierStokes": {"filtered": 305.0938123752493, "value": 305.0938123752493}, "Decrypt": {"filtered": 52.75898306772911, "value": 52.75898306772911}, "Splay": {"filtered": 122.23650000000005, "value": 122.23650000000005}, "RegExp": {"filtered": 31.38235728346456, "value": 31.38235728346456}, "Richards": {"filtered": 214.177234, "value": 214.177234}, "Earley": {"filtered": 1006.35913, "value": 1006.35913}, "Boyer": {"filtered": 52.28458490566033, "value": 52.28458490566033}, "RayTrace": {"filtered": 472.1129819999999, "value": 472.1129819999999}}}, "test_machine": {"platform": "x86_64", "osversion": "6.2.9200", "os": "win", "name": "T-W864-IX-115"}, "testrun": {"date": 1445648521, "suite": "v8_7", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151023160116", "branch": "Fx-Team-Non-PGO", "revision": "335ea7a95492650e23c9ba14648d4f43620babab"}}]
18:22:31 INFO - RETURN: dromaeo_css: 6154.84
18:22:31 INFO - RETURN: kraken: 1502.30
18:22:31 INFO - RETURN: v8_7: 20327.07
18:22:31 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,31]]", "result": "20327.07"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,31]]", "result": "1502.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,31]]", "result": "6154.84"}}}
18:22:31 INFO - Return code: 0
18:22:31 INFO - # TBPL SUCCESS #
18:22:31 INFO - Running post-action listener: _resource_record_post_action
18:22:31 INFO - Running post-run listener: _resource_record_post_run
18:22:32 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 282845184; Write bytes: 389302272; Read time: 2581090; Write time: 4604730
18:22:32 INFO - install - Wall time: 2s; CPU: 14.0%; Read bytes: 0; Write bytes: 16384; Read time: 0; Write time: 80
18:22:32 INFO - run-tests - Wall time: 1231s; CPU: 13.0%; Read bytes: 282845184; Write bytes: 351840256; Read time: 2581090; Write time: 4480240
18:22:32 INFO - Running post-run listener: _upload_blobber_files
18:22:32 INFO - Blob upload gear active.
18:22:32 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
18:22:32 INFO - Copying logs to upload dir...
18:22:32 INFO - mkdir: C:\slave\test\build\upload\logs
program finished with exit code 0
elapsedTime=1477.484000
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 36 secs) (at 2015-10-23 18:22:32.665319) =========
========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 18:22:32.666297) =========
'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f'
in dir C:\slave\test\properties (timeout 1200 secs)
watching logfiles {}
argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
COMMONPROGRAMW6432=C:\Program Files\Common Files
COMPUTERNAME=T-W864-IX-115
COMSPEC=C:\windows\system32\cmd.exe
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld.T-W864-IX-115
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Local
LOGONSERVER=\\T-W864-IX-115
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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_ARCHITEW6432=AMD64
PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files (x86)
PROGRAMFILES(X86)=C:\Program Files (x86)
PROGRAMW6432=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test\properties
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W864-IX-115
USERDOMAIN_ROAMINGPROFILE=T-W864-IX-115
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W864-IX-115
WINDIR=C:\windows
XPCOM_DEBUG_BREAK=warn
using PTY: False
build_url:https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/whc_GMA1T_OHG4w0nsVwpg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip'
========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 18:22:32.825462) =========
========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2015-10-23 18:22:32.825901) =========
'rm' '-f' 'oauth.txt'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
COMMONPROGRAMW6432=C:\Program Files\Common Files
COMPUTERNAME=T-W864-IX-115
COMSPEC=C:\windows\system32\cmd.exe
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld.T-W864-IX-115
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-115\AppData\Local
LOGONSERVER=\\T-W864-IX-115
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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_ARCHITEW6432=AMD64
PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files (x86)
PROGRAMFILES(X86)=C:\Program Files (x86)
PROGRAMW6432=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W864-IX-115
USERDOMAIN_ROAMINGPROFILE=T-W864-IX-115
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W864-IX-115
WINDIR=C:\windows
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.100000
========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2015-10-23 18:22:33.892624) =========
========= Started reboot slave lost (results: 0, elapsed: 1 secs) (at 2015-10-23 18:22:33.892990) =========
========= Finished reboot slave lost (results: 0, elapsed: 1 secs) (at 2015-10-23 18:22:34.958006) =========