builder: mozilla-central_win8_64_test-svgr-pgo slave: t-w864-ix-162 starttime: 1453826125.92 results: success (0) buildid: 20160126043024 builduid: dcf95949083f4e61b16fb9df04565f26 revision: c0ba5835ca489d15f8f170d5deb01f8dad92709a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:25.918511) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:25.918954) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:25.919244) ========= 'cd' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['cd'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-162\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-162 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-162 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-162\AppData\Local LOGONSERVER=\\T-W864-IX-162 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-pgo 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-162 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-162 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-162 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False C:\slave\test-pgo program finished with exit code 0 elapsedTime=0.751000 basedir: 'C:\\slave\\test-pgo' ========= master_lag: 0.06 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:26.730971) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:26.731275) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:26.882342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:26.882704) ========= 'rm' '-rf' 'properties' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-162\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-162 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-162 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-162\AppData\Local LOGONSERVER=\\T-W864-IX-162 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-pgo 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-162 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-162 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-162 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.201000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:27.104260) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:27.104573) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:27.104925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:27.105198) ========= 'bash' '-c' 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-162\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-162 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-162 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-162\AppData\Local LOGONSERVER=\\T-W864-IX-162 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-pgo 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-162 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-162 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-162 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False --08:35:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.45 GB/s 08:35:27 (2.45 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.650000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:27.772925) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-01-26 08:35:27.773239) ========= 'rm' '-rf' 'scripts' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-162\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-162 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-162 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-162\AppData\Local LOGONSERVER=\\T-W864-IX-162 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-pgo 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-162 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-162 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-162 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=2.606000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-01-26 08:35:30.394972) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-26 08:35:30.395377) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev c0ba5835ca489d15f8f170d5deb01f8dad92709a --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c0ba5835ca489d15f8f170d5deb01f8dad92709a --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-162\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-162 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-162 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-162\AppData\Local LOGONSERVER=\\T-W864-IX-162 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-pgo 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-162 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-162 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-162 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 2016-01-26 08:35:30,674 truncating revision to first 12 chars 2016-01-26 08:35:30,674 Setting DEBUG logging. 2016-01-26 08:35:30,674 attempt 1/10 2016-01-26 08:35:30,674 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c0ba5835ca48?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-26 08:35:31,068 unpacking tar archive at: mozilla-central-c0ba5835ca48/testing/mozharness/ program finished with exit code 0 elapsedTime=1.283000 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-26 08:35:31.781312) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:31.781704) ========= 'echo' u'c0ba5835ca489d15f8f170d5deb01f8dad92709a' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'c0ba5835ca489d15f8f170d5deb01f8dad92709a'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-162\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-162 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-162 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-162\AppData\Local LOGONSERVER=\\T-W864-IX-162 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-pgo 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-162 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-162 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-162 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False c0ba5835ca489d15f8f170d5deb01f8dad92709a program finished with exit code 0 elapsedTime=0.100000 script_repo_revision: 'c0ba5835ca489d15f8f170d5deb01f8dad92709a' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:31.901156) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:31.901482) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 08:35:31.974408) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-01-26 08:35:31.974809) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'svgr' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox' in dir C:\slave\test-pgo\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'svgr', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-162\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-162 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-162 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-162\AppData\Local LOGONSERVER=\\T-W864-IX-162 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-pgo/buildprops.json PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo 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-162 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-162 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-162 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 08:35:32 INFO - MultiFileLogger online at 20160126 08:35:32 in C:\slave\test-pgo 08:35:32 INFO - Run as scripts/scripts/talos_script.py --suite svgr --add-option --webServer,localhost --branch-name Firefox --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:35:32 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 08:35:32 INFO - {'append_to_log': False, 08:35:32 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 08:35:32 INFO - 'blob_upload_branch': 'Firefox', 08:35:32 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 08:35:32 INFO - 'branch': 'Firefox', 08:35:32 INFO - 'buildbot_json_path': 'buildprops.json', 08:35:32 INFO - 'config_files': ('talos/windows_config.py',), 08:35:32 INFO - 'default_actions': ('clobber', 08:35:32 INFO - 'read-buildbot-config', 08:35:32 INFO - 'download-and-extract', 08:35:32 INFO - 'populate-webroot', 08:35:32 INFO - 'create-virtualenv', 08:35:32 INFO - 'install', 08:35:32 INFO - 'run-tests'), 08:35:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:35:32 INFO - 'download_minidump_stackwalk': True, 08:35:32 INFO - 'download_symbols': 'ondemand', 08:35:32 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 08:35:32 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 08:35:32 INFO - 'hg': 'c:/mozilla-build/hg/hg', 08:35:32 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 08:35:32 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 08:35:32 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 08:35:32 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 08:35:32 INFO - 'C:/mozilla-build/tooltool.py'), 08:35:32 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 08:35:32 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 08:35:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:35:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:35:32 INFO - 'installer_path': 'installer.exe', 08:35:32 INFO - 'log_level': 'info', 08:35:32 INFO - 'log_name': 'talos', 08:35:32 INFO - 'log_to_console': True, 08:35:32 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 08:35:32 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 08:35:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 08:35:32 INFO - 'opt_config_files': (), 08:35:32 INFO - 'pip_index': False, 08:35:32 INFO - 'sps_profile': False, 08:35:32 INFO - 'sps_profile_interval': 0, 08:35:32 INFO - 'suite': 'svgr', 08:35:32 INFO - 'system_bits': '32', 08:35:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:35:32 INFO - 'title': 'T-W864-IX-162', 08:35:32 INFO - 'use_talos_json': True, 08:35:32 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 08:35:32 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 08:35:32 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 08:35:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:35:32 INFO - 'work_dir': 'build'} 08:35:32 INFO - ##### 08:35:32 INFO - ##### Running clobber step. 08:35:32 INFO - ##### 08:35:32 INFO - Running pre-action listener: _resource_record_pre_action 08:35:32 INFO - Running main action method: clobber 08:35:32 INFO - rmtree: C:\slave\test-pgo\build 08:35:32 INFO - Using _rmtree_windows ... 08:35:32 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 08:37:46 INFO - Running post-action listener: _resource_record_post_action 08:37:46 INFO - ##### 08:37:46 INFO - ##### Running read-buildbot-config step. 08:37:46 INFO - ##### 08:37:46 INFO - Running pre-action listener: _resource_record_pre_action 08:37:46 INFO - Running main action method: read_buildbot_config 08:37:46 INFO - Using buildbot properties: 08:37:46 INFO - { 08:37:46 INFO - "project": "", 08:37:46 INFO - "product": "firefox", 08:37:46 INFO - "script_repo_revision": "production", 08:37:46 INFO - "builddir": "mozilla-central_win8_64_test-svgr", 08:37:46 INFO - "repository": "", 08:37:46 INFO - "buildername": "Windows 8 64-bit mozilla-central pgo talos svgr", 08:37:46 INFO - "buildid": "20160126043024", 08:37:46 INFO - "pgo_build": "True", 08:37:46 INFO - "basedir": "C:\\slave\\test-pgo", 08:37:46 INFO - "buildnumber": 105, 08:37:46 INFO - "slavename": "t-w864-ix-162", 08:37:46 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 08:37:46 INFO - "platform": "win8_64", 08:37:46 INFO - "scheduler": "tests-mozilla-central-win64-pgo-talos", 08:37:46 INFO - "branch": "mozilla-central", 08:37:46 INFO - "repo_path": "mozilla-central", 08:37:46 INFO - "revision": "c0ba5835ca489d15f8f170d5deb01f8dad92709a", 08:37:46 INFO - "stage_platform": "win64-pgo", 08:37:46 INFO - "builduid": "dcf95949083f4e61b16fb9df04565f26", 08:37:46 INFO - "slavebuilddir": "test" 08:37:46 INFO - } 08:37:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.zip. 08:37:46 INFO - Running post-action listener: _resource_record_post_action 08:37:46 INFO - ##### 08:37:46 INFO - ##### Running download-and-extract step. 08:37:46 INFO - ##### 08:37:46 INFO - Running pre-action listener: _resource_record_pre_action 08:37:46 INFO - Running main action method: download_and_extract 08:37:46 INFO - mkdir: C:\slave\test-pgo\build\tests 08:37:46 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')]} 08:37:46 INFO - https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:37:46 INFO - trying https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/test_packages.json 08:37:46 INFO - Downloading https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 08:37:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 08:37:47 INFO - Downloaded 1301 bytes. 08:37:47 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 08:37:47 INFO - Using the following test package requirements: 08:37:47 INFO - {u'common': [u'firefox-47.0a1.en-US.win64.common.tests.zip'], 08:37:47 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.win64.common.tests.zip', 08:37:47 INFO - u'firefox-47.0a1.en-US.win64.cppunittest.tests.zip'], 08:37:47 INFO - u'gtest': [u'firefox-47.0a1.en-US.win64.common.tests.zip'], 08:37:47 INFO - u'jittest': [u'firefox-47.0a1.en-US.win64.common.tests.zip', 08:37:47 INFO - u'jsshell-win64.zip'], 08:37:47 INFO - u'mochitest': [u'firefox-47.0a1.en-US.win64.common.tests.zip', 08:37:47 INFO - u'firefox-47.0a1.en-US.win64.mochitest.tests.zip'], 08:37:47 INFO - u'mozbase': [u'firefox-47.0a1.en-US.win64.common.tests.zip'], 08:37:47 INFO - u'reftest': [u'firefox-47.0a1.en-US.win64.common.tests.zip', 08:37:47 INFO - u'firefox-47.0a1.en-US.win64.reftest.tests.zip'], 08:37:47 INFO - u'talos': [u'firefox-47.0a1.en-US.win64.common.tests.zip', 08:37:47 INFO - u'firefox-47.0a1.en-US.win64.talos.tests.zip'], 08:37:47 INFO - u'web-platform': [u'firefox-47.0a1.en-US.win64.common.tests.zip', 08:37:47 INFO - u'firefox-47.0a1.en-US.win64.web-platform.tests.zip'], 08:37:47 INFO - u'webapprt': [u'firefox-47.0a1.en-US.win64.common.tests.zip'], 08:37:47 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.win64.common.tests.zip', 08:37:47 INFO - u'firefox-47.0a1.en-US.win64.xpcshell.tests.zip']} 08:37:47 INFO - Downloading packages: [u'firefox-47.0a1.en-US.win64.common.tests.zip'] for test suite category: common 08:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:37:47 INFO - https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 08:37:47 INFO - trying https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.common.tests.zip 08:37:47 INFO - Downloading https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.common.tests.zip to C:\slave\test-pgo\build\firefox-47.0a1.en-US.win64.common.tests.zip 08:37:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-47.0a1.en-US.win64.common.tests.zip'}, attempt #1 08:37:50 INFO - Downloaded 17310029 bytes. 08:37:50 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-47.0a1.en-US.win64.common.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 08:37:50 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-47.0a1.en-US.win64.common.tests.zip -d C:\slave\test-pgo\build\tests 08:38:21 INFO - Return code: 0 08:38:21 INFO - Downloading packages: [u'firefox-47.0a1.en-US.win64.common.tests.zip', u'firefox-47.0a1.en-US.win64.talos.tests.zip'] for test suite category: talos 08:38:21 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')]} 08:38:21 INFO - https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 08:38:21 INFO - trying https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.common.tests.zip 08:38:21 INFO - Downloading https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.common.tests.zip to C:\slave\test-pgo\build\firefox-47.0a1.en-US.win64.common.tests.zip 08:38:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-47.0a1.en-US.win64.common.tests.zip'}, attempt #1 08:38:22 INFO - Downloaded 17310029 bytes. 08:38:22 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-47.0a1.en-US.win64.common.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 08:38:22 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-47.0a1.en-US.win64.common.tests.zip -d C:\slave\test-pgo\build\tests 08:38:47 INFO - Return code: 0 08:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:47 INFO - https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.talos.tests.zip matches https://queue.taskcluster.net 08:38:47 INFO - trying https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.talos.tests.zip 08:38:47 INFO - Downloading https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.talos.tests.zip to C:\slave\test-pgo\build\firefox-47.0a1.en-US.win64.talos.tests.zip 08:38:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-47.0a1.en-US.win64.talos.tests.zip'}, attempt #1 08:38:48 INFO - Downloaded 10706572 bytes. 08:38:48 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-47.0a1.en-US.win64.talos.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 08:38:48 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-47.0a1.en-US.win64.talos.tests.zip -d C:\slave\test-pgo\build\tests 08:38:50 INFO - Return code: 0 08:38: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')]} 08:38:50 INFO - https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.zip matches https://queue.taskcluster.net 08:38:50 INFO - trying https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.zip 08:38:50 INFO - Downloading https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.zip to C:\slave\test-pgo\build\firefox-47.0a1.en-US.win64.zip 08:38:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-47.0a1.en-US.win64.zip'}, attempt #1 08:38:52 INFO - Downloaded 56251193 bytes. 08:38:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.zip 08:38:52 INFO - mkdir: C:\slave\test-pgo\properties 08:38:52 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 08:38:52 INFO - Writing to file C:\slave\test-pgo\properties\build_url 08:38:52 INFO - Contents: 08:38:52 INFO - build_url:https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.zip 08:38:52 INFO - Running post-action listener: _resource_record_post_action 08:38:52 INFO - Running post-action listener: set_extra_try_arguments 08:38:52 INFO - ##### 08:38:52 INFO - ##### Running populate-webroot step. 08:38:52 INFO - ##### 08:38:52 INFO - Running pre-action listener: _resource_record_pre_action 08:38:52 INFO - Running main action method: populate_webroot 08:38:52 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 08:38:52 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 08:38:52 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 08:38:52 INFO - u'--tppagecycles', 08:38:52 INFO - u'7'], 08:38:52 INFO - u'tests': [u'tsvgm']}}, 08:38:52 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 08:38:52 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 08:38:52 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 08:38:52 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 08:38:52 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 08:38:52 INFO - u'tests': [u'dromaeo_css', 08:38:52 INFO - u'kraken', 08:38:52 INFO - u'v8_7']}, 08:38:52 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:52 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 08:38:52 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:52 INFO - u'talos_options': [u'--e10s'], 08:38:52 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 08:38:52 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:52 INFO - u'tests': [u'damp', u'tps']}, 08:38:52 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:52 INFO - u'talos_options': [u'--e10s'], 08:38:52 INFO - u'tests': [u'damp', u'tps']}, 08:38:52 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 08:38:52 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 08:38:52 INFO - u'other': {u'tests': [u'a11yr', 08:38:52 INFO - u'ts_paint', 08:38:52 INFO - u'tpaint', 08:38:52 INFO - u'sessionrestore', 08:38:52 INFO - u'sessionrestore_no_auto_restore']}, 08:38:52 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 08:38:52 INFO - u'tests': [u'a11yr', 08:38:52 INFO - u'ts_paint', 08:38:52 INFO - u'tpaint', 08:38:52 INFO - u'sessionrestore', 08:38:52 INFO - u'sessionrestore_no_auto_restore']}, 08:38:52 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 08:38:52 INFO - u'tests': [u'a11yr', 08:38:52 INFO - u'ts_paint', 08:38:52 INFO - u'tpaint', 08:38:52 INFO - u'sessionrestore', 08:38:52 INFO - u'sessionrestore_no_auto_restore']}, 08:38:52 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 08:38:52 INFO - u'tests': [u'a11yr', 08:38:52 INFO - u'ts_paint', 08:38:52 INFO - u'tpaint', 08:38:52 INFO - u'sessionrestore', 08:38:52 INFO - u'sessionrestore_no_auto_restore']}, 08:38:52 INFO - u'other_l64': {u'tests': [u'a11yr', 08:38:52 INFO - u'ts_paint', 08:38:52 INFO - u'tpaint', 08:38:52 INFO - u'sessionrestore', 08:38:52 INFO - u'sessionrestore_no_auto_restore']}, 08:38:52 INFO - u'other_nol64': {u'tests': [u'a11yr', 08:38:52 INFO - u'ts_paint', 08:38:52 INFO - u'tpaint', 08:38:52 INFO - u'sessionrestore', 08:38:52 INFO - u'sessionrestore_no_auto_restore']}, 08:38:52 INFO - u'svgr': {u'tests': [u'tsvgx', 08:38:52 INFO - u'tsvgr_opacity', 08:38:52 INFO - u'tart', 08:38:52 INFO - u'tscrollx', 08:38:52 INFO - u'cart']}, 08:38:52 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 08:38:52 INFO - u'tests': [u'tsvgx', 08:38:52 INFO - u'tsvgr_opacity', 08:38:52 INFO - u'tart', 08:38:52 INFO - u'tscrollx', 08:38:52 INFO - u'cart']}, 08:38:52 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:52 INFO - u'tests': [u'tp5o']}, 08:38:52 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:52 INFO - u'talos_options': [u'--e10s'], 08:38:52 INFO - u'tests': [u'tp5o']}, 08:38:52 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:52 INFO - u'talos_options': [u'--xperf_path', 08:38:52 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:52 INFO - u'tests': [u'tp5n']}, 08:38:52 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:52 INFO - u'talos_options': [u'--e10s', 08:38:52 INFO - u'--xperf_path', 08:38:52 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:52 INFO - u'tests': [u'tp5n']}}, 08:38:52 INFO - u'talos.zip': {u'path': u'', 08:38:52 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:38:52 INFO - Running post-action listener: _resource_record_post_action 08:38:52 INFO - ##### 08:38:52 INFO - ##### Running create-virtualenv step. 08:38:52 INFO - ##### 08:38:52 INFO - Running pre-action listener: _resource_record_pre_action 08:38:52 INFO - Running main action method: create_virtualenv 08:38:52 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 08:38:52 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 08:38:52 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 08:38:52 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 08:38:52 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build 08:38:52 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv 08:39:01 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 08:39:04 INFO - Installing distribute......................................................................................................................................................................................done. 08:39:06 INFO - Return code: 0 08:39:06 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 08:39:06 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')]} 08:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:06 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')]} 08:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:06 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x023A76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0245BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01F53690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-162', 'USERDOMAIN': 'T-W864-IX-162', '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-162', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-162', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/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-162\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', '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 08:39:06 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 08:39:06 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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 08:39:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:06 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:06 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:06 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:06 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:06 INFO - 'DCLOCATION': 'SCL3', 08:39:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:06 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:06 INFO - 'HOMEDRIVE': 'C:', 08:39:06 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:06 INFO - 'KTS_VERSION': '1.19c', 08:39:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:06 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:06 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:06 INFO - 'MOZ_NO_REMOTE': '1', 08:39:06 INFO - 'NO_EM_RESTART': '1', 08:39:06 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:06 INFO - 'OS': 'Windows_NT', 08:39:06 INFO - 'OURDRIVE': 'C:', 08:39:06 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', 08:39:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:06 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:06 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:06 INFO - 'PROCESSOR_LEVEL': '6', 08:39:06 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:06 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:06 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:06 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:06 INFO - 'PROMPT': '$P$G', 08:39:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:06 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:06 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:06 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:06 INFO - 'SYSTEMDRIVE': 'C:', 08:39:06 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:06 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:06 INFO - 'TEST1': 'testie', 08:39:06 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:06 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:06 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:06 INFO - 'USERNAME': 'cltbld', 08:39:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:06 INFO - 'WINDIR': 'C:\\windows', 08:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:10 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:39:10 INFO - Downloading/unpacking pip>=1.5 08:39:14 INFO - Running setup.py egg_info for package pip 08:39:14 INFO - warning: no files found matching 'pip\cacert.pem' 08:39:14 INFO - warning: no files found matching '*.html' under directory 'docs' 08:39:14 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:39:14 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:39:14 INFO - Installing collected packages: pip 08:39:14 INFO - Found existing installation: pip 0.8.2 08:39:14 INFO - Uninstalling pip: 08:39:14 INFO - Successfully uninstalled pip 08:39:14 INFO - Running setup.py install for pip 08:39:14 INFO - warning: no files found matching 'pip\cacert.pem' 08:39:14 INFO - warning: no files found matching '*.html' under directory 'docs' 08:39:14 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:39:14 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:39:14 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:14 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:14 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:14 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:14 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:14 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:14 INFO - Successfully installed pip 08:39:14 INFO - Cleaning up... 08:39:14 INFO - Return code: 0 08:39:14 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 08:39:14 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')]} 08:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:14 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')]} 08:39:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:14 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x023A76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0245BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01F53690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-162', 'USERDOMAIN': 'T-W864-IX-162', '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-162', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-162', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/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-162\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', '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 08:39:14 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test-pgo\build 08:39:14 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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 08:39:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:14 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:14 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:14 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:14 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:14 INFO - 'DCLOCATION': 'SCL3', 08:39:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:14 INFO - 'HOMEDRIVE': 'C:', 08:39:14 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:14 INFO - 'KTS_VERSION': '1.19c', 08:39:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:14 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:14 INFO - 'MOZ_NO_REMOTE': '1', 08:39:14 INFO - 'NO_EM_RESTART': '1', 08:39:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:14 INFO - 'OS': 'Windows_NT', 08:39:14 INFO - 'OURDRIVE': 'C:', 08:39:14 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', 08:39:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:14 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:14 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:14 INFO - 'PROCESSOR_LEVEL': '6', 08:39:14 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:14 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:14 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:14 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:14 INFO - 'PROMPT': '$P$G', 08:39:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:14 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:14 INFO - 'SYSTEMDRIVE': 'C:', 08:39:14 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:14 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:14 INFO - 'TEST1': 'testie', 08:39:14 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:14 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:14 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:14 INFO - 'USERNAME': 'cltbld', 08:39:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:14 INFO - 'WINDIR': 'C:\\windows', 08:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:15 INFO - Downloading/unpacking psutil>=0.7.1 08:39:16 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 08:39:16 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 08:39:17 INFO - Installing collected packages: psutil 08:39:17 INFO - Successfully installed psutil 08:39:17 INFO - Cleaning up... 08:39:17 INFO - Return code: 0 08:39:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 08:39: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')]} 08:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39: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')]} 08:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:17 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x023A76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0245BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01F53690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-162', 'USERDOMAIN': 'T-W864-IX-162', '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-162', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-162', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/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-162\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', '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 08:39:17 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build 08:39:17 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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 08:39:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:17 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:17 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:17 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:17 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:17 INFO - 'DCLOCATION': 'SCL3', 08:39:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:17 INFO - 'HOMEDRIVE': 'C:', 08:39:17 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:17 INFO - 'KTS_VERSION': '1.19c', 08:39:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:17 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:17 INFO - 'MOZ_NO_REMOTE': '1', 08:39:17 INFO - 'NO_EM_RESTART': '1', 08:39:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:17 INFO - 'OS': 'Windows_NT', 08:39:17 INFO - 'OURDRIVE': 'C:', 08:39:17 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', 08:39:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:17 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:17 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:17 INFO - 'PROCESSOR_LEVEL': '6', 08:39:17 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:17 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:17 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:17 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:17 INFO - 'PROMPT': '$P$G', 08:39:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:17 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:17 INFO - 'SYSTEMDRIVE': 'C:', 08:39:17 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:17 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:17 INFO - 'TEST1': 'testie', 08:39:17 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:17 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:17 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:17 INFO - 'USERNAME': 'cltbld', 08:39:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:17 INFO - 'WINDIR': 'C:\\windows', 08:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:39:19 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 08:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 08:39:19 INFO - Installing collected packages: mozsystemmonitor 08:39:19 INFO - Running setup.py install for mozsystemmonitor 08:39:19 INFO - Successfully installed mozsystemmonitor 08:39:19 INFO - Cleaning up... 08:39:19 INFO - Return code: 0 08:39:19 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 08:39:19 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')]} 08:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:19 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')]} 08:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:19 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x023A76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0245BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01F53690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-162', 'USERDOMAIN': 'T-W864-IX-162', '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-162', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-162', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/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-162\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', '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 08:39:19 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build 08:39:19 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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 08:39:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:19 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:19 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:19 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:19 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:19 INFO - 'DCLOCATION': 'SCL3', 08:39:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:19 INFO - 'HOMEDRIVE': 'C:', 08:39:19 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:19 INFO - 'KTS_VERSION': '1.19c', 08:39:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:19 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:19 INFO - 'MOZ_NO_REMOTE': '1', 08:39:19 INFO - 'NO_EM_RESTART': '1', 08:39:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:19 INFO - 'OS': 'Windows_NT', 08:39:19 INFO - 'OURDRIVE': 'C:', 08:39:19 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', 08:39:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:19 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:19 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:19 INFO - 'PROCESSOR_LEVEL': '6', 08:39:19 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:19 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:19 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:19 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:19 INFO - 'PROMPT': '$P$G', 08:39:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:19 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:19 INFO - 'SYSTEMDRIVE': 'C:', 08:39:19 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:19 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:19 INFO - 'TEST1': 'testie', 08:39:19 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:19 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:19 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:19 INFO - 'USERNAME': 'cltbld', 08:39:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:19 INFO - 'WINDIR': 'C:\\windows', 08:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:19 INFO - Downloading/unpacking blobuploader==1.2.4 08:39:19 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 08:39:19 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 08:39:20 INFO - Downloading blobuploader-1.2.4.tar.gz 08:39:20 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 08:39:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:39:20 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 08:39:20 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 08:39:20 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 08:39:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:39:21 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 08:39:21 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 08:39:21 INFO - Downloading docopt-0.6.1.tar.gz 08:39:21 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 08:39:21 INFO - Installing collected packages: blobuploader, requests, docopt 08:39:21 INFO - Running setup.py install for blobuploader 08:39:21 INFO - Running setup.py install for requests 08:39:22 INFO - Running setup.py install for docopt 08:39:22 INFO - Successfully installed blobuploader requests docopt 08:39:22 INFO - Cleaning up... 08:39:23 INFO - Return code: 0 08:39:23 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 08:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:23 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023A76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0245BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01F53690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-162', 'USERDOMAIN': 'T-W864-IX-162', '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-162', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-162', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/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-162\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', '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 08:39:23 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 08:39:23 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:39:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:23 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:23 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:23 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:23 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:23 INFO - 'DCLOCATION': 'SCL3', 08:39:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:23 INFO - 'HOMEDRIVE': 'C:', 08:39:23 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:23 INFO - 'KTS_VERSION': '1.19c', 08:39:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:23 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:23 INFO - 'MOZ_NO_REMOTE': '1', 08:39:23 INFO - 'NO_EM_RESTART': '1', 08:39:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:23 INFO - 'OS': 'Windows_NT', 08:39:23 INFO - 'OURDRIVE': 'C:', 08:39:23 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', 08:39:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:23 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:23 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:23 INFO - 'PROCESSOR_LEVEL': '6', 08:39:23 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:23 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:23 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:23 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:23 INFO - 'PROMPT': '$P$G', 08:39:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:23 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:23 INFO - 'SYSTEMDRIVE': 'C:', 08:39:23 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:23 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:23 INFO - 'TEST1': 'testie', 08:39:23 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:23 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:23 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:23 INFO - 'USERNAME': 'cltbld', 08:39:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:23 INFO - 'WINDIR': 'C:\\windows', 08:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 08:39:23 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ykvo6w-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 08:39:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 08:39:23 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ios_dm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 08:39:24 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-hyvf8c-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 08:39:24 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-eujj_s-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 08:39:24 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-bap5w9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 08:39:24 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-iacfvh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 08:39:24 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-shvv1x-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 08:39:24 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-vohrvn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 08:39:24 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-nsq_sb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 08:39:24 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-kpra3t-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 08:39:24 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-wopuuu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 08:39:24 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 08:39:25 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-qsgegj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 08:39:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 08:39:25 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-tspbc1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 08:39:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 08:39:25 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-6pisfw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 08:39:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 08:39:25 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-odojdp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:39:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 08:39:25 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-swaq_b-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 08:39:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 08:39:25 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-nebrz4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 08:39:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:39:25 INFO - Running setup.py install for manifestparser 08:39:25 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:25 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:26 INFO - Running setup.py install for mozcrash 08:39:26 INFO - Running setup.py install for mozdebug 08:39:27 INFO - Running setup.py install for mozdevice 08:39:27 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:27 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:27 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:27 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:27 INFO - Running setup.py install for mozfile 08:39:28 INFO - Running setup.py install for mozhttpd 08:39:28 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:28 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:28 INFO - Running setup.py install for mozinfo 08:39:28 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:28 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:28 INFO - Running setup.py install for mozInstall 08:39:29 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:29 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:29 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:29 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:29 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:29 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:29 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:29 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:29 INFO - Running setup.py install for mozleak 08:39:29 INFO - Running setup.py install for mozlog 08:39:30 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:30 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:30 INFO - Running setup.py install for moznetwork 08:39:30 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:30 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:31 INFO - Running setup.py install for mozprocess 08:39:31 INFO - Running setup.py install for mozprofile 08:39:31 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:31 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:31 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:31 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:31 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:31 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:32 INFO - Running setup.py install for mozrunner 08:39:32 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:32 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:33 INFO - Running setup.py install for mozscreenshot 08:39:33 INFO - Running setup.py install for moztest 08:39:33 INFO - Running setup.py install for mozversion 08:39:34 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:34 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:39:34 INFO - Cleaning up... 08:39:34 INFO - Return code: 0 08:39:34 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 08:39: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')]} 08:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39: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')]} 08:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:34 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023A76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0245BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01F53690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-162', 'USERDOMAIN': 'T-W864-IX-162', '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-162', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-162', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/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-162\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', '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 08:39:34 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 08:39:34 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:39:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:34 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:34 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:34 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:34 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:34 INFO - 'DCLOCATION': 'SCL3', 08:39:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:34 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:34 INFO - 'HOMEDRIVE': 'C:', 08:39:34 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:34 INFO - 'KTS_VERSION': '1.19c', 08:39:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:34 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:34 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:34 INFO - 'MOZ_NO_REMOTE': '1', 08:39:34 INFO - 'NO_EM_RESTART': '1', 08:39:34 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:34 INFO - 'OS': 'Windows_NT', 08:39:34 INFO - 'OURDRIVE': 'C:', 08:39: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', 08:39:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:34 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:34 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:34 INFO - 'PROCESSOR_LEVEL': '6', 08:39:34 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:34 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:34 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:34 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:34 INFO - 'PROMPT': '$P$G', 08:39:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:34 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:34 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:34 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:34 INFO - 'SYSTEMDRIVE': 'C:', 08:39:34 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:34 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:34 INFO - 'TEST1': 'testie', 08:39:34 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:34 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:34 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:34 INFO - 'USERNAME': 'cltbld', 08:39:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:34 INFO - 'WINDIR': 'C:\\windows', 08:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 08:39:34 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-kmnnjn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 08:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1)) 08:39:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 08:39:34 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ud3yyt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 08:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 08:39:34 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 08:39:35 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-oce7wa-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 08:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 08:39:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 08:39:35 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ajhovj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 08:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 08:39:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 08:39:35 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-46qpva-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 08:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5)) 08:39:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 08:39:35 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-gwxypd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 08:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6)) 08:39:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 08:39:35 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-2zng2y-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 08:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7)) 08:39:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 08:39:35 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-mbtdwr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 08:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8)) 08:39:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 08:39:35 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-rrk2wo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 08:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9)) 08:39:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 08:39:35 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-jwzleb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 08:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 08:39:35 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 08:39:35 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-siopba-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11)) 08:39:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 08:39:36 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-lhh7wf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12)) 08:39:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 08:39:36 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-rtczyc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13)) 08:39:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 08:39:36 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-0fz6rv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 08:39:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 08:39:36 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-p_x2i9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15)) 08:39:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 08:39:36 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-4t6oy8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16)) 08:39:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 08:39:36 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-hq4gqo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17)) 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 08:39:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 08:39:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 08:39: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 08:39: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 08:39:37 INFO - Downloading blessings-1.6.tar.gz 08:39:37 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 08:39:38 INFO - Installing collected packages: blessings 08:39:38 INFO - Running setup.py install for blessings 08:39:38 INFO - Successfully installed blessings 08:39:38 INFO - Cleaning up... 08:39:41 INFO - Return code: 0 08:39:41 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 08:39:41 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 08:39:41 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 08:39:41 INFO - Reading from file tmpfile_stdout 08:39:41 INFO - Using _rmtree_windows ... 08:39:41 INFO - Using _rmtree_windows ... 08:39:41 INFO - Current package versions: 08:39:41 INFO - blessings == 1.6 08:39:41 INFO - blobuploader == 1.2.4 08:39:41 INFO - docopt == 0.6.1 08:39:41 INFO - manifestparser == 1.1 08:39:41 INFO - mozInstall == 1.12 08:39:41 INFO - mozcrash == 0.16 08:39:41 INFO - mozdebug == 0.1 08:39:41 INFO - mozdevice == 0.48 08:39:41 INFO - mozfile == 1.2 08:39:41 INFO - mozhttpd == 0.7 08:39:41 INFO - mozinfo == 0.9 08:39:41 INFO - mozleak == 0.1 08:39:41 INFO - mozlog == 3.1 08:39:41 INFO - moznetwork == 0.27 08:39:41 INFO - mozprocess == 0.22 08:39:41 INFO - mozprofile == 0.28 08:39:41 INFO - mozrunner == 6.11 08:39:41 INFO - mozscreenshot == 0.1 08:39:41 INFO - mozsystemmonitor == 0.0 08:39:41 INFO - moztest == 0.7 08:39:41 INFO - mozversion == 1.4 08:39:41 INFO - psutil == 3.1.1 08:39:41 INFO - requests == 1.2.3 08:39:41 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 08:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:41 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023A76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0245BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01F53690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-162', 'USERDOMAIN': 'T-W864-IX-162', '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-162', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-162', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/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-162\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', '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 08:39:41 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\talos 08:39:41 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test-pgo\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:39:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:41 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:41 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:41 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:41 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:41 INFO - 'DCLOCATION': 'SCL3', 08:39:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:41 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:41 INFO - 'HOMEDRIVE': 'C:', 08:39:41 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:41 INFO - 'KTS_VERSION': '1.19c', 08:39:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:41 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:41 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:41 INFO - 'MOZ_NO_REMOTE': '1', 08:39:41 INFO - 'NO_EM_RESTART': '1', 08:39:41 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:41 INFO - 'OS': 'Windows_NT', 08:39:41 INFO - 'OURDRIVE': 'C:', 08:39:41 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', 08:39:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:41 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:41 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:41 INFO - 'PROCESSOR_LEVEL': '6', 08:39:41 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:41 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:41 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:41 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:41 INFO - 'PROMPT': '$P$G', 08:39:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:41 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:41 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:41 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:41 INFO - 'SYSTEMDRIVE': 'C:', 08:39:41 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:41 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:41 INFO - 'TEST1': 'testie', 08:39:41 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:41 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:41 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:41 INFO - 'USERNAME': 'cltbld', 08:39:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:41 INFO - 'WINDIR': 'C:\\windows', 08:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 9)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 08:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 08:39:41 INFO - Cleaning up... 08:39:41 INFO - Return code: 0 08:39:41 INFO - Installing jsonschema into virtualenv C:\slave\test-pgo\build/venv 08:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:41 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023A76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0245BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01F53690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-162', 'USERDOMAIN': 'T-W864-IX-162', '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-162', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-162', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/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-162\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', '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 08:39:41 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test-pgo\build 08:39:41 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:39:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:41 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:41 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:41 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:41 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:41 INFO - 'DCLOCATION': 'SCL3', 08:39:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:41 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:41 INFO - 'HOMEDRIVE': 'C:', 08:39:41 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:41 INFO - 'KTS_VERSION': '1.19c', 08:39:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:41 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:41 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:41 INFO - 'MOZ_NO_REMOTE': '1', 08:39:41 INFO - 'NO_EM_RESTART': '1', 08:39:41 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:41 INFO - 'OS': 'Windows_NT', 08:39:41 INFO - 'OURDRIVE': 'C:', 08:39:41 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', 08:39:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:41 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:41 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:41 INFO - 'PROCESSOR_LEVEL': '6', 08:39:41 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:41 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:41 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:41 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:41 INFO - 'PROMPT': '$P$G', 08:39:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:41 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:41 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:41 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:41 INFO - 'SYSTEMDRIVE': 'C:', 08:39:41 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:41 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:41 INFO - 'TEST1': 'testie', 08:39:41 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:41 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:41 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:41 INFO - 'USERNAME': 'cltbld', 08:39:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:41 INFO - 'WINDIR': 'C:\\windows', 08:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:42 INFO - Downloading/unpacking jsonschema 08:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:39:43 INFO - Downloading/unpacking functools32 (from jsonschema) 08:39:43 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 08:39:43 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 08:39:43 INFO - Downloading functools32-3.2.3-2.tar.gz 08:39:43 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\functools32\setup.py) egg_info for package functools32 08:39:43 INFO - warning: no files found matching '*.txt' 08:39:43 INFO - no previously-included directories found matching 'build' 08:39:43 INFO - no previously-included directories found matching 'dist' 08:39:43 INFO - no previously-included directories found matching '.git*' 08:39:43 INFO - Installing collected packages: jsonschema, functools32 08:39:43 INFO - Running setup.py install for functools32 08:39:43 INFO - warning: no files found matching '*.txt' 08:39:43 INFO - no previously-included directories found matching 'build' 08:39:43 INFO - no previously-included directories found matching 'dist' 08:39:43 INFO - no previously-included directories found matching '.git*' 08:39:43 INFO - Successfully installed jsonschema functools32 08:39:43 INFO - Cleaning up... 08:39:44 INFO - Return code: 0 08:39:44 INFO - Installing flake8 into virtualenv C:\slave\test-pgo\build/venv 08:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:44 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023A76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0245BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01F53690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-162', 'USERDOMAIN': 'T-W864-IX-162', '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-162', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-162', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/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-162\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', '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 08:39:44 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in C:\slave\test-pgo\build 08:39:44 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:39:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:44 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:44 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:44 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:44 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:44 INFO - 'DCLOCATION': 'SCL3', 08:39:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:44 INFO - 'HOMEDRIVE': 'C:', 08:39:44 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:44 INFO - 'KTS_VERSION': '1.19c', 08:39:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:44 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:44 INFO - 'MOZ_NO_REMOTE': '1', 08:39:44 INFO - 'NO_EM_RESTART': '1', 08:39:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:44 INFO - 'OS': 'Windows_NT', 08:39:44 INFO - 'OURDRIVE': 'C:', 08:39:44 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', 08:39:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:44 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:44 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:44 INFO - 'PROCESSOR_LEVEL': '6', 08:39:44 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:44 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:44 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:44 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:44 INFO - 'PROMPT': '$P$G', 08:39:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:44 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:44 INFO - 'SYSTEMDRIVE': 'C:', 08:39:44 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:44 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:44 INFO - 'TEST1': 'testie', 08:39:44 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:44 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:44 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:44 INFO - 'USERNAME': 'cltbld', 08:39:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:44 INFO - 'WINDIR': 'C:\\windows', 08:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:44 INFO - Downloading/unpacking flake8 08:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:39:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:39:45 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 08:39:45 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 08:39:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:39:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:39:45 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 08:39:45 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 08:39:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:39:45 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 08:39:45 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 08:39:45 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\pep8\setup.py) egg_info for package pep8 08:39:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:39:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:39:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:39:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:39:45 INFO - no previously-included directories found matching 'docs\_build' 08:39:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:39:46 INFO - Running setup.py install for pep8 08:39:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:39:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:39:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:39:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:39:46 INFO - no previously-included directories found matching 'docs\_build' 08:39:46 INFO - Installing pep8-script.py script to C:\slave\test-pgo\build\venv\Scripts 08:39:46 INFO - Installing pep8.exe script to C:\slave\test-pgo\build\venv\Scripts 08:39:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:39:46 INFO - Cleaning up... 08:39:46 INFO - Return code: 0 08:39:46 INFO - Running post-action listener: _resource_record_post_action 08:39:46 INFO - Running post-action listener: _start_resource_monitoring 08:39:47 INFO - Starting resource monitoring. 08:39:47 INFO - ##### 08:39:47 INFO - ##### Running install step. 08:39:47 INFO - ##### 08:39:47 INFO - Running pre-action listener: _resource_record_pre_action 08:39:47 INFO - Running main action method: install 08:39:47 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 08:39:47 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 08:39:47 INFO - Reading from file tmpfile_stdout 08:39:47 INFO - Using _rmtree_windows ... 08:39:47 INFO - Using _rmtree_windows ... 08:39:47 INFO - Detecting whether we're running mozinstall >=1.0... 08:39:47 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 08:39:47 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 08:39:47 INFO - Reading from file tmpfile_stdout 08:39:47 INFO - Output received: 08:39:47 INFO - Usage: mozinstall-script.py [options] installer 08:39:47 INFO - Options: 08:39:47 INFO - -h, --help show this help message and exit 08:39:47 INFO - -d DEST, --destination=DEST 08:39:47 INFO - Directory to install application into. [default: 08:39:47 INFO - "C:\slave\test-pgo"] 08:39:47 INFO - --app=APP Application being installed. [default: firefox] 08:39:47 INFO - Using _rmtree_windows ... 08:39:47 INFO - Using _rmtree_windows ... 08:39:47 INFO - mkdir: C:\slave\test-pgo\build\application 08:39:47 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-47.0a1.en-US.win64.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 08:39:47 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-47.0a1.en-US.win64.zip --destination C:\slave\test-pgo\build\application 08:39:49 INFO - Reading from file tmpfile_stdout 08:39:49 INFO - Output received: 08:39:49 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 08:39:49 INFO - Using _rmtree_windows ... 08:39:49 INFO - Using _rmtree_windows ... 08:39:49 INFO - Running post-action listener: _resource_record_post_action 08:39:49 INFO - ##### 08:39:49 INFO - ##### Running run-tests step. 08:39:49 INFO - ##### 08:39:49 INFO - Running pre-action listener: _resource_record_pre_action 08:39:49 INFO - Running main action method: run_tests 08:39:49 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 08:39:49 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 08:39:49 INFO - Python 2.7.3 08:39:49 INFO - Return code: 0 08:39:49 INFO - grabbing minidump binary from tooltool 08:39:49 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')]} 08:39:49 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E88BF0>, '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 0x01EAA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028715C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 08:39:49 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build 08:39:49 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o 08:39:49 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 08:39:51 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmp4uytut 08:39:51 INFO - INFO - File integrity verified, renaming tmp4uytut to win32-minidump_stackwalk.exe 08:39:51 INFO - Return code: 0 08:39:51 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 08:39:51 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 08:39:51 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 08:39:51 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 08:39:51 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 08:39:51 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\flake8', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos'] 08:39:51 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\flake8 C:\slave\test-pgo\build\tests\talos\talos 08:39:52 INFO - Return code: 0 08:39:52 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-162', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_errorsummary.log', '--log-raw=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_raw.log'] in C:\slave\test-pgo\build 08:39:52 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --branchName Firefox --suite svgr --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.crashreporter-symbols.zip --title T-W864-IX-162 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log --log-raw=C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log 08:39:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:39:52 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Roaming', 08:39:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:39:52 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:39:52 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:39:52 INFO - 'COMPUTERNAME': 'T-W864-IX-162', 08:39:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:39:52 INFO - 'DCLOCATION': 'SCL3', 08:39:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:39:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:39:52 INFO - 'HOMEDRIVE': 'C:', 08:39:52 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-162', 08:39:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:39:52 INFO - 'KTS_VERSION': '1.19c', 08:39:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-162\\AppData\\Local', 08:39:52 INFO - 'LOGONSERVER': '\\\\T-W864-IX-162', 08:39:52 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 08:39:52 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 08:39:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:39:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:52 INFO - 'MOZ_NO_REMOTE': '1', 08:39:52 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 08:39:52 INFO - 'NO_EM_RESTART': '1', 08:39:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:39:52 INFO - 'OS': 'Windows_NT', 08:39:52 INFO - 'OURDRIVE': 'C:', 08:39:52 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', 08:39:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:39:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:39:52 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:39:52 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:39:52 INFO - 'PROCESSOR_LEVEL': '6', 08:39:52 INFO - 'PROCESSOR_REVISION': '1e05', 08:39:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:39:52 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:39:52 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:39:52 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:39:52 INFO - 'PROMPT': '$P$G', 08:39:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 08:39:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:39:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:39:52 INFO - 'PWD': 'C:\\slave\\test-pgo', 08:39:52 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 08:39:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:39:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:39:52 INFO - 'SYSTEMDRIVE': 'C:', 08:39:52 INFO - 'SYSTEMROOT': 'C:\\windows', 08:39:52 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:52 INFO - 'TEST1': 'testie', 08:39:52 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:39:52 INFO - 'USERDOMAIN': 'T-W864-IX-162', 08:39:52 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-162', 08:39:52 INFO - 'USERNAME': 'cltbld', 08:39:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-162', 08:39:52 INFO - 'WINDIR': 'C:\\windows', 08:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:39:52 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-162', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_errorsummary.log', '--log-raw=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_raw.log'] with output_timeout 3600 ProcessManager NOT managing child processes ProcessManager UNABLE to use job objects to manage child processes 08:39:53 INFO - mozversion application_buildid: 20160126043024 08:39:53 INFO - mozversion application_changeset: c0ba5835ca489d15f8f170d5deb01f8dad92709a 08:39:53 INFO - mozversion application_display_name: Nightly 08:39:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:39:53 INFO - mozversion application_name: Firefox 08:39:53 INFO - mozversion application_remotingname: firefox 08:39:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:39:53 INFO - mozversion application_vendor: Mozilla 08:39:53 INFO - mozversion application_version: 47.0a1 08:39:53 INFO - mozversion platform_buildid: 20160126043024 08:39:53 INFO - mozversion platform_changeset: c0ba5835ca489d15f8f170d5deb01f8dad92709a 08:39:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:39:53 INFO - mozversion platform_version: 47.0a1 08:39:53 INFO - using testdate: 1453826393 08:39:53 INFO - actual date: 1453826393 08:39:53 INFO - starting webserver on 'localhost:49239' 08:39:53 INFO - SUITE-START | Running 5 tests 08:39:53 INFO - TEST-START | tsvgx 08:39:53 INFO - operating with platform_type : w8_ 08:39:53 INFO - Initialising browser for tsvgx test... 08:39:54 INFO - TEST-INFO | started process 3068 (C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpg4fd_l\profile http://localhost:49239/getInfo.html) 08:40:14 INFO - PROCESS | 3068 | __metrics Screen width/height:1600/1200 08:40:14 INFO - PROCESS | 3068 | colorDepth:24 08:40:14 INFO - PROCESS | 3068 | Browser inner width/height: 1010/672 08:40:14 INFO - PROCESS | 3068 | __metrics 08:40:14 INFO - PROCESS | 3068 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 08:40:14 INFO - PROCESS | 3068 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:40:14 INFO - PROCESS | 3068 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:40:21 INFO - PROCESS | 3068 | ************************* 08:40:21 INFO - PROCESS | 3068 | A coding exception was thrown and uncaught in a Task. 08:40:21 INFO - PROCESS | 3068 | 08:40:21 ERROR - PROCESS | 3068 | Full message: TypeError: connection.database is undefined 08:40:21 INFO - PROCESS | 3068 | Full stack: wrapStorageConnection@resource://gre/modules/Sqlite.jsm:1048:5 08:40:21 INFO - PROCESS | 3068 | @resource://gre/modules/PlacesUtils.jsm:2100:9 08:40:21 INFO - PROCESS | 3068 | XPCU_defineLazyGetter/<.get@resource://gre/modules/XPCOMUtils.jsm:198:21 08:40:21 INFO - PROCESS | 3068 | this.PlacesUtils.withConnectionWrapper/<@resource://gre/modules/PlacesUtils.jsm:1424:11 08:40:21 INFO - PROCESS | 3068 | TaskImpl_run@resource://gre/modules/Task.jsm:315:40 08:40:21 INFO - PROCESS | 3068 | TaskImpl@resource://gre/modules/Task.jsm:276:3 08:40:21 INFO - PROCESS | 3068 | createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:250:14 08:40:21 INFO - PROCESS | 3068 | Task_spawn@resource://gre/modules/Task.jsm:164:12 08:40:21 INFO - PROCESS | 3068 | this.PlacesUtils.withConnectionWrapper@resource://gre/modules/PlacesUtils.jsm:1423:12 08:40:21 INFO - PROCESS | 3068 | @resource://gre/modules/PlacesUtils.jsm:2293:3 08:40:21 INFO - PROCESS | 3068 | XPCU_defineLazyGetter/<.get@resource://gre/modules/XPCOMUtils.jsm:198:21 08:40:21 INFO - PROCESS | 3068 | PU_observe@resource://gre/modules/PlacesUtils.jsm:389:9 08:40:21 INFO - PROCESS | 3068 | PlacesCategoriesStarter/notify@resource://gre/components/PlacesCategoriesStarter.js:51:7 08:40:21 INFO - PROCESS | 3068 | walkTreeForImport@resource://gre/modules/BookmarkHTMLUtils.jsm:935:5 08:40:21 INFO - PROCESS | 3068 | BookmarkImporter.prototype.importFromURLtsvgx: 121.68 08:55:52 INFO - RETURN: tsvgr_opacity: 273.71 08:55:52 INFO - RETURN: tart: 3.99 08:55:52 INFO - RETURN: tscrollx: 2.75 08:55:52 INFO - RETURN: cart: 22.64 08:55:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [152.0, 105.0, 99.0, 98.0, 101.0, 101.0, 98.0, 100.0, 97.0, 98.0, 100.0, 101.0, 97.0, 103.0, 97.0, 96.0, 99.0, 99.0, 97.0, 98.0, 100.0, 100.0, 98.0, 100.0, 98.0], "unit": "ms", "name": "gearflowers.svg", "value": 98.5}, {"lowerIsBetter": true, "replicates": [75.0, 35.0, 35.0, 38.0, 39.0, 35.0, 36.0, 35.0, 34.0, 35.0, 35.0, 34.0, 71.0, 35.0, 36.0, 36.0, 36.0, 37.0, 34.0, 34.0, 35.0, 35.0, 35.0, 36.0, 35.0], "unit": "ms", "name": "composite-scale.svg", "value": 35.0}, {"lowerIsBetter": true, "replicates": [46.0, 39.0, 37.0, 38.0, 35.0, 36.0, 38.0, 36.0, 37.0, 39.0, 37.0, 39.0, 38.0, 35.0, 38.0, 37.0, 36.0, 38.0, 37.0, 36.0, 38.0, 36.0, 38.0, 37.0, 35.0], "unit": "ms", "name": "composite-scale-opacity.svg", "value": 37.0}, {"lowerIsBetter": true, "replicates": [45.0, 41.0, 37.0, 38.0, 36.0, 36.0, 37.0, 37.0, 35.0, 36.0, 37.0, 39.0, 38.0, 37.0, 39.0, 36.0, 36.0, 38.0, 35.0, 35.0, 39.0, 35.0, 38.0, 38.0, 37.0], "unit": "ms", "name": "composite-scale-rotate.svg", "value": 37.0}, {"lowerIsBetter": true, "replicates": [49.0, 39.0, 38.0, 40.0, 38.0, 36.0, 40.0, 37.0, 36.0, 40.0, 37.0, 38.0, 38.0, 38.0, 38.0, 37.0, 38.0, 40.0, 38.0, 37.0, 40.0, 37.0, 40.0, 40.0, 38.0], "unit": "ms", "name": "composite-scale-rotate-opacity.svg", "value": 38.0}, {"lowerIsBetter": true, "replicates": [509.0, 380.0, 350.0, 346.0, 347.0, 363.0, 351.0, 341.0, 350.0, 344.0, 347.0, 345.0, 357.0, 343.0, 351.0, 344.0, 352.0, 352.0, 352.0, 351.0, 350.0, 346.0, 350.0, 347.0, 343.0], "unit": "ms", "name": "hixie-001.xml", "value": 350.0}, {"lowerIsBetter": true, "replicates": [355.0, 353.0, 353.0, 350.0, 351.0, 358.0, 352.0, 356.0, 353.0, 348.0, 349.0, 356.0, 346.0, 354.0, 356.0, 357.0, 347.0, 350.0, 347.0, 348.0, 357.0, 371.0, 353.0, 356.0, 352.0], "unit": "ms", "name": "hixie-002.xml", "value": 353.0}, {"lowerIsBetter": true, "replicates": [410.0, 188.0, 184.0, 185.0, 187.0, 185.0, 186.0, 182.0, 185.0, 187.0, 185.0, 186.0, 186.0, 184.0, 187.0, 186.0, 188.0, 182.0, 185.0, 184.0, 186.0, 185.0, 191.0, 191.0, 192.0], "unit": "ms", "name": "hixie-003.xml", "value": 186.0}, {"lowerIsBetter": true, "replicates": [323.0, 316.0, 312.0, 317.0, 301.0, 311.0, 308.0, 305.0, 307.0, 304.0, 313.0, 317.0, 313.0, 305.0, 298.0, 315.0, 308.0, 308.0, 317.0, 312.0, 313.0, 310.0, 311.0, 303.0, 304.0], "unit": "ms", "name": "hixie-004.xml", "value": 309.0}, {"lowerIsBetter": true, "replicates": [203.0, 199.0, 199.0, 198.0, 203.0, 200.0, 198.0, 200.0, 199.0, 199.0, 196.0, 198.0, 199.0, 198.0, 199.0, 199.0, 198.0, 197.0, 202.0, 201.0, 199.0, 201.0, 199.0, 200.0, 199.0], "unit": "ms", "name": "hixie-005.xml", "value": 199.0}, {"lowerIsBetter": true, "replicates": [205.0, 210.0, 199.0, 202.0, 201.0, 201.0, 199.0, 202.0, 200.0, 201.0, 198.0, 201.0, 198.0, 199.0, 200.0, 201.0, 199.0, 207.0, 200.0, 202.0, 200.0, 203.0, 200.0, 196.0, 199.0], "unit": "ms", "name": "hixie-006.xml", "value": 200.0}, {"lowerIsBetter": true, "replicates": [252.0, 201.0, 197.0, 202.0, 197.0, 200.0, 192.0, 208.0, 196.0, 201.0, 196.0, 199.0, 196.0, 202.0, 199.0, 198.0, 196.0, 198.0, 196.0, 198.0, 198.0, 204.0, 196.0, 200.0, 199.0], "unit": "ms", "name": "hixie-007.xml", "value": 198.0}], "name": "tsvgx", "value": 121.68152970150358}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [431.0, 225.0, 230.0, 228.0, 229.0, 233.0, 226.0, 228.0, 226.0, 235.0, 226.0, 227.0, 226.0, 228.0, 227.0, 228.0, 230.0, 225.0, 227.0, 230.0, 226.0, 230.0, 227.0, 229.0, 222.0], "unit": "ms", "name": "big-optimizable-group-opacity-2500.svg", "value": 227.0}, {"lowerIsBetter": true, "replicates": [317.0, 374.0, 140.0, 361.0, 330.0, 325.0, 330.0, 327.0, 337.0, 328.0, 330.0, 330.0, 331.0, 325.0, 327.0, 124.0, 362.0, 330.0, 141.0, 360.0, 330.0, 325.0, 330.0, 326.0, 330.0], "unit": "ms", "name": "small-group-opacity-2500.svg", "value": 330.0}], "name": "tsvgr_opacity", "value": 273.7143971472918}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1.6761126863783684, 1.7001741142833935, 1.6568914106913974, 1.6803867695988088, 1.6155493093861475, 1.6589266180992126, 1.661340320110321, 1.6802365002424822, 1.674354076385498, 1.6935783554525936, 1.7017496368464302, 1.6631396276610237, 1.6753669493440269, 1.667275135067926, 1.6370758187602943, 1.6895637995954873, 1.6794963470403699, 1.6592063869748797, 1.673691823862601, 1.6485312002045767, 1.6459748557635716, 1.669278607852217, 1.6497654778616768, 1.6487945062773568, 1.6632328867912292], "unit": "ms", "name": "simple-open-DPI1.half.TART", "value": 1.6652540109295777}, {"lowerIsBetter": true, "replicates": [1.9872232785591712, 2.0254031185090073, 1.999359640040139, 1.9707345480227287, 1.9262812173188621, 1.9611778394742445, 1.9610612956622175, 1.9640599907809542, 1.945407664233988, 1.9648807722193595, 1.9965853330700896, 1.944757540423171, 1.9719334567776163, 1.939219870065388, 1.9529949738018548, 1.9595282232488385, 2.0065365715544354, 1.951531292814197, 1.9789170742034912, 1.9547225778753108, 1.9447575377342396, 1.9632752817688566, 1.9254100447270408, 1.9531720235492245, 1.9606076515082158], "unit": "ms", "name": "simple-open-DPI1.all.TART", "value": 1.9608344735852166}, {"lowerIsBetter": true, "replicates": [28.76000000000022, 27.735000000000582, 28.395000000004075, 28.49500000000262, 28.5, 29.16500000000815, 27.35999999998603, 27.72500000000582, 27.235000000000582, 27.844999999986612, 27.98499999998603, 28.920000000012806, 28.68499999999767, 28.239999999990687, 28.360000000015134, 27.02499999999418, 29.14999999999418, 27.919999999983702, 27.714999999996508, 28.349999999976717, 29.03499999997439, 29.650000000023283, 28.270000000018626, 28.19000000000233, 29.210000000020955], "unit": "ms", "name": "simple-open-DPI1.error.TART", "value": 28.30999999999767}, {"lowerIsBetter": true, "replicates": [1.3507847300795621, 1.3284656494513325, 1.312038543549451, 1.319535260850733, 1.2954067453909457, 1.3142989034002477, 1.3693508619353885, 1.35211085857347, 1.3827999858629136, 1.3331354349509053, 1.3234736535740994, 1.3036735191773832, 1.3635094502392937, 1.3592673834632425, 1.4331472523418474, 1.3098087297244505, 1.308905459263108, 1.3038029630532426, 1.3464044651319815, 1.3603154799517463, 1.3407248355621515, 1.361255152085248, 1.2980257111988711, 1.351789381614951, 1.3527627739795418], "unit": "ms", "name": "simple-close-DPI1.half.TART", "value": 1.3369301352565284}, {"lowerIsBetter": true, "replicates": [1.3654803938335842, 1.4518338182393242, 1.4219920531261798, 1.4036888211114067, 1.3793472815095709, 1.417536971196963, 1.5651129112122164, 1.5413879268574264, 1.5506114283944392, 1.5372629503034196, 1.5502734727497343, 1.542213888288294, 1.5706312281963153, 1.5830856746242892, 1.5931932793723211, 1.4164404634795438, 1.3803345187235687, 1.5236259935805516, 1.5624836166952825, 1.5439739355558082, 1.5479279910243533, 1.5631464499576835, 1.4164582256636868, 1.5542641493338574, 1.562556991911238], "unit": "ms", "name": "simple-close-DPI1.all.TART", "value": 1.543093911922051}, {"lowerIsBetter": true, "replicates": [18.329999999999927, 19.61999999999898, 17.6050000000032, 18.77499999999418, 18.31500000000233, 18.070000000006985, 18.680000000007567, 18.360000000000582, 16.604999999995925, 17.420000000012806, 17.69000000000233, 18.014999999984866, 17.76500000001397, 18.43499999999767, 16.579999999987194, 17.994999999995343, 17.48499999998603, 18.260000000009313, 18.175000000017462, 16.76500000001397, 18.860000000015134, 18.39000000001397, 17.895000000018626, 18.410000000032596, 18.145000000018626], "unit": "ms", "name": "simple-close-DPI1.error.TART", "value": 18.107500000012806}, {"lowerIsBetter": true, "replicates": [1.6566281029156276, 1.758507616592176, 1.6915171889291294, 1.6760013777276743, 1.6100879857937496, 1.6943519956925337, 1.680447991343512, 1.6734024372653684, 1.72963628484242, 1.7113100973998798, 1.7473716392661587, 1.6822288537370986, 1.6893189281657122, 1.7020376412307514, 1.7474530917225461, 1.6949336441124188, 1.6747714816660122, 1.6958484702250536, 1.6824125120605247, 1.6824959913889568, 1.6351179522527775, 1.67917912075485, 1.69965457740952, 1.694741650539286, 1.6850726690845212], "unit": "ms", "name": "icon-open-DPI1.half.TART", "value": 1.6904180585474209}, {"lowerIsBetter": true, "replicates": [1.958524746425224, 2.085996006765673, 1.9858822299883916, 1.9656839425327213, 1.9294755297495907, 2.0263071698466626, 1.9832119666613066, 1.986260321507087, 2.018951647914946, 1.9883339148301344, 2.006873664446175, 1.9604633000060803, 1.961026105261941, 1.9919267282929531, 2.055795436813718, 2.012231644243002, 2.0134671330451965, 1.9779866172717169, 1.973249606503785, 1.9941563171933787, 1.9081386719177018, 2.004764652810991, 2.0030276607722044, 2.0054887533187866, 1.9828161487212548], "unit": "ms", "name": "icon-open-DPI1.all.TART", "value": 1.9901303215615438}, {"lowerIsBetter": true, "replicates": [28.9950000000008, 29.05000000000291, 28.489999999997963, 27.68000000000029, 27.085000000006403, 27.695000000006985, 28.20999999999185, 28.610000000000582, 28.72500000000582, 28.80000000000291, 27.41500000000815, 27.214999999996508, 27.06500000000233, 27.35999999998603, 29.66500000000815, 27.795000000012806, 27.285000000003492, 27.474999999976717, 28.85000000000582, 27.720000000001164, 26.244999999995343, 26.759999999951106, 26.820000000006985, 29.04999999998836, 28.669999999983702], "unit": "ms", "name": "icon-open-DPI1.error.TART", "value": 27.707500000004075}, {"lowerIsBetter": true, "replicates": [1.2154751934503254, 1.2752407756480542, 1.338501214981079, 1.3222799829461358, 1.2941511949796356, 1.3405064604748254, 1.347900270029556, 1.3542753948884851, 1.322564418288483, 1.3049937841597568, 1.3020727942498882, 1.295018433854821, 1.3650589999030618, 1.3646388558780447, 1.3096254535696723, 1.3264220678943328, 1.353430602129768, 1.333828398551064, 1.353990779203527, 1.3363530813962563, 1.3337842609690524, 1.3307652350129753, 1.3436182266057923, 1.3305445457326954, 1.3311404113111824], "unit": "ms", "name": "icon-close-DPI1.half.TART", "value": 1.3324623361401176}, {"lowerIsBetter": true, "replicates": [1.3390855075883084, 1.4445129811763764, 1.4569918598447527, 1.432013993346414, 1.4312013408472373, 1.434888838327419, 1.4473274798954234, 1.4620729982852936, 1.437844058923554, 1.4280735250822334, 1.4315429177395134, 1.4481248424333684, 1.4554825449836324, 1.4620082639378202, 1.4206977524509319, 1.4516169677762425, 1.4676387075866972, 1.4548236103452874, 1.4610925687135323, 1.4686901214576902, 1.4418165495521145, 1.4595228412572074, 1.455605241673938, 1.448703103205737, 1.448746017147513], "unit": "ms", "name": "icon-close-DPI1.all.TART", "value": 1.448724560176625}, {"lowerIsBetter": true, "replicates": [18.74499999999898, 19.779999999998836, 18.125, 19.599999999998545, 19.44499999999971, 18.645000000004075, 19.27499999999418, 18.93499999999767, 19.220000000001164, 18.964999999996508, 19.479999999981374, 19.474999999976717, 19.39000000001397, 20.289999999979045, 19.730000000010477, 20.130000000004657, 19.705000000016298, 19.254999999975553, 20.27499999999418, 20.089999999996508, 20.095000000001164, 21.489999999990687, 19.364999999990687, 19.80999999999767, 19.724999999976717], "unit": "ms", "name": "icon-close-DPI1.error.TART", "value": 19.53999999998996}, {"lowerIsBetter": true, "replicates": [1.5277263020214282, 1.611341322461764, 1.592440084235309, 1.6117893159389496, 1.588005684826472, 1.600402671429846, 1.6151333153247833, 1.6142693161964417, 1.6227272161295716, 1.6272054104737832, 1.6236142037620007, 1.6404614952248586, 1.6136346542172961, 1.6059493372837703, 1.6351990800508311, 1.5923348893857983, 1.6121146480242412, 1.6069146543741226, 1.6098106536600325, 1.618237305018637, 1.6218348204250066, 1.600850674841139, 1.6007546616925135, 1.6010106719202466, 1.6011066718233957], "unit": "ms", "name": "icon-open-DPI2.half.TART", "value": 1.611565319200357}, {"lowerIsBetter": true, "replicates": [1.8166387819908034, 1.915148806922576, 1.8791291073841208, 1.9048245659580938, 1.892309317693991, 1.904443417655097, 1.9349785568122577, 1.9039883021955137, 1.922736377858404, 1.9181026113567068, 1.9153630137443542, 1.9240517260423347, 1.9062894520936189, 1.8760050622216107, 1.8966894626617432, 1.884584778416766, 1.946856393958583, 1.8965273327297634, 1.913296882785968, 1.9178733514316046, 1.898359145058526, 1.8984018290484392, 1.911305232724147, 1.877810127543707, 1.9033426214147497], "unit": "ms", "name": "icon-open-DPI2.all.TART", "value": 1.9046339918065955}, {"lowerIsBetter": true, "replicates": [28.36999999999898, 30.75499999999738, 25.994999999995343, 27.56500000000233, 27.770000000004075, 27.5, 27.705000000001746, 27.339999999996508, 28.21500000001106, 27.33499999999185, 26.95999999999185, 28.170000000012806, 27.669999999983702, 27.404999999998836, 26.5, 28.45999999999185, 27.39999999999418, 26.529999999998836, 26.980000000010477, 28.239999999990687, 26.570000000006985, 26.619999999995343, 26.664999999979045, 27.649999999965075, 27.285000000032596], "unit": "ms", "name": "icon-open-DPI2.error.TART", "value": 27.402499999996508}, {"lowerIsBetter": true, "replicates": [1.1697822929632784, 1.2701053141237615, 1.264981545831846, 1.2799765368302662, 1.268244396199237, 1.2644465945579193, 1.272687812427898, 1.2841877281665801, 1.2769497908078706, 1.2510031371012977, 1.2755741431162908, 1.2790080044004652, 1.3022928412040968, 1.2714556409762456, 1.2803050690227085, 1.275481308554555, 1.2805184013313717, 1.2779114723205567, 1.2860181245538924, 1.2925030220760387, 1.291573319832484, 1.275489751454238, 1.2677169232578067, 1.2885567877027724, 1.285313375703581], "unit": "ms", "name": "icon-close-DPI2.half.TART", "value": 1.2774306315642137}, {"lowerIsBetter": true, "replicates": [1.2677178879578908, 1.3547817491822773, 1.3471836906213026, 1.3648531903823218, 1.350476962097442, 1.3481793235678698, 1.3444028601541624, 1.3590904260208594, 1.3571598200508244, 1.34762772275598, 1.3569364094071918, 1.3534259022270119, 1.3706910806661212, 1.3543148264700537, 1.366689991288715, 1.3522420828513677, 1.3614729145613824, 1.3590373818386985, 1.3613502638207542, 1.3533113381480644, 1.3580211628207843, 1.3569714584193386, 1.353623064664694, 1.3578132066461774, 1.3553534762726889], "unit": "ms", "name": "icon-close-DPI2.all.TART", "value": 1.3561449428399404}, {"lowerIsBetter": true, "replicates": [17.37000000000262, 17.81999999999971, 19.345000000001164, 19.4800000000032, 18.270000000004075, 17.895000000004075, 18.354999999995925, 19.72500000000582, 19.520000000004075, 18.110000000000582, 18.429999999993015, 18.85000000000582, 19.119999999995343, 19.0, 19.835000000020955, 18.505000000004657, 20.349999999976717, 19.785000000003492, 18.75, 18.720000000001164, 19.56500000000233, 20.760000000009313, 20.205000000016298, 18.37000000005355, 17.710000000020955], "unit": "ms", "name": "icon-close-DPI2.error.TART", "value": 18.92500000000291}, {"lowerIsBetter": true, "replicates": [1.2118979441492181, 1.2588333830885265, 1.251387854417165, 1.2640382433715074, 1.2683330191360724, 1.235260581716578, 1.2550810432952384, 1.2450415421557683, 1.2561495641003484, 1.2590921644283377, 1.2388769061334672, 1.2513370442649592, 1.3099570965225047, 1.2583700800719468, 1.269447026016948, 1.2758484319016175, 1.256817379075548, 1.2523596558881842, 1.2657547272168672, 1.2419076126108888, 1.249054955538883, 1.2684342841525653, 1.25724730413893, 1.28564692735672, 1.2699276254228924], "unit": "ms", "name": "iconFade-close-DPI2.half.TART", "value": 1.2578086921054386}, {"lowerIsBetter": true, "replicates": [1.1942025676751749, 1.7537366582038707, 1.2526410132326105, 1.2697439724984376, 1.2660959861848666, 1.253788879481695, 1.2515340033062001, 1.2662921544650327, 1.2642566645467603, 1.2585868053538825, 1.2673722463685113, 1.2522281091059408, 1.2896586702673474, 1.2585298866839023, 1.2684792788780255, 1.2660458917203157, 1.2643805023120798, 1.265715860676121, 1.263648491936761, 1.253508109559295, 1.2467678922382905, 1.2655512842795122, 1.2592729787568788, 1.270082058465999, 1.2663777162847312], "unit": "ms", "name": "iconFade-close-DPI2.all.TART", "value": 1.2649658932957961}, {"lowerIsBetter": true, "replicates": [2.279999999998836, 3.069999999999709, 2.834999999999127, 3.3249999999970896, 2.585000000006403, 2.610000000000582, 3.275000000008731, 2.5500000000029104, 3.3249999999970896, 3.6350000000093132, 2.709999999991851, 2.4649999999965075, 2.974999999976717, 2.4949999999953434, 2.9649999999965075, 2.7150000000256114, 2.3099999999976717, 3.569999999977881, 3.4649999999965075, 2.8500000000058208, 3.154999999998836, 2.429999999993015, 2.4400000000023283, 2.2299999999813735, 2.7950000000419095], "unit": "ms", "name": "iconFade-close-DPI2.error.TART", "value": 2.815000000020518}, {"lowerIsBetter": true, "replicates": [1.4970240004650959, 1.5970849011042347, 1.634523030737756, 1.6070853223403294, 1.5856438270986897, 1.5863329237454558, 1.6312887802929945, 1.5636194622194446, 1.5976635315646863, 1.569275662705705, 1.5953227183590197, 1.5889472700145146, 1.6067173315419092, 1.6251448006697105, 1.5990469896630064, 1.611479981078042, 1.6566500442368643, 1.5883633910793147, 1.591787818360002, 1.5973952567740664, 1.5807675596785873, 1.579163175739654, 1.589131381413708, 1.5921192185519493, 1.598094881397404], "unit": "ms", "name": "iconFade-open-DPI2.half.TART", "value": 1.5962038097316271}, {"lowerIsBetter": true, "replicates": [1.488684288202188, 1.5436291118331302, 1.6058985726586703, 1.579823001712358, 1.5551615730921426, 1.571037387525713, 1.5591018391935618, 1.5605682566661963, 1.5624624738757242, 1.5501183843612671, 1.551487979888916, 1.5674905424950107, 1.553720299800237, 1.5769919761129327, 1.5739537098923244, 1.5892975995329772, 1.6029616331232006, 1.5748047305120003, 1.5690445883962132, 1.553978853225708, 1.546436634284771, 1.5565900492668152, 1.5537535774707794, 1.5803741914074436, 1.5737046293310217], "unit": "ms", "name": "iconFade-open-DPI2.all.TART", "value": 1.5649765081853675}, {"lowerIsBetter": true, "replicates": [2.375, 3.475000000002183, 3.025000000001455, 2.25, 3.3399999999965075, 2.529999999998836, 2.3649999999906868, 2.5899999999965075, 2.889999999999418, 2.5750000000116415, 2.6300000000046566, 3.5799999999871943, 3.125, 3.3999999999941792, 3.0599999999976717, 3.7250000000058208, 2.5, 3.1849999999976717, 3.720000000001164, 3.054999999993015, 3.6300000000046566, 3.4699999999720603, 3.099999999976717, 2.3950000000186265, 2.9349999999976717], "unit": "ms", "name": "iconFade-open-DPI2.error.TART", "value": 3.0574999999953434}, {"lowerIsBetter": true, "replicates": [14.48124024271965, 1.8597398746398188, 1.8747406198132424, 1.8165209740400314, 1.7803096514481764, 1.8319268548299397, 1.8698725027422751, 1.86356129377119, 1.854137865323869, 1.8196649681776762, 1.8295192453596327, 1.8382536986517528, 1.8785310560657131, 1.8780170025364045, 1.857739358179031, 1.853052920765347, 1.8180689718574286, 1.8672154718829739, 1.8464944495095148, 1.8401858939064875, 1.833590857566349, 1.792195908839886, 1.8402163698559715, 1.8422887495585851, 1.8210989627987146], "unit": "ms", "name": "newtab-open-preload-no.half.TART", "value": 1.8412525597072782}, {"lowerIsBetter": true, "replicates": [16.191045098006725, 2.7867783218301754, 2.8306846722312597, 2.7297584809755024, 2.6669195629656315, 2.805520201506822, 2.8098330275043026, 2.8164791890553067, 2.840831473633483, 2.7456240172081805, 2.74668616944171, 2.805044376331827, 2.8556837889883253, 2.7988377783609475, 3.142655301094055, 2.8117101101771644, 2.736497910732919, 2.8019932586213816, 2.8842537135220647, 2.758482144724938, 2.8358015293603414, 2.7098834828326575, 2.823201276443817, 2.8297883573469225, 2.7130363376517046], "unit": "ms", "name": "newtab-open-preload-no.all.TART", "value": 2.8052822889193245}, {"lowerIsBetter": true, "replicates": [34.47999999999956, 29.44999999999709, 30.75, 29.709999999999127, 26.445000000006985, 28.32499999999709, 26.375, 26.714999999996508, 28.75, 28.489999999990687, 28.630000000004657, 28.285000000003492, 27.525000000023283, 28.00999999998021, 31.05999999999767, 28.97500000000582, 27.56500000000233, 28.095000000001164, 27.214999999996508, 27.044999999983702, 28.349999999976717, 27.944999999948777, 27.210000000020955, 27.904999999969732, 28.04500000004191], "unit": "ms", "name": "newtab-open-preload-no.error.TART", "value": 28.070000000021537}, {"lowerIsBetter": true, "replicates": [1.8641249037558032, 1.8783019223520834, 1.8696619253004751, 1.8646141905938425, 1.8474270256738814, 1.9018795920200036, 1.9134940026236362, 1.885201507999051, 1.8791875820006094, 1.8578384499396048, 1.854034249744718, 1.8417401673301819, 1.890441427465345, 1.8851457718879945, 2.0883393670831407, 1.8514133029513888, 1.8701883785186275, 1.8653698005983907, 1.9202720046043396, 1.827568771347167, 1.848804551457602, 1.84022855758667, 1.864403615074773, 1.863858578666564, 1.8651654124259949], "unit": "ms", "name": "newtab-open-preload-yes.half.TART", "value": 1.8652676065121927}, {"lowerIsBetter": true, "replicates": [2.064629228388677, 2.069901346183214, 2.1029010843639533, 2.1277436759291577, 2.092215754769065, 2.13383283234444, 2.129650863011678, 2.0988294231004954, 2.1176764686902363, 2.0936057774488592, 2.093872346168707, 2.1027995338124676, 2.14317201557806, 2.1059032718012154, 2.3663332406605515, 2.1205852687358857, 2.1231676816940306, 2.1128348896900815, 2.208476315324123, 2.0747547973462237, 2.1037167113674573, 2.08105620888413, 2.141916169839747, 2.1253404676914216, 2.125551676750183], "unit": "ms", "name": "newtab-open-preload-yes.all.TART", "value": 2.1152556791901587}, {"lowerIsBetter": true, "replicates": [36.75500000000102, 32.26000000000204, 35.125, 33.919999999998254, 33.61499999999069, 34.85000000000582, 36.11000000000058, 34.71499999999651, 34.90000000000873, 33.954999999987194, 33.90000000002328, 35.154999999998836, 33.56500000000233, 35.48000000001048, 33.11999999999534, 35.36500000001979, 35.64499999998952, 34.31500000000233, 82.70999999999185, 35.804999999993015, 35.289999999979045, 34.85999999998603, 35.84000000002561, 35.7050000000163, 35.84499999997206], "unit": "ms", "name": "newtab-open-preload-yes.error.TART", "value": 35.012500000004366}], "name": "tart", "value": 3.989232834325608}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [3.371182795698925, 2.1053225806451614, 2.1117204301075265, 2.0991666666666666, 2.109005376344086, 2.105510752688172, 2.099005376344086, 2.127983870967742, 2.100295698924731, 2.111720430107527, 2.101182795698925, 2.9007795698924737, 2.10497311827957, 2.1134139784946235, 2.0976612903225806, 2.5227956989247313, 2.101639784946237, 2.110537634408602, 2.101155913978494, 2.107258064516129, 2.101424731182796, 2.149596774193548, 2.106505376344086, 2.105967741935484, 2.1062096774193546], "unit": "ms", "name": "tiled.html", "value": 2.1060887096774192}, {"lowerIsBetter": true, "replicates": [2.8618010752688177, 2.8472580645161294, 2.853870967741935, 2.85991935483871, 2.863440860215054, 2.855241935483871, 2.855618279569893, 2.860510752688172, 2.8542473118279568, 2.8650268817204307, 2.8664516129032256, 2.85752688172043, 3.242795698924731, 2.8574462365591398, 2.8534408602150534, 2.8601075268817207, 2.88747311827957, 2.8598118279569897, 2.856048387096774, 2.868602150537634, 2.860752688172043, 2.854354838709677, 2.8613440860215054, 2.85991935483871, 2.8533870967741937], "unit": "ms", "name": "tiled-fixed.html", "value": 2.85986559139785}, {"lowerIsBetter": true, "replicates": [2.5000268817204305, 2.5026075268817207, 2.510672043010753, 2.5072849462365587, 2.5127150537634413, 2.5051075268817207, 2.5065322580645164, 2.5090860215053765, 2.4988978494623657, 2.510430107526882, 2.5075268817204304, 2.5037365591397847, 2.4991935483870966, 2.500752688172043, 2.499220430107527, 2.496586021505377, 2.5128494623655917, 2.508790322580645, 2.501478494623656, 2.5052956989247317, 2.501559139784946, 2.511317204301075, 2.498467741935484, 2.500564516129032, 2.5078763440860214], "unit": "ms", "name": "tiled-downscale.html", "value": 2.5044220430107527}, {"lowerIsBetter": true, "replicates": [3.3766129032258063, 3.3763709677419356, 3.376155913978495, 3.3862096774193544, 3.3890860215053764, 3.388467741935484, 3.3893548387096772, 3.3778763440860216, 3.3736827956989255, 3.377715053763441, 3.388951612903226, 3.3815860215053757, 3.3841666666666668, 3.380779569892473, 3.390967741935484, 3.378763440860215, 3.370940860215054, 3.3736827956989255, 3.3833333333333337, 3.3863440860215053, 3.387231182795699, 3.3873387096774192, 3.3882526881720425, 3.384220430107527, 3.384865591397849], "unit": "ms", "name": "tiled-fixed-downscale.html", "value": 3.384193548387097}, {"lowerIsBetter": true, "replicates": [2.8178846153846164, 2.9146794871794874, 2.864038461538461, 2.845192307692307, 2.864935897435897, 2.855512820512821, 2.7971153846153847, 2.808717948717949, 2.8021794871794876, 2.8124358974358974, 2.8064743589743593, 2.79198717948718, 2.799487179487179, 2.803205128205128, 2.776538461538462, 2.774935897435898, 2.8135897435897435, 2.800064102564103, 2.801217948717949, 2.8060897435897436, 2.773012820512821, 2.8106410256410252, 2.8051282051282045, 2.7954487179487173, 2.7923717948717943], "unit": "ms", "name": "iframe.svg", "value": 2.8016987179487183}, {"lowerIsBetter": true, "replicates": [2.97796568627451, 2.979730392156863, 2.988627450980392, 2.979411764705883, 3.1152205882352937, 2.9741176470588235, 2.973823529411765, 2.971764705882353, 2.970073529411765, 2.9917647058823533, 2.9761764705882356, 2.968382352941177, 2.9677205882352937, 2.9805882352941175, 2.980563725490196, 2.967475490196078, 2.9767647058823528, 2.959117647058824, 2.9595098039215686, 2.973161764705883, 2.9680637254901963, 2.9593382352941178, 2.9648039215686275, 2.9602941176470594, 2.9785049019607843], "unit": "ms", "name": "reader.htm", "value": 2.9709191176470586}], "name": "tscrollx", "value": 2.749961561505938}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [5.474544501304626, 5.336237998235793, 5.068465089797973, 5.002570879459381, 5.260699771699452, 5.327844880876087, 5.2982768558320545, 5.3029946599687845, 5.286610614685785, 5.314642565590995, 5.273975281488328, 5.1817056451525, 5.191909074783325, 5.297015156064715, 5.064970767498016, 5.268233514967418, 5.24517525945391, 5.2766998041243784, 5.227675903411138, 5.295040380387079, 5.239927280516851, 5.2276575565338135, 5.234405086154029, 5.229906717936198, 5.296155804679508], "unit": "ms", "name": "1-customize-enter.half.TART", "value": 5.264466643333435}, {"lowerIsBetter": true, "replicates": [10.916053841511408, 9.06586594166963, 9.1472153145334, 9.32566335995992, 9.17718373404609, 9.154920469159665, 9.17517835828993, 9.54252377423373, 9.053978686747344, 9.39458797194741, 9.06234329679738, 9.140114916695488, 9.035020833430083, 9.054905367934186, 9.371598291397095, 9.055489638577336, 9.13922741678026, 9.249512094921537, 9.475865011865443, 9.249443827735053, 9.146557590696547, 9.152078527874417, 9.244392108917236, 9.158410294850666, 9.425063539635051], "unit": "ms", "name": "1-customize-enter.all.TART", "value": 9.156665382005166}, {"lowerIsBetter": true, "replicates": [434.34000000000015, 225.54999999999927, 229.39500000000044, 229.86999999999898, 221.46500000000015, 229.8050000000003, 221.51000000000204, 228.52999999999884, 225.08000000000175, 222.01000000000204, 225.75, 222.7300000000032, 224.18500000000495, 225.19499999999243, 230.52499999999418, 225.04499999999825, 219.70999999999185, 224.63499999999476, 225.63000000000466, 224.84500000000116, 220.2149999999965, 220.41999999999825, 224.77000000000407, 220.84000000001106, 223.35499999999593], "unit": "ms", "name": "1-customize-enter.error.TART", "value": 224.80750000000262}, {"lowerIsBetter": true, "replicates": [9.031856993834177, 8.85963319738706, 9.103926767002452, 8.666321645180384, 8.662833432356516, 8.984592974185944, 9.00142498811086, 8.62542555729548, 8.96097700794538, 8.85912138223648, 8.635985553264618, 8.587249805529913, 8.721649467945099, 9.199792802333832, 8.942385156949362, 8.983728965123495, 9.035825113455454, 9.35457350990989, 8.965009063482285, 9.040304839611053, 8.820145209630331, 9.028112888336182, 8.856785386800766, 8.883057137330374, 8.591153601805368], "unit": "ms", "name": "2-customize-exit.half.TART", "value": 8.912721147139868}, {"lowerIsBetter": true, "replicates": [10.111188261132492, 9.911887523200777, 10.289668295118544, 9.847819337973723, 9.710141269784225, 9.49045556019514, 9.472151646247276, 9.495733053256304, 9.438379327456156, 9.349183192619911, 9.514150098750466, 9.715401871784314, 10.056289402214256, 9.500449474041279, 9.472791677866226, 9.529574229167057, 10.718924396178302, 9.552489343442415, 10.216366936763128, 10.50689172063555, 9.357749333748451, 9.868700433421779, 9.408870535019117, 9.410239066833105, 9.477773690835024], "unit": "ms", "name": "2-customize-exit.all.TART", "value": 9.521862163958762}, {"lowerIsBetter": true, "replicates": [205.48999999999978, 177.89500000000044, 191.65999999999985, 185.30999999999767, 190.02000000000044, 190.8199999999997, 190.125, 192.05999999999767, 189.54500000000553, 185.74500000000262, 182.23500000000058, 180.11499999999796, 193.0, 191.3649999999907, 190.1200000000099, 192.2149999999965, 186.24000000000524, 183.51499999999942, 188.45000000001164, 189.01499999999942, 185.2950000000128, 185.52499999999418, 187.61000000000058, 187.3350000000064, 190.28499999998894], "unit": "ms", "name": "2-customize-exit.error.TART", "value": 188.73250000000553}, {"lowerIsBetter": true, "replicates": [7.483763524464199, 8.142913231482872, 7.872459484980657, 8.34670595328013, 7.698182912973257, 8.266354194054237, 7.764521973473685, 7.552115440368652, 7.7455033889183635, 7.766830187577468, 7.9898450924799995, 7.8365702995887165, 8.189258868877705, 7.802044187273298, 9.719477566805752, 7.6523118019104, 7.776961876795842, 7.806825160980225, 7.627927916390555, 7.649925538471767, 8.034566365755522, 8.0725527543288, 7.899280438056359, 7.9842328658470745, 7.741591657911028], "unit": "ms", "name": "3-customize-enter-css.half.TART", "value": 7.821697730284471}, {"lowerIsBetter": true, "replicates": [11.162554264068604, 11.250461339950562, 10.999139385223389, 12.200244053550389, 10.964825420379638, 11.825627513553785, 11.029813194274903, 11.142386665344238, 11.347629229227701, 11.301149109999338, 11.851856418277906, 11.357020934422811, 11.722114604452383, 11.094048919677734, 12.501483006910844, 11.389469186464945, 11.026664428710937, 11.220029294490814, 10.850255165100098, 10.980615253448486, 11.5257568359375, 11.363229076067606, 11.435676912466684, 11.243485371271769, 11.409884929656982], "unit": "ms", "name": "3-customize-enter-css.all.TART", "value": 11.32438916961352}, {"lowerIsBetter": true, "replicates": [79.02499999999964, 70.02999999999884, 74.92999999999665, 80.56000000000131, 74.02999999999884, 71.83000000000175, 75.75499999999738, 78.40000000000146, 72.5199999999968, 71.0399999999936, 72.45500000000175, 72.64500000000407, 69.75500000000466, 77.22999999999593, 74.81500000000233, 73.3350000000064, 75.57499999999709, 69.1649999999936, 71.26999999998952, 74.48000000001048, 76.58999999999651, 72.55000000000291, 74.38999999999942, 69.80499999999302, 73.84000000001106], "unit": "ms", "name": "3-customize-enter-css.error.TART", "value": 73.58750000000873}], "name": "cart", "value": 22.639741217093498}]} 08:55:52 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tscrollx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[287,1,31]]", "result": "2.75"}, "tsvgr_opacity": {"url": "http://graphs.mozilla.org/graph.html#tests=[[225,1,31]]", "result": "273.71"}, "tart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[293,1,31]]", "result": "3.99"}, "cart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[309,1,31]]", "result": "22.64"}, "tsvgx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[281,1,31]]", "result": "121.68"}}} 08:55:52 INFO - Return code: 0 08:55:52 INFO - Validating PERFHERDER_DATA against C:\slave\test-pgo\build\tests\talos\treeherder-schemas\performance-artifact.json 08:55:52 ERROR - # TBPL SUCCESS # 08:55:52 INFO - Running post-action listener: _resource_record_post_action 08:55:52 INFO - Running post-run listener: _resource_record_post_run 08:55:53 INFO - Total resource usage - Wall time: 965s; CPU: 8.0%; Read bytes: 279936000; Write bytes: 543942144; Read time: 10963180; Write time: 8720240 08:55:53 INFO - install - Wall time: 3s; CPU: 12.0%; Read bytes: 0; Write bytes: 32569856; Read time: 0; Write time: 50580 08:55:53 INFO - run-tests - Wall time: 963s; CPU: 8.0%; Read bytes: 279893504; Write bytes: 464170496; Read time: 10953500; Write time: 8614090 08:55:53 INFO - Running post-run listener: _upload_blobber_files 08:55:53 INFO - Blob upload gear active. 08:55:53 INFO - Preparing to upload files from C:\slave\test-pgo\build\blobber_upload_dir. 08:55:53 INFO - Files from C:\slave\test-pgo\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:55:53 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test-pgo\\oauth.txt', '-b', 'Firefox', '-d', 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test-pgo\\build\\uploaded_files.json'] 08:55:53 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test-pgo\oauth.txt -b Firefox -d C:\slave\test-pgo\build\blobber_upload_dir --output-manifest C:\slave\test-pgo\build\uploaded_files.json 08:55:54 INFO - (blobuploader) - INFO - Open directory for files ... 08:55:54 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log ... 08:55:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:54 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_errorsummary.log: uploaded 08:55:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:54 INFO - (blobuploader) - INFO - Done attempting. 08:55:54 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log ... 08:55:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:56 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_raw.log: uploaded 08:55:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:56 INFO - (blobuploader) - INFO - Done attempting. 08:55:56 INFO - (blobuploader) - INFO - Iteration through files over. 08:55:56 INFO - Return code: 0 08:55:56 INFO - rmtree: C:\slave\test-pgo\build\uploaded_files.json 08:55:56 INFO - Using _rmtree_windows ... 08:55:56 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build\\uploaded_files.json',), kwargs: {}, attempt #1 08:55:56 INFO - Setting buildbot property blobber_files to {"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f4c59390b98d1de6f41ec5b32d06654e6bba95f79ae972356d09130f7eebe4c639189f8d8d9967684994828ce5bf21a0b62bdd58fed827403944ec8f2963a23", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c61f64d555aa557184a85e5108e57938ae79d6d1e44842be3ee90d602961f0f85292d10e04425ee0d2ff43af6183cb7437b89d99258df9c739847f6b1302103"} 08:55:56 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test-pgo\properties\blobber_files 08:55:56 INFO - Writing to file C:\slave\test-pgo\properties\blobber_files 08:55:56 INFO - Contents: 08:55:56 INFO - blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f4c59390b98d1de6f41ec5b32d06654e6bba95f79ae972356d09130f7eebe4c639189f8d8d9967684994828ce5bf21a0b62bdd58fed827403944ec8f2963a23", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c61f64d555aa557184a85e5108e57938ae79d6d1e44842be3ee90d602961f0f85292d10e04425ee0d2ff43af6183cb7437b89d99258df9c739847f6b1302103"} 08:55:56 INFO - Running post-run listener: copy_logs_to_upload_dir 08:55:56 INFO - Copying logs to upload dir... 08:55:56 INFO - mkdir: C:\slave\test-pgo\build\upload\logs 08:55:56 INFO - Copying logs to upload dir... 08:55:56 INFO - Using _rmtree_windows ... 08:55:56 INFO - Using _rmtree_windows ... 08:55:56 INFO - Using _rmtree_windows ... 08:55:56 INFO - Using _rmtree_windows ... 08:55:56 INFO - Using _rmtree_windows ... 08:55:56 INFO - Using _rmtree_windows ... 08:55:56 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1224.838000 ========= master_lag: -0.09 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-01-26 08:55:56.723933) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-26 08:55:56.730114) ========= 'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f' in dir C:\slave\test-pgo\properties (timeout 1200 secs) watching logfiles {} argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-162\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-162 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-162 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-162\AppData\Local LOGONSERVER=\\T-W864-IX-162 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-pgo\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-162 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-162 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-162 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f4c59390b98d1de6f41ec5b32d06654e6bba95f79ae972356d09130f7eebe4c639189f8d8d9967684994828ce5bf21a0b62bdd58fed827403944ec8f2963a23", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c61f64d555aa557184a85e5108e57938ae79d6d1e44842be3ee90d602961f0f85292d10e04425ee0d2ff43af6183cb7437b89d99258df9c739847f6b1302103"} build_url:https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/NftShH61SwShwMTs6fWMPQ/artifacts/public/build/firefox-47.0a1.en-US.win64.zip' blobber_files: '{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f4c59390b98d1de6f41ec5b32d06654e6bba95f79ae972356d09130f7eebe4c639189f8d8d9967684994828ce5bf21a0b62bdd58fed827403944ec8f2963a23", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c61f64d555aa557184a85e5108e57938ae79d6d1e44842be3ee90d602961f0f85292d10e04425ee0d2ff43af6183cb7437b89d99258df9c739847f6b1302103"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-26 08:55:56.853411) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 08:55:56.853762) ========= 'rm' '-f' 'oauth.txt' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-162\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-162 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-162 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-162\AppData\Local LOGONSERVER=\\T-W864-IX-162 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-pgo 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-162 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-162 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-162 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 08:55:56.970468) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-01-26 08:55:56.970783) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-01-26 08:55:57.359766) ========= ========= Total master_lag: 0.19 =========