builder: b2g-inbound_win8_64_test-dromaeojs-pgo slave: t-w864-ix-123 starttime: 1445854374.18 results: success (0) buildid: 20151026023015 builduid: 123146fa571745e2ac9482c1b1447113 revision: 8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 03:12:54.183120) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 03:12:54.185600) ========= ========= Started set props: basedir (results: 0, elapsed: 22 secs) (at 2015-10-26 03:12:54.185940) ========= '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-123\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-123 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-123 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-123\AppData\Local LOGONSERVER=\\T-W864-IX-123 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-123 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-123 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-123 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False C:\slave\test-pgo program finished with exit code 0 elapsedTime=0.603000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 22 secs) (at 2015-10-26 03:13:16.997297) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 42 secs) (at 2015-10-26 03:13:16.997641) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 42 secs) (at 2015-10-26 03:13:59.223816) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 03:13:59.224250) ========= '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-123\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-123 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-123 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-123\AppData\Local LOGONSERVER=\\T-W864-IX-123 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-123 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-123 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-123 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.201000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 03:14:00.022443) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 03:14:00.022773) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 03:14:00.023523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-26 03:14:00.023828) ========= '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-123\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-123 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-123 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-123\AppData\Local LOGONSERVER=\\T-W864-IX-123 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-123 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-123 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-123 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False --03:14:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.27 GB/s 03:14:01 (2.27 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.540000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-26 03:14:01.838217) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2015-10-26 03:14:01.838529) ========= '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-123\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-123 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-123 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-123\AppData\Local LOGONSERVER=\\T-W864-IX-123 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-123 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-123 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-123 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=2.307000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2015-10-26 03:14:05.659255) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 03:14:05.659583) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55 --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-123\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-123 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-123 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-123\AppData\Local LOGONSERVER=\\T-W864-IX-123 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-123 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-123 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-123 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-26 03:14:06,400 truncating revision to first 12 chars 2015-10-26 03:14:06,401 Setting DEBUG logging. 2015-10-26 03:14:06,401 attempt 1/10 2015-10-26 03:14:06,401 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/8631cff3e818?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-26 03:14:06,927 unpacking tar archive at: b2g-inbound-8631cff3e818/testing/mozharness/ program finished with exit code 0 elapsedTime=1.185000 ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 03:14:08.189469) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 26 secs) (at 2015-10-26 03:14:08.189786) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 26 secs) (at 2015-10-26 03:14:34.823225) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 03:14:34.823488) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 03:14:34.824285) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 10 secs) (at 2015-10-26 03:14:34.824584) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'B2G-Inbound' '--system-bits' '64' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'B2G-Inbound' 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', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'B2G-Inbound', '--system-bits', '64', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-123\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-123 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-123 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-123\AppData\Local LOGONSERVER=\\T-W864-IX-123 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-123 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-123 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-123 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 03:14:35 INFO - MultiFileLogger online at 20151026 03:14:35 in C:\slave\test-pgo 03:14:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 03:14:35 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 03:14:35 INFO - {'append_to_log': False, 03:14:35 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 03:14:35 INFO - 'blob_upload_branch': 'B2G-Inbound', 03:14:35 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 03:14:35 INFO - 'branch': 'B2G-Inbound', 03:14:35 INFO - 'buildbot_json_path': 'buildprops.json', 03:14:35 INFO - 'config_files': ('talos/windows_config.py',), 03:14:35 INFO - 'default_actions': ('clobber', 03:14:35 INFO - 'read-buildbot-config', 03:14:35 INFO - 'download-and-extract', 03:14:35 INFO - 'populate-webroot', 03:14:35 INFO - 'create-virtualenv', 03:14:35 INFO - 'install', 03:14:35 INFO - 'run-tests'), 03:14:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:14:35 INFO - 'download_minidump_stackwalk': True, 03:14:35 INFO - 'download_symbols': 'ondemand', 03:14:35 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 03:14:35 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 03:14:35 INFO - 'hg': 'c:/mozilla-build/hg/hg', 03:14:35 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 03:14:35 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 03:14:35 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 03:14:35 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 03:14:35 INFO - 'C:/mozilla-build/tooltool.py'), 03:14:35 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 03:14:35 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 03:14:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:14:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:14:35 INFO - 'installer_path': 'installer.exe', 03:14:35 INFO - 'log_level': 'info', 03:14:35 INFO - 'log_name': 'talos', 03:14:35 INFO - 'log_to_console': True, 03:14:35 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 03:14:35 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 03:14:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 03:14:35 INFO - 'opt_config_files': (), 03:14:35 INFO - 'pip_index': False, 03:14:35 INFO - 'sps_profile': False, 03:14:35 INFO - 'sps_profile_interval': 0, 03:14:35 INFO - 'suite': 'dromaeojs', 03:14:35 INFO - 'system_bits': '64', 03:14:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:14:35 INFO - 'title': 'T-W864-IX-123', 03:14:35 INFO - 'use_talos_json': True, 03:14:35 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 03:14:35 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 03:14:35 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 03:14:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:14:35 INFO - 'work_dir': 'build'} 03:14:35 INFO - ##### 03:14:35 INFO - ##### Running clobber step. 03:14:35 INFO - ##### 03:14:35 INFO - Running pre-action listener: _resource_record_pre_action 03:14:35 INFO - Running main action method: clobber 03:14:35 INFO - rmtree: C:\slave\test-pgo\build 03:14:35 INFO - Using _rmtree_windows ... 03:14:35 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 03:15:31 INFO - Running post-action listener: _resource_record_post_action 03:15:31 INFO - ##### 03:15:31 INFO - ##### Running read-buildbot-config step. 03:15:31 INFO - ##### 03:15:31 INFO - Running pre-action listener: _resource_record_pre_action 03:15:31 INFO - Running main action method: read_buildbot_config 03:15:31 INFO - Using buildbot properties: 03:15:31 INFO - { 03:15:31 INFO - "properties": { 03:15:31 INFO - "buildnumber": 304, 03:15:31 INFO - "product": "firefox", 03:15:31 INFO - "script_repo_revision": "production", 03:15:31 INFO - "builddir": "b2g-inbound_win8_64_test-dromaeojs", 03:15:31 INFO - "repository": "", 03:15:31 INFO - "buildername": "Windows 8 64-bit b2g-inbound pgo talos dromaeojs", 03:15:31 INFO - "buildid": "20151026023015", 03:15:31 INFO - "slavename": "t-w864-ix-123", 03:15:31 INFO - "pgo_build": "True", 03:15:31 INFO - "basedir": "C:\\slave\\test-pgo", 03:15:31 INFO - "project": "", 03:15:31 INFO - "platform": "win8_64", 03:15:31 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 03:15:31 INFO - "slavebuilddir": "test", 03:15:31 INFO - "scheduler": "tests-b2g-inbound-win64-pgo-talos", 03:15:31 INFO - "branch": "b2g-inbound", 03:15:31 INFO - "repo_path": "integration/b2g-inbound", 03:15:31 INFO - "stage_platform": "win64-pgo", 03:15:31 INFO - "builduid": "123146fa571745e2ac9482c1b1447113", 03:15:31 INFO - "revision": "8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55" 03:15:31 INFO - }, 03:15:31 INFO - "sourcestamp": { 03:15:31 INFO - "repository": "", 03:15:31 INFO - "hasPatch": false, 03:15:31 INFO - "project": "", 03:15:31 INFO - "branch": "b2g-inbound-win64-pgo-talos", 03:15:31 INFO - "changes": [ 03:15:31 INFO - { 03:15:31 INFO - "category": null, 03:15:31 INFO - "files": [ 03:15:31 INFO - { 03:15:31 INFO - "url": null, 03:15:31 INFO - "name": "https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip" 03:15:31 INFO - } 03:15:31 INFO - ], 03:15:31 INFO - "repository": "", 03:15:31 INFO - "rev": "8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55", 03:15:31 INFO - "who": "sendchange", 03:15:31 INFO - "when": 1445854295, 03:15:31 INFO - "number": 6572617, 03:15:31 INFO - "comments": "", 03:15:31 INFO - "project": "", 03:15:31 INFO - "at": "Mon 26 Oct 2015 03:11:35", 03:15:31 INFO - "branch": "b2g-inbound-win64-pgo-talos", 03:15:31 INFO - "revlink": "", 03:15:31 INFO - "properties": [ 03:15:31 INFO - [ 03:15:31 INFO - "buildid", 03:15:31 INFO - "20151026023015", 03:15:31 INFO - "Change" 03:15:31 INFO - ], 03:15:31 INFO - [ 03:15:31 INFO - "builduid", 03:15:31 INFO - "123146fa571745e2ac9482c1b1447113", 03:15:31 INFO - "Change" 03:15:31 INFO - ], 03:15:31 INFO - [ 03:15:31 INFO - "pgo_build", 03:15:31 INFO - "True", 03:15:31 INFO - "Change" 03:15:31 INFO - ] 03:15:31 INFO - ], 03:15:31 INFO - "revision": "8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55" 03:15:31 INFO - } 03:15:31 INFO - ], 03:15:31 INFO - "revision": "8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55" 03:15:31 INFO - } 03:15:31 INFO - } 03:15:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip. 03:15:31 INFO - Running post-action listener: _resource_record_post_action 03:15:31 INFO - ##### 03:15:31 INFO - ##### Running download-and-extract step. 03:15:31 INFO - ##### 03:15:31 INFO - Running pre-action listener: _resource_record_pre_action 03:15:31 INFO - Running main action method: download_and_extract 03:15:31 INFO - mkdir: C:\slave\test-pgo\build\tests 03:15:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:31 INFO - https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:15:31 INFO - trying https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/test_packages.json 03:15:31 INFO - Downloading https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 03:15:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 03:15:32 INFO - Downloaded 1221 bytes. 03:15:32 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 03:15:32 INFO - Using the following test package requirements: 03:15:32 INFO - {u'common': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 03:15:32 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 03:15:32 INFO - u'firefox-44.0a1.en-US.win64.cppunittest.tests.zip'], 03:15:32 INFO - u'jittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 03:15:32 INFO - u'jsshell-win64.zip'], 03:15:32 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 03:15:32 INFO - u'firefox-44.0a1.en-US.win64.mochitest.tests.zip'], 03:15:32 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 03:15:32 INFO - u'reftest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 03:15:32 INFO - u'firefox-44.0a1.en-US.win64.reftest.tests.zip'], 03:15:32 INFO - u'talos': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 03:15:32 INFO - u'firefox-44.0a1.en-US.win64.talos.tests.zip'], 03:15:32 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 03:15:32 INFO - u'firefox-44.0a1.en-US.win64.web-platform.tests.zip'], 03:15:32 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 03:15:32 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 03:15:32 INFO - u'firefox-44.0a1.en-US.win64.xpcshell.tests.zip']} 03:15:32 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win64.common.tests.zip'] for test suite category: common 03:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:15:32 INFO - https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 03:15:32 INFO - trying https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip 03:15:32 INFO - Downloading https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.common.tests.zip 03:15:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'}, attempt #1 ProcessManager NOT managing child processes 03:15:33 INFO - Downloaded 18100928 bytes. 03:15:33 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] in C:\slave\test-pgo\build\tests 03:15:33 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.common.tests.zip 03:15:33 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] with output_timeout 1760 ProcessManager UNABLE to use job objects to manage child processes 03:16:02 INFO - Return code: 0 03:16:02 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win64.common.tests.zip', u'firefox-44.0a1.en-US.win64.talos.tests.zip'] for test suite category: talos 03:16:02 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')]} 03:16:02 INFO - https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 03:16:02 INFO - trying https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip 03:16:02 INFO - Downloading https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.common.tests.zip 03:16:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'}, attempt #1 ProcessManager NOT managing child processes 03:16:03 INFO - Downloaded 18100928 bytes. 03:16:03 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] in C:\slave\test-pgo\build\tests 03:16:03 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.common.tests.zip 03:16:03 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] with output_timeout 1760 ProcessManager UNABLE to use job objects to manage child processes 03:16:24 INFO - Return code: 0 03:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:24 INFO - https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip matches https://queue.taskcluster.net 03:16:24 INFO - trying https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip 03:16:24 INFO - Downloading https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.talos.tests.zip 03:16:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.talos.tests.zip'}, attempt #1 ProcessManager NOT managing child processes 03:16:27 INFO - Downloaded 11131307 bytes. 03:16:27 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.talos.tests.zip'] in C:\slave\test-pgo\build\tests 03:16:27 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.talos.tests.zip 03:16:27 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.talos.tests.zip'] with output_timeout 1760 ProcessManager UNABLE to use job objects to manage child processes 03:16:28 INFO - Return code: 0 03:16:28 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')]} 03:16:28 INFO - https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip matches https://queue.taskcluster.net 03:16:28 INFO - trying https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 03:16:28 INFO - Downloading https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.zip 03:16:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.zip'}, attempt #1 03:16:30 INFO - Downloaded 55517105 bytes. 03:16:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 03:16:30 INFO - mkdir: C:\slave\test-pgo\properties 03:16:30 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 03:16:30 INFO - Writing to file C:\slave\test-pgo\properties\build_url 03:16:30 INFO - Contents: 03:16:30 INFO - build_url:https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 03:16:30 INFO - Running post-action listener: _resource_record_post_action 03:16:30 INFO - Running post-action listener: set_extra_try_arguments 03:16:30 INFO - ##### 03:16:30 INFO - ##### Running populate-webroot step. 03:16:30 INFO - ##### 03:16:30 INFO - Running pre-action listener: _resource_record_pre_action 03:16:30 INFO - Running main action method: populate_webroot 03:16:30 INFO - Guessing talos json url... 03:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:30 INFO - https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt matches https://queue.taskcluster.net 03:16:30 INFO - trying https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt 03:16:30 INFO - Downloading https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.txt 03:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win64.txt'}, attempt #1 03:16:30 INFO - Downloaded 107 bytes. 03:16:30 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.txt 03:16:30 INFO - Contents: 03:16:30 INFO - 20151026023015 03:16:30 INFO - https://hg.mozilla.org/integration/b2g-inbound/rev/8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55 03:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:30 INFO - trying https://hg.mozilla.org/integration/b2g-inbound/raw-file/8631cff3e8/testing/talos/talos.json 03:16:30 INFO - Downloading https://hg.mozilla.org/integration/b2g-inbound/raw-file/8631cff3e8/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 03:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/b2g-inbound/raw-file/8631cff3e8/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 03:16:31 INFO - Downloaded 4264 bytes. 03:16:31 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 03:16:31 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 03:16:31 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 03:16:31 INFO - u'../fennec_ids.txt'], 03:16:31 INFO - u'tests': [u'tcheck2']}, 03:16:31 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 03:16:31 INFO - u'--tppagecycles', 03:16:31 INFO - u'7'], 03:16:31 INFO - u'tests': [u'tsvgm']}}, 03:16:31 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 03:16:31 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 03:16:31 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 03:16:31 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 03:16:31 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 03:16:31 INFO - u'tests': [u'dromaeo_css', 03:16:31 INFO - u'kraken', 03:16:31 INFO - u'v8_7']}, 03:16:31 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:31 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 03:16:31 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:31 INFO - u'talos_options': [u'--e10s'], 03:16:31 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 03:16:31 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:31 INFO - u'tests': [u'damp', u'tps']}, 03:16:31 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:31 INFO - u'talos_options': [u'--e10s'], 03:16:31 INFO - u'tests': [u'damp', u'tps']}, 03:16:31 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 03:16:31 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 03:16:31 INFO - u'other': {u'tests': [u'a11yr', 03:16:31 INFO - u'ts_paint', 03:16:31 INFO - u'tpaint', 03:16:31 INFO - u'sessionrestore', 03:16:31 INFO - u'sessionrestore_no_auto_restore']}, 03:16:31 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 03:16:31 INFO - u'tests': [u'a11yr', 03:16:31 INFO - u'ts_paint', 03:16:31 INFO - u'tpaint', 03:16:31 INFO - u'sessionrestore', 03:16:31 INFO - u'sessionrestore_no_auto_restore']}, 03:16:31 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 03:16:31 INFO - u'tests': [u'a11yr', 03:16:31 INFO - u'ts_paint', 03:16:31 INFO - u'tpaint', 03:16:31 INFO - u'sessionrestore', 03:16:31 INFO - u'sessionrestore_no_auto_restore']}, 03:16:31 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 03:16:31 INFO - u'tests': [u'a11yr', 03:16:31 INFO - u'ts_paint', 03:16:31 INFO - u'tpaint', 03:16:31 INFO - u'sessionrestore', 03:16:31 INFO - u'sessionrestore_no_auto_restore']}, 03:16:31 INFO - u'other_l64': {u'tests': [u'a11yr', 03:16:31 INFO - u'ts_paint', 03:16:31 INFO - u'tpaint', 03:16:31 INFO - u'sessionrestore', 03:16:31 INFO - u'sessionrestore_no_auto_restore']}, 03:16:31 INFO - u'other_nol64': {u'tests': [u'a11yr', 03:16:31 INFO - u'ts_paint', 03:16:31 INFO - u'tpaint', 03:16:31 INFO - u'sessionrestore', 03:16:31 INFO - u'sessionrestore_no_auto_restore']}, 03:16:31 INFO - u'svgr': {u'tests': [u'tsvgx', 03:16:31 INFO - u'tsvgr_opacity', 03:16:31 INFO - u'tart', 03:16:31 INFO - u'tscrollx', 03:16:31 INFO - u'cart']}, 03:16:31 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 03:16:31 INFO - u'tests': [u'tsvgx', 03:16:31 INFO - u'tsvgr_opacity', 03:16:31 INFO - u'tart', 03:16:31 INFO - u'tscrollx', 03:16:31 INFO - u'cart']}, 03:16:31 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:31 INFO - u'tests': [u'tp5o']}, 03:16:31 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:31 INFO - u'talos_options': [u'--e10s'], 03:16:31 INFO - u'tests': [u'tp5o']}, 03:16:31 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:31 INFO - u'talos_options': [u'--xperf_path', 03:16:31 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:31 INFO - u'tests': [u'tp5n']}, 03:16:31 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:31 INFO - u'talos_options': [u'--e10s', 03:16:31 INFO - u'--xperf_path', 03:16:31 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 03:16:31 INFO - u'C:/slave/talos-data/talos/xperf.config'], 03:16:31 INFO - u'tests': [u'tp5n']}}, 03:16:31 INFO - u'talos.zip': {u'path': u'', 03:16:31 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:16:31 INFO - Running post-action listener: _resource_record_post_action 03:16:31 INFO - ##### 03:16:31 INFO - ##### Running create-virtualenv step. 03:16:31 INFO - ##### 03:16:31 INFO - Running pre-action listener: _resource_record_pre_action 03:16:31 INFO - Running main action method: create_virtualenv 03:16:31 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 03:16:31 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 03:16:31 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 03:16:31 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'! 03:16:31 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 03:16:31 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 03:16:37 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 03:16:40 INFO - Installing distribute......................................................................................................................................................................................done. 03:16:41 INFO - Return code: 0 03:16:41 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 03:16: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')]} 03:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16: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')]} 03:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:41 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0233DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022F9EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023D3A28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00498B48>, '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 0x004BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023A7660>, '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-123', 'USERDOMAIN': 'T-W864-IX-123', '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-123', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-123', '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-123\\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 03:16:42 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 03:16:42 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:16:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:16:42 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Roaming', 03:16:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 03:16:42 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 03:16:42 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 03:16:42 INFO - 'COMPUTERNAME': 'T-W864-IX-123', 03:16:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:16:42 INFO - 'DCLOCATION': 'SCL3', 03:16:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:16:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:16:42 INFO - 'HOMEDRIVE': 'C:', 03:16:42 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 03:16:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:16:42 INFO - 'KTS_VERSION': '1.19c', 03:16:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 03:16:42 INFO - 'LOGONSERVER': '\\\\T-W864-IX-123', 03:16:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:16:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:42 INFO - 'MOZ_NO_REMOTE': '1', 03:16:42 INFO - 'NO_EM_RESTART': '1', 03:16:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:16:42 INFO - 'OS': 'Windows_NT', 03:16:42 INFO - 'OURDRIVE': 'C:', 03:16:42 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', 03:16:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:16:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:16:42 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 03:16:42 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 03:16:42 INFO - 'PROCESSOR_LEVEL': '6', 03:16:42 INFO - 'PROCESSOR_REVISION': '1e05', 03:16:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:16:42 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 03:16:42 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 03:16:42 INFO - 'PROGRAMW6432': 'C:\\Program Files', 03:16:42 INFO - 'PROMPT': '$P$G', 03:16:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 03:16:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:16:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:16:42 INFO - 'PWD': 'C:\\slave\\test-pgo', 03:16:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:16:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:16:42 INFO - 'SYSTEMDRIVE': 'C:', 03:16:42 INFO - 'SYSTEMROOT': 'C:\\windows', 03:16:42 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:42 INFO - 'TEST1': 'testie', 03:16:42 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:42 INFO - 'USERDOMAIN': 'T-W864-IX-123', 03:16:42 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 03:16:42 INFO - 'USERNAME': 'cltbld', 03:16:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-123', 03:16:42 INFO - 'WINDIR': 'C:\\windows', 03:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:16:42 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:16:42 INFO - Downloading/unpacking pip>=1.5 03:16:42 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 03:16:47 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 03:16:47 INFO - Running setup.py egg_info for package pip 03:16:47 INFO - warning: no files found matching 'pip\cacert.pem' 03:16:47 INFO - warning: no files found matching '*.html' under directory 'docs' 03:16:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 03:16:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 03:16:47 INFO - Installing collected packages: pip 03:16:47 INFO - Found existing installation: pip 0.8.2 03:16:47 INFO - Uninstalling pip: 03:16:47 INFO - Successfully uninstalled pip 03:16:47 INFO - Running setup.py install for pip 03:16:47 INFO - warning: no files found matching 'pip\cacert.pem' 03:16:47 INFO - warning: no files found matching '*.html' under directory 'docs' 03:16:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 03:16:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 03:16:47 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:16:47 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 03:16:47 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:16:47 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 03:16:47 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:16:47 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 03:16:47 INFO - Successfully installed pip 03:16:47 INFO - Cleaning up... 03:16:47 INFO - Return code: 0 03:16:47 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 03:16: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')]} 03:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16: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')]} 03:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:47 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0233DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022F9EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023D3A28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00498B48>, '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 0x004BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023A7660>, '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-123', 'USERDOMAIN': 'T-W864-IX-123', '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-123', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-123', '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-123\\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 03:16:47 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test-pgo\build 03:16:47 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:16:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:16:47 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Roaming', 03:16:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 03:16:47 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 03:16:47 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 03:16:47 INFO - 'COMPUTERNAME': 'T-W864-IX-123', 03:16:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:16:47 INFO - 'DCLOCATION': 'SCL3', 03:16:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:16:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:16:47 INFO - 'HOMEDRIVE': 'C:', 03:16:47 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 03:16:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:16:47 INFO - 'KTS_VERSION': '1.19c', 03:16:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 03:16:47 INFO - 'LOGONSERVER': '\\\\T-W864-IX-123', 03:16:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:16:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:47 INFO - 'MOZ_NO_REMOTE': '1', 03:16:47 INFO - 'NO_EM_RESTART': '1', 03:16:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:16:47 INFO - 'OS': 'Windows_NT', 03:16:47 INFO - 'OURDRIVE': 'C:', 03:16:47 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', 03:16:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:16:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:16:47 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 03:16:47 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 03:16:47 INFO - 'PROCESSOR_LEVEL': '6', 03:16:47 INFO - 'PROCESSOR_REVISION': '1e05', 03:16:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:16:47 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 03:16:47 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 03:16:47 INFO - 'PROGRAMW6432': 'C:\\Program Files', 03:16:47 INFO - 'PROMPT': '$P$G', 03:16:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 03:16:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:16:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:16:47 INFO - 'PWD': 'C:\\slave\\test-pgo', 03:16:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:16:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:16:47 INFO - 'SYSTEMDRIVE': 'C:', 03:16:47 INFO - 'SYSTEMROOT': 'C:\\windows', 03:16:47 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:47 INFO - 'TEST1': 'testie', 03:16:47 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:47 INFO - 'USERDOMAIN': 'T-W864-IX-123', 03:16:47 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 03:16:47 INFO - 'USERNAME': 'cltbld', 03:16:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-123', 03:16:47 INFO - 'WINDIR': 'C:\\windows', 03:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:49 INFO - Downloading/unpacking psutil>=0.7.1 03:16:49 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 03:16:49 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 03:16:50 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl 03:16:50 INFO - Installing collected packages: psutil 03:16:50 INFO - Successfully installed psutil 03:16:50 INFO - Cleaning up... 03:16:51 INFO - Return code: 0 03:16:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 03:16:51 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')]} 03:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:51 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')]} 03:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0233DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022F9EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023D3A28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00498B48>, '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 0x004BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023A7660>, '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-123', 'USERDOMAIN': 'T-W864-IX-123', '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-123', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-123', '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-123\\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 03:16:51 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build 03:16:51 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:16:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:16:51 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Roaming', 03:16:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 03:16:51 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 03:16:51 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 03:16:51 INFO - 'COMPUTERNAME': 'T-W864-IX-123', 03:16:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:16:51 INFO - 'DCLOCATION': 'SCL3', 03:16:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:16:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:16:51 INFO - 'HOMEDRIVE': 'C:', 03:16:51 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 03:16:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:16:51 INFO - 'KTS_VERSION': '1.19c', 03:16:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 03:16:51 INFO - 'LOGONSERVER': '\\\\T-W864-IX-123', 03:16:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:16:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:51 INFO - 'MOZ_NO_REMOTE': '1', 03:16:51 INFO - 'NO_EM_RESTART': '1', 03:16:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:16:51 INFO - 'OS': 'Windows_NT', 03:16:51 INFO - 'OURDRIVE': 'C:', 03:16:51 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', 03:16:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:16:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:16:51 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 03:16:51 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 03:16:51 INFO - 'PROCESSOR_LEVEL': '6', 03:16:51 INFO - 'PROCESSOR_REVISION': '1e05', 03:16:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:16:51 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 03:16:51 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 03:16:51 INFO - 'PROGRAMW6432': 'C:\\Program Files', 03:16:51 INFO - 'PROMPT': '$P$G', 03:16:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 03:16:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:16:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:16:51 INFO - 'PWD': 'C:\\slave\\test-pgo', 03:16:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:16:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:16:51 INFO - 'SYSTEMDRIVE': 'C:', 03:16:51 INFO - 'SYSTEMROOT': 'C:\\windows', 03:16:51 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:51 INFO - 'TEST1': 'testie', 03:16:51 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:51 INFO - 'USERDOMAIN': 'T-W864-IX-123', 03:16:51 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 03:16:51 INFO - 'USERNAME': 'cltbld', 03:16:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-123', 03:16:51 INFO - 'WINDIR': 'C:\\windows', 03:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:16:51 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 03:16:51 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 03:16:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:16:52 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 03:16:52 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 03:16:52 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) 03:16:52 INFO - Installing collected packages: mozsystemmonitor 03:16:52 INFO - Running setup.py install for mozsystemmonitor 03:16:52 INFO - Successfully installed mozsystemmonitor 03:16:52 INFO - Cleaning up... 03:16:52 INFO - Return code: 0 03:16:52 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 03:16:52 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')]} 03:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:52 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')]} 03:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:52 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0233DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022F9EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023D3A28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00498B48>, '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 0x004BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023A7660>, '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-123', 'USERDOMAIN': 'T-W864-IX-123', '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-123', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-123', '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-123\\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 03:16:52 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build 03:16:52 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:16:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:16:52 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Roaming', 03:16:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 03:16:52 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 03:16:52 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 03:16:52 INFO - 'COMPUTERNAME': 'T-W864-IX-123', 03:16:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:16:52 INFO - 'DCLOCATION': 'SCL3', 03:16:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:16:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:16:52 INFO - 'HOMEDRIVE': 'C:', 03:16:52 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 03:16:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:16:52 INFO - 'KTS_VERSION': '1.19c', 03:16:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 03:16:52 INFO - 'LOGONSERVER': '\\\\T-W864-IX-123', 03:16:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:16:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:52 INFO - 'MOZ_NO_REMOTE': '1', 03:16:52 INFO - 'NO_EM_RESTART': '1', 03:16:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:16:52 INFO - 'OS': 'Windows_NT', 03:16:52 INFO - 'OURDRIVE': 'C:', 03:16: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', 03:16:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:16:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:16:52 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 03:16:52 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 03:16:52 INFO - 'PROCESSOR_LEVEL': '6', 03:16:52 INFO - 'PROCESSOR_REVISION': '1e05', 03:16:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:16:52 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 03:16:52 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 03:16:52 INFO - 'PROGRAMW6432': 'C:\\Program Files', 03:16:52 INFO - 'PROMPT': '$P$G', 03:16:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 03:16:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:16:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:16:52 INFO - 'PWD': 'C:\\slave\\test-pgo', 03:16:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:16:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:16:52 INFO - 'SYSTEMDRIVE': 'C:', 03:16:52 INFO - 'SYSTEMROOT': 'C:\\windows', 03:16:52 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:52 INFO - 'TEST1': 'testie', 03:16:52 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:52 INFO - 'USERDOMAIN': 'T-W864-IX-123', 03:16:52 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 03:16:52 INFO - 'USERNAME': 'cltbld', 03:16:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-123', 03:16:52 INFO - 'WINDIR': 'C:\\windows', 03:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:53 INFO - Downloading/unpacking blobuploader==1.2.4 03:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:53 INFO - Downloading blobuploader-1.2.4.tar.gz 03:16:53 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 03:16:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 03:16:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:16:54 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 03:16:54 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 03:16:54 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 03:16:54 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 03:16:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:16:54 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 03:16:54 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 03:16:54 INFO - Downloading docopt-0.6.1.tar.gz 03:16:54 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 03:16:54 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 03:16:54 INFO - Installing collected packages: blobuploader, requests, docopt 03:16:54 INFO - Running setup.py install for blobuploader 03:16:54 INFO - Running setup.py install for requests 03:16:55 INFO - Running setup.py install for docopt 03:16:55 INFO - Successfully installed blobuploader requests docopt 03:16:55 INFO - Cleaning up... 03:16:57 INFO - Return code: 0 03:16:57 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 03:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:57 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0233DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022F9EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023D3A28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00498B48>, '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 0x004BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023A7660>, '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-123', 'USERDOMAIN': 'T-W864-IX-123', '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-123', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-123', '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-123\\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 03:16:57 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 03:16:57 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:16:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:16:57 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Roaming', 03:16:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 03:16:57 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 03:16:57 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 03:16:57 INFO - 'COMPUTERNAME': 'T-W864-IX-123', 03:16:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:16:57 INFO - 'DCLOCATION': 'SCL3', 03:16:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:16:57 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:16:57 INFO - 'HOMEDRIVE': 'C:', 03:16:57 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 03:16:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:16:57 INFO - 'KTS_VERSION': '1.19c', 03:16:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 03:16:57 INFO - 'LOGONSERVER': '\\\\T-W864-IX-123', 03:16:57 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:16:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:57 INFO - 'MOZ_NO_REMOTE': '1', 03:16:57 INFO - 'NO_EM_RESTART': '1', 03:16:57 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:16:57 INFO - 'OS': 'Windows_NT', 03:16:57 INFO - 'OURDRIVE': 'C:', 03:16:57 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 03:16:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:16:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:16:57 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 03:16:57 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 03:16:57 INFO - 'PROCESSOR_LEVEL': '6', 03:16:57 INFO - 'PROCESSOR_REVISION': '1e05', 03:16:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:16:57 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 03:16:57 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 03:16:57 INFO - 'PROGRAMW6432': 'C:\\Program Files', 03:16:57 INFO - 'PROMPT': '$P$G', 03:16:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 03:16:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:16:57 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:16:57 INFO - 'PWD': 'C:\\slave\\test-pgo', 03:16:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:16:57 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:16:57 INFO - 'SYSTEMDRIVE': 'C:', 03:16:57 INFO - 'SYSTEMROOT': 'C:\\windows', 03:16:57 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:57 INFO - 'TEST1': 'testie', 03:16:57 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:16:57 INFO - 'USERDOMAIN': 'T-W864-IX-123', 03:16:57 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 03:16:57 INFO - 'USERNAME': 'cltbld', 03:16:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-123', 03:16:57 INFO - 'WINDIR': 'C:\\windows', 03:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 03:16:57 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-rj_gbj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 03:16:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 03:16:57 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-oy0o7z-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 03:16:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 03:16:57 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-hcxzx5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 03:16:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 03:16:57 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-pixiow-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-3lzpau-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-1g82uf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-nv7dr7-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-nargpz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-jzsjyx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-kp_3n1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-raq218-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-hvohn6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-woduks-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 03:16:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 03:16:58 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-4zmsay-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 03:16:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 03:16:59 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ld2_it-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:16:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 03:16:59 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-jmmlju-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 03:16:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 03:16:59 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-nmj1q2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 03:16:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:16:59 INFO - Running setup.py install for manifestparser 03:16:59 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:16:59 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:00 INFO - Running setup.py install for mozcrash 03:17:00 INFO - Running setup.py install for mozdebug 03:17:00 INFO - Running setup.py install for mozdevice 03:17:01 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:01 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:01 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:01 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:01 INFO - Running setup.py install for mozfile 03:17:02 INFO - Running setup.py install for mozhttpd 03:17:02 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:02 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:02 INFO - Running setup.py install for mozinfo 03:17:03 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Running setup.py install for mozInstall 03:17:03 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:03 INFO - Running setup.py install for mozleak 03:17:04 INFO - Running setup.py install for mozlog 03:17:04 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:04 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:04 INFO - Running setup.py install for moznetwork 03:17:05 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:05 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:05 INFO - Running setup.py install for mozprocess 03:17:05 INFO - Running setup.py install for mozprofile 03:17:05 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:05 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:05 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:05 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:05 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:05 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:06 INFO - Running setup.py install for mozrunner 03:17:06 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:06 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:07 INFO - Running setup.py install for mozscreenshot 03:17:07 INFO - Running setup.py install for moztest 03:17:07 INFO - Running setup.py install for mozversion 03:17:07 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 03:17:07 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 03:17:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:17:08 INFO - Cleaning up... 03:17:08 INFO - Return code: 0 03:17:08 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 03:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:08 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0233DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022F9EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023D3A28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00498B48>, '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 0x004BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023A7660>, '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-123', 'USERDOMAIN': 'T-W864-IX-123', '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-123', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-123', '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-123\\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 03:17:08 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 03:17:08 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:17:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:17:08 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Roaming', 03:17:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 03:17:08 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 03:17:08 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 03:17:08 INFO - 'COMPUTERNAME': 'T-W864-IX-123', 03:17:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:17:08 INFO - 'DCLOCATION': 'SCL3', 03:17:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:17:08 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:17:08 INFO - 'HOMEDRIVE': 'C:', 03:17:08 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 03:17:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:17:08 INFO - 'KTS_VERSION': '1.19c', 03:17:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 03:17:08 INFO - 'LOGONSERVER': '\\\\T-W864-IX-123', 03:17:08 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:17:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:08 INFO - 'MOZ_NO_REMOTE': '1', 03:17:08 INFO - 'NO_EM_RESTART': '1', 03:17:08 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:17:08 INFO - 'OS': 'Windows_NT', 03:17:08 INFO - 'OURDRIVE': 'C:', 03:17:08 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', 03:17:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:17:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:17:08 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 03:17:08 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 03:17:08 INFO - 'PROCESSOR_LEVEL': '6', 03:17:08 INFO - 'PROCESSOR_REVISION': '1e05', 03:17:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:17:08 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 03:17:08 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 03:17:08 INFO - 'PROGRAMW6432': 'C:\\Program Files', 03:17:08 INFO - 'PROMPT': '$P$G', 03:17:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 03:17:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:17:08 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:17:08 INFO - 'PWD': 'C:\\slave\\test-pgo', 03:17:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:17:08 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:17:08 INFO - 'SYSTEMDRIVE': 'C:', 03:17:08 INFO - 'SYSTEMROOT': 'C:\\windows', 03:17:08 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:17:08 INFO - 'TEST1': 'testie', 03:17:08 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:17:08 INFO - 'USERDOMAIN': 'T-W864-IX-123', 03:17:08 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 03:17:08 INFO - 'USERNAME': 'cltbld', 03:17:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-123', 03:17:08 INFO - 'WINDIR': 'C:\\windows', 03:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 03:17:08 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-d3gxpc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 03:17:08 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)) 03:17:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 03:17:08 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-qp_fmu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 03:17:09 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)) 03:17:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 03:17:09 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-vyiozq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 03:17:09 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)) 03:17:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 03:17:09 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-vwcuzy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 03:17:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 03:17:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 03:17:09 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-hk1ity-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 03:17:09 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)) 03:17:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 03:17:09 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-inpdgq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 03:17:09 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)) 03:17:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 03:17:09 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ibn1ew-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 03:17:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7)) 03:17:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 03:17:09 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-7anct5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 03:17:09 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)) 03:17:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 03:17:09 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-rtkcmz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 03:17:09 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)) 03:17:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 03:17:09 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-acqxna-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 03:17:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 03:17:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 03:17:10 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-kgm18x-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 03:17:10 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)) 03:17:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 03:17:10 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-kmsjns-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 03:17:10 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)) 03:17:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 03:17:10 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-vucpnf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 03:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13)) 03:17:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 03:17:10 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-afxxbq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 03:17:10 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)) 03:17:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 03:17:10 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-hvppyd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:17:10 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)) 03:17:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 03:17:10 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-uktgs3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 03:17:10 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)) 03:17:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 03:17:10 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-vvey3n-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 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)) 03:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 03:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 03:17:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 03:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:11 INFO - Downloading blessings-1.5.1.tar.gz 03:17:11 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 03:17:11 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 03:17:12 INFO - Installing collected packages: blessings 03:17:12 INFO - Running setup.py install for blessings 03:17:12 INFO - Successfully installed blessings 03:17:12 INFO - Cleaning up... 03:17:15 INFO - Return code: 0 03:17:15 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 03:17:15 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 03:17:15 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 03:17:15 INFO - Reading from file tmpfile_stdout 03:17:15 INFO - Using _rmtree_windows ... 03:17:15 INFO - Using _rmtree_windows ... 03:17:15 INFO - Current package versions: 03:17:15 INFO - blessings == 1.5.1 03:17:15 INFO - blobuploader == 1.2.4 03:17:15 INFO - docopt == 0.6.1 03:17:15 INFO - manifestparser == 1.1 03:17:15 INFO - mozInstall == 1.12 03:17:15 INFO - mozcrash == 0.16 03:17:15 INFO - mozdebug == 0.1 03:17:15 INFO - mozdevice == 0.46 03:17:15 INFO - mozfile == 1.2 03:17:15 INFO - mozhttpd == 0.7 03:17:15 INFO - mozinfo == 0.8 03:17:15 INFO - mozleak == 0.1 03:17:15 INFO - mozlog == 3.0 03:17:15 INFO - moznetwork == 0.27 03:17:15 INFO - mozprocess == 0.22 03:17:15 INFO - mozprofile == 0.27 03:17:15 INFO - mozrunner == 6.11 03:17:15 INFO - mozscreenshot == 0.1 03:17:15 INFO - mozsystemmonitor == 0.0 03:17:15 INFO - moztest == 0.7 03:17:15 INFO - mozversion == 1.4 03:17:15 INFO - psutil == 3.1.1 03:17:15 INFO - requests == 1.2.3 03:17:15 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 03:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:15 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0233DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x022F9EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023D3A28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00498B48>, '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 0x004BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023A7660>, '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-123', 'USERDOMAIN': 'T-W864-IX-123', '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-123', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-123', '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-123\\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 03:17:15 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\talos 03:17:15 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:17:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:17:15 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Roaming', 03:17:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 03:17:15 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 03:17:15 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 03:17:15 INFO - 'COMPUTERNAME': 'T-W864-IX-123', 03:17:15 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:17:15 INFO - 'DCLOCATION': 'SCL3', 03:17:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:17:15 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:17:15 INFO - 'HOMEDRIVE': 'C:', 03:17:15 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 03:17:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:17:15 INFO - 'KTS_VERSION': '1.19c', 03:17:15 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 03:17:15 INFO - 'LOGONSERVER': '\\\\T-W864-IX-123', 03:17:15 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:17:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:15 INFO - 'MOZ_NO_REMOTE': '1', 03:17:15 INFO - 'NO_EM_RESTART': '1', 03:17:15 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:17:15 INFO - 'OS': 'Windows_NT', 03:17:15 INFO - 'OURDRIVE': 'C:', 03:17:15 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', 03:17:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:17:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:17:15 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 03:17:15 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 03:17:15 INFO - 'PROCESSOR_LEVEL': '6', 03:17:15 INFO - 'PROCESSOR_REVISION': '1e05', 03:17:15 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:17:15 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 03:17:15 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 03:17:15 INFO - 'PROGRAMW6432': 'C:\\Program Files', 03:17:15 INFO - 'PROMPT': '$P$G', 03:17:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 03:17:15 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:17:15 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:17:15 INFO - 'PWD': 'C:\\slave\\test-pgo', 03:17:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:17:15 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:17:15 INFO - 'SYSTEMDRIVE': 'C:', 03:17:15 INFO - 'SYSTEMROOT': 'C:\\windows', 03:17:15 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:17:15 INFO - 'TEST1': 'testie', 03:17:15 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:17:15 INFO - 'USERDOMAIN': 'T-W864-IX-123', 03:17:15 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 03:17:15 INFO - 'USERNAME': 'cltbld', 03:17:15 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-123', 03:17:15 INFO - 'WINDIR': 'C:\\windows', 03:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 03:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 03:17:15 INFO - Cleaning up... 03:17:15 INFO - Return code: 0 03:17:15 INFO - Running post-action listener: _resource_record_post_action 03:17:15 INFO - Running post-action listener: _start_resource_monitoring 03:17:15 INFO - Starting resource monitoring. 03:17:16 INFO - ##### 03:17:16 INFO - ##### Running install step. 03:17:16 INFO - ##### 03:17:16 INFO - Running pre-action listener: _resource_record_pre_action 03:17:16 INFO - Running main action method: install 03:17:16 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 03:17:16 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 03:17:16 INFO - Reading from file tmpfile_stdout 03:17:16 INFO - Using _rmtree_windows ... 03:17:16 INFO - Using _rmtree_windows ... 03:17:16 INFO - Detecting whether we're running mozinstall >=1.0... 03:17:16 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 03:17:16 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 03:17:16 INFO - Reading from file tmpfile_stdout 03:17:16 INFO - Output received: 03:17:16 INFO - Usage: mozinstall-script.py [options] installer 03:17:16 INFO - Options: 03:17:16 INFO - -h, --help show this help message and exit 03:17:16 INFO - -d DEST, --destination=DEST 03:17:16 INFO - Directory to install application into. [default: 03:17:16 INFO - "C:\slave\test-pgo"] 03:17:16 INFO - --app=APP Application being installed. [default: firefox] 03:17:16 INFO - Using _rmtree_windows ... 03:17:16 INFO - Using _rmtree_windows ... 03:17:16 INFO - mkdir: C:\slave\test-pgo\build\application 03:17:16 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-44.0a1.en-US.win64.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 03:17:16 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-44.0a1.en-US.win64.zip --destination C:\slave\test-pgo\build\application 03:17:17 INFO - Reading from file tmpfile_stdout 03:17:17 INFO - Output received: 03:17:17 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 03:17:17 INFO - Using _rmtree_windows ... 03:17:17 INFO - Using _rmtree_windows ... 03:17:17 INFO - Running post-action listener: _resource_record_post_action 03:17:17 INFO - ##### 03:17:17 INFO - ##### Running run-tests step. 03:17:17 INFO - ##### 03:17:17 INFO - Running pre-action listener: _resource_record_pre_action 03:17:17 INFO - Running main action method: run_tests 03:17:17 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 03:17:17 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 03:17:17 INFO - Python 2.7.3 03:17:17 INFO - Return code: 0 03:17:17 INFO - grabbing minidump binary from tooltool 03:17: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')]} 03:17:17 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 0x00498B48>, '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 0x004BA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023A7660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 03:17:17 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 03:17:17 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 03:17:17 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 03:17:18 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpcnvgih 03:17:18 INFO - INFO - File integrity verified, renaming tmpcnvgih to win32-minidump_stackwalk.exe 03:17:18 INFO - Return code: 0 03:17:18 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 03:17:18 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 03:17:18 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 03:17:18 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 03:17:18 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 03:17:18 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-123', '--webServer', 'localhost'] in C:\slave\test-pgo\build 03:17:18 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName B2G-Inbound --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip --title T-W864-IX-123 --webServer localhost 03:17:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:17:18 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Roaming', 03:17:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 03:17:18 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 03:17:18 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 03:17:18 INFO - 'COMPUTERNAME': 'T-W864-IX-123', 03:17:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:17:18 INFO - 'DCLOCATION': 'SCL3', 03:17:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:17:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:17:18 INFO - 'HOMEDRIVE': 'C:', 03:17:18 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-123', 03:17:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:17:18 INFO - 'KTS_VERSION': '1.19c', 03:17:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-123\\AppData\\Local', 03:17:18 INFO - 'LOGONSERVER': '\\\\T-W864-IX-123', 03:17:18 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 03:17:18 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 03:17:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:17:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:18 INFO - 'MOZ_NO_REMOTE': '1', 03:17:18 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 03:17:18 INFO - 'NO_EM_RESTART': '1', 03:17:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:17:18 INFO - 'OS': 'Windows_NT', 03:17:18 INFO - 'OURDRIVE': 'C:', 03:17:18 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', 03:17:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:17:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:17:18 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 03:17:18 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 03:17:18 INFO - 'PROCESSOR_LEVEL': '6', 03:17:18 INFO - 'PROCESSOR_REVISION': '1e05', 03:17:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:17:18 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 03:17:18 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 03:17:18 INFO - 'PROGRAMW6432': 'C:\\Program Files', 03:17:18 INFO - 'PROMPT': '$P$G', 03:17:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 03:17:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:17:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:17:18 INFO - 'PWD': 'C:\\slave\\test-pgo', 03:17:18 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 03:17:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:17:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:17:18 INFO - 'SYSTEMDRIVE': 'C:', 03:17:18 INFO - 'SYSTEMROOT': 'C:\\windows', 03:17:18 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:17:18 INFO - 'TEST1': 'testie', 03:17:18 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 03:17:18 INFO - 'USERDOMAIN': 'T-W864-IX-123', 03:17:18 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-123', 03:17:18 INFO - 'USERNAME': 'cltbld', 03:17:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-123', 03:17:18 INFO - 'WINDIR': 'C:\\windows', 03:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:17:18 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-123', '--webServer', 'localhost'] with output_timeout 3600 ProcessManager NOT managing child processes ProcessManager UNABLE to use job objects to manage child processes 03:17:19 INFO - mozversion INFO | application_buildid: 20151026023015 03:17:19 INFO - mozversion INFO | application_changeset: 8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55 03:17:19 INFO - mozversion INFO | application_display_name: Nightly 03:17:19 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:17:19 INFO - mozversion INFO | application_name: Firefox 03:17:19 INFO - mozversion INFO | application_remotingname: firefox 03:17:19 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 03:17:19 INFO - mozversion INFO | application_vendor: Mozilla 03:17:19 INFO - mozversion INFO | application_version: 44.0a1 03:17:19 INFO - mozversion INFO | platform_buildid: 20151026023015 03:17:19 INFO - mozversion INFO | platform_changeset: 8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55 03:17:19 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 03:17:19 INFO - mozversion INFO | platform_version: 44.0a1 03:17:19 INFO - 2015-10-26 03:17:19,161 DEBUG : using testdate: 1445854639 03:17:19 INFO - 2015-10-26 03:17:19,161 DEBUG : actual date: 1445854639 03:17:19 INFO - 2015-10-26 03:17:19,256 INFO : starting webserver on 'localhost:49278' 03:17:19 INFO - 2015-10-26 03:17:19,257 INFO : Starting test suite T-W864-IX-123 03:17:19 INFO - 2015-10-26 03:17:19,257 INFO : Starting test dromaeo_css 03:17:19 INFO - 2015-10-26 03:17:19,257 DEBUG : operating with platform_type : w8_ 03:17:19 INFO - 2015-10-26 03:17:19,259 INFO : Initialising browser for dromaeo_css test... 03:17:19 INFO - 2015-10-26 03:17:19,355 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpjdqffe\profile http://localhost:49278/getInfo.html 03:17:27 INFO - 2015-10-26 03:17:27,788 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:17:27 INFO - 2015-10-26 03:17:27,788 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:17:27 INFO - 2015-10-26 03:17:27,788 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 03:17:27 INFO - 2015-10-26 03:17:27,788 DEBUG : BROWSER_OUTPUT: __metrics 03:17:27 INFO - 2015-10-26 03:17:27,930 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: console.error: 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no] 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: Stack: 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 03:17:29 INFO - 2015-10-26 03:17:29,144 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 03:36:04 INFO - 2015-10-26 03:36:04,651 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 03:36:04 INFO - 2015-10-26 03:36:04,651 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 03:36:04 INFO - 2015-10-26 03:36:04,651 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 03:36:04 INFO - 2015-10-26 03:36:04,651 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 03:36:04 INFO - 2015-10-26 03:36:04,651 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 03:36:04 INFO - 2015-10-26 03:36:04,651 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 03:36:04 INFO - 2015-10-26 03:36:04,651 DEBUG : BROWSER_OUTPUT: 03:36:05 INFO - 2015-10-26 03:36:05,359 INFO : Browser initialized. 03:36:05 INFO - 2015-10-26 03:36:05,359 INFO : Running cycle 1/1 for kraken test... 03:36:05 INFO - 2015-10-26 03:36:05,359 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpq0i7q3\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:36:16 INFO - 2015-10-26 03:36:16,720 DEBUG : BROWSER_OUTPUT: RSS: Main: 188542976 03:36:16 INFO - 2015-10-26 03:36:16,720 DEBUG : BROWSER_OUTPUT: 03:36:19 INFO - 2015-10-26 03:36:19,288 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 03:36:19 INFO - 2015-10-26 03:36:19,874 DEBUG : BROWSER_OUTPUT: RSS: Main: 225857536 03:36:19 INFO - 2015-10-26 03:36:19,874 DEBUG : BROWSER_OUTPUT: 03:36:22 INFO - 2015-10-26 03:36:22,354 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 03:36:22 INFO - 2015-10-26 03:36:22,921 DEBUG : BROWSER_OUTPUT: RSS: Main: 226074624 03:36:22 INFO - 2015-10-26 03:36:22,921 DEBUG : BROWSER_OUTPUT: 03:36:25 INFO - 2015-10-26 03:36:25,522 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 03:36:26 INFO - 2015-10-26 03:36:26,092 DEBUG : BROWSER_OUTPUT: RSS: Main: 213438464 03:36:26 INFO - 2015-10-26 03:36:26,092 DEBUG : BROWSER_OUTPUT: 03:36:28 INFO - 2015-10-26 03:36:28,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 03:36:28 INFO - 2015-10-26 03:36:28,572 DEBUG : BROWSER_OUTPUT: RSS: Main: 214487040 03:36:28 INFO - 2015-10-26 03:36:28,572 DEBUG : BROWSER_OUTPUT: 03:36:30 INFO - 2015-10-26 03:36:30,638 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:36:31 INFO - 2015-10-26 03:36:31,200 DEBUG : BROWSER_OUTPUT: RSS: Main: 211177472 03:36:31 INFO - 2015-10-26 03:36:31,200 DEBUG : BROWSER_OUTPUT: 03:36:34 INFO - 2015-10-26 03:36:34,177 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 03:36:34 INFO - 2015-10-26 03:36:34,750 DEBUG : BROWSER_OUTPUT: RSS: Main: 203317248 03:36:34 INFO - 2015-10-26 03:36:34,750 DEBUG : BROWSER_OUTPUT: 03:36:38 INFO - 2015-10-26 03:36:38,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 03:36:38 INFO - 2015-10-26 03:36:38,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 203288576 03:36:38 INFO - 2015-10-26 03:36:38,796 DEBUG : BROWSER_OUTPUT: 03:36:41 INFO - 2015-10-26 03:36:41,573 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 03:36:42 INFO - 2015-10-26 03:36:42,148 DEBUG : BROWSER_OUTPUT: RSS: Main: 202674176 03:36:42 INFO - 2015-10-26 03:36:42,148 DEBUG : BROWSER_OUTPUT: 03:36:43 INFO - 2015-10-26 03:36:43,872 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:36:44 INFO - 2015-10-26 03:36:44,434 DEBUG : BROWSER_OUTPUT: RSS: Main: 228130816 03:36:44 INFO - 2015-10-26 03:36:44,434 DEBUG : BROWSER_OUTPUT: 03:36:46 INFO - 2015-10-26 03:36:46,171 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:36:46 INFO - 2015-10-26 03:36:46,746 DEBUG : BROWSER_OUTPUT: RSS: Main: 232865792 03:36:46 INFO - 2015-10-26 03:36:46,746 DEBUG : BROWSER_OUTPUT: 03:36:48 INFO - 2015-10-26 03:36:48,869 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:36:49 INFO - 2015-10-26 03:36:49,446 DEBUG : BROWSER_OUTPUT: RSS: Main: 220155904 03:36:49 INFO - 2015-10-26 03:36:49,447 DEBUG : BROWSER_OUTPUT: 03:36:51 INFO - 2015-10-26 03:36:51,809 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:36:52 INFO - 2015-10-26 03:36:52,390 DEBUG : BROWSER_OUTPUT: RSS: Main: 223404032 03:36:52 INFO - 2015-10-26 03:36:52,390 DEBUG : BROWSER_OUTPUT: 03:36:54 INFO - 2015-10-26 03:36:54,990 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:36:55 INFO - 2015-10-26 03:36:55,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 223903744 03:36:55 INFO - 2015-10-26 03:36:55,562 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: RSS: Main: 278818816 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;104;110;109;109;111;110;103;109 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;129;120;137;153;123;125;127;125;145;122 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: |2;audio-dft;140;140;141;150;141;141;140;142;140;140 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: |3;audio-fft;69;70;70;94;76;67;70;71;102;66 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;86;86;106;86;86;86;113;87;86;112 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;112;109;111;109;111;110;111;110;110 03:36:57 INFO - 2015-10-26 03:36:57,487 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;131;132;131;132;131;131;132;133;132;132 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;96;99;96;100;97 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;81;73;73;74;74;74;72;83;74;71 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;63;63;63;63;63;63;63;63 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;86;87;88;88;87;87;86;88;88 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;115;120;115;111;120;122;114;118;119;114 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;153;147;153;153;154;144;155;155;154;145 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;68;68;70;67;69;68;68;67;67 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: __start_cc_report 03:36:57 INFO - 2015-10-26 03:36:57,489 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,929 03:36:57 INFO - 2015-10-26 03:36:57,490 DEBUG : BROWSER_OUTPUT: __end_cc_report 03:36:57 INFO - 2015-10-26 03:36:57,490 DEBUG : BROWSER_OUTPUT: __startTimestamp1445855817486__endTimestamp 03:36:57 INFO - 2015-10-26 03:36:57,490 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,490 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:36:57 INFO - 2015-10-26 03:36:57,490 DEBUG : BROWSER_OUTPUT: Number of tests: 14 03:36:57 INFO - 2015-10-26 03:36:57,490 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,490 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.40 Median:109.50 stddev:2.67 (2.4%) stddev-sans-first:2.83 03:36:57 INFO - 2015-10-26 03:36:57,490 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 104.0 110.0 109.0 109.0 111.0 110.0 103.0 109.0 03:36:57 INFO - 2015-10-26 03:36:57,492 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,492 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:130.60 Median:128.00 stddev:10.92 (8.5%) stddev-sans-first:11.56 03:36:57 INFO - 2015-10-26 03:36:57,492 DEBUG : BROWSER_OUTPUT: Values: 129.0 120.0 137.0 153.0 123.0 125.0 127.0 125.0 145.0 122.0 03:36:57 INFO - 2015-10-26 03:36:57,492 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,492 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:141.50 Median:141.00 stddev:3.06 (2.2%) stddev-sans-first:3.20 03:36:57 INFO - 2015-10-26 03:36:57,492 DEBUG : BROWSER_OUTPUT: Values: 140.0 140.0 141.0 150.0 141.0 141.0 140.0 142.0 140.0 140.0 03:36:57 INFO - 2015-10-26 03:36:57,492 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:75.50 Median:70.00 stddev:12.29 (17.6%) stddev-sans-first:12.81 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 70.0 94.0 76.0 67.0 70.0 71.0 102.0 66.0 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:93.40 Median:86.00 stddev:11.82 (13.7%) stddev-sans-first:12.23 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: Values: 86.0 86.0 106.0 86.0 86.0 86.0 113.0 87.0 86.0 112.0 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.30 Median:110.50 stddev:0.95 (0.9%) stddev-sans-first:1.00 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: Values: 110.0 112.0 109.0 111.0 109.0 111.0 110.0 111.0 110.0 110.0 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:131.70 Median:132.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: Values: 131.0 132.0 131.0 132.0 131.0 131.0 132.0 133.0 132.0 132.0 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.80 Median:96.00 stddev:1.48 (1.5%) stddev-sans-first:1.54 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 96.0 99.0 96.0 100.0 97.0 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,493 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.90 Median:74.00 stddev:3.90 (5.3%) stddev-sans-first:3.46 03:36:57 INFO - 2015-10-26 03:36:57,494 DEBUG : BROWSER_OUTPUT: Values: 81.0 73.0 73.0 74.0 74.0 74.0 72.0 83.0 74.0 71.0 03:36:57 INFO - 2015-10-26 03:36:57,494 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,494 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 03:36:57 INFO - 2015-10-26 03:36:57,494 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 03:36:57 INFO - 2015-10-26 03:36:57,494 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,496 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.50 stddev:0.88 (1.0%) stddev-sans-first:0.83 03:36:57 INFO - 2015-10-26 03:36:57,496 DEBUG : BROWSER_OUTPUT: Values: 86.0 86.0 87.0 88.0 88.0 87.0 87.0 86.0 88.0 88.0 03:36:57 INFO - 2015-10-26 03:36:57,496 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,496 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.80 Median:118.50 stddev:3.49 (2.9%) stddev-sans-first:3.64 03:36:57 INFO - 2015-10-26 03:36:57,496 DEBUG : BROWSER_OUTPUT: Values: 115.0 120.0 115.0 111.0 120.0 122.0 114.0 118.0 119.0 114.0 03:36:57 INFO - 2015-10-26 03:36:57,496 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,496 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:153.50 stddev:4.24 (2.8%) stddev-sans-first:4.46 03:36:57 INFO - 2015-10-26 03:36:57,497 DEBUG : BROWSER_OUTPUT: Values: 153.0 147.0 153.0 153.0 154.0 144.0 155.0 155.0 154.0 145.0 03:36:57 INFO - 2015-10-26 03:36:57,497 DEBUG : BROWSER_OUTPUT: 03:36:57 INFO - 2015-10-26 03:36:57,497 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 03:36:57 INFO - 2015-10-26 03:36:57,499 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 68.0 70.0 67.0 69.0 68.0 68.0 67.0 67.0 03:36:57 INFO - 2015-10-26 03:36:57,499 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:36:57 INFO - 2015-10-26 03:36:57,499 DEBUG : BROWSER_OUTPUT: 03:36:58 INFO - 2015-10-26 03:36:58,151 INFO : Browser exited with error code: 0 03:36:59 INFO - 2015-10-26 03:36:59,092 INFO : Completed test kraken (00:01:00) 03:36:59 INFO - 2015-10-26 03:36:59,092 INFO : Starting test v8_7 03:36:59 INFO - 2015-10-26 03:36:59,092 DEBUG : operating with platform_type : w8_ 03:36:59 INFO - 2015-10-26 03:36:59,092 INFO : Initialising browser for v8_7 test... 03:36:59 INFO - 2015-10-26 03:36:59,114 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmp3dbqmb\profile http://localhost:49278/getInfo.html 03:37:04 INFO - 2015-10-26 03:37:04,207 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:37:04 INFO - 2015-10-26 03:37:04,207 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:37:04 INFO - 2015-10-26 03:37:04,207 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 03:37:04 INFO - 2015-10-26 03:37:04,207 DEBUG : BROWSER_OUTPUT: __metrics 03:37:04 INFO - 2015-10-26 03:37:04,448 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:37:05 INFO - 2015-10-26 03:37:05,334 DEBUG : BROWSER_OUTPUT: console.error: 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no] 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: Stack: 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 03:37:05 INFO - 2015-10-26 03:37:05,335 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 03:37:05 INFO - 2015-10-26 03:37:05,336 DEBUG : BROWSER_OUTPUT: 03:37:06 INFO - 2015-10-26 03:37:06,036 INFO : Browser initialized. 03:37:06 INFO - 2015-10-26 03:37:06,036 INFO : Running cycle 1/1 for v8_7 test... 03:37:06 INFO - 2015-10-26 03:37:06,036 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmp3dbqmb\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\v8_7\v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:37:17 INFO - 2015-10-26 03:37:17,394 DEBUG : BROWSER_OUTPUT: RSS: Main: 190672896 03:37:17 INFO - 2015-10-26 03:37:17,394 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,167 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49278/tests/v8_7/run.html (next: http://localhost:49278/tests/v8_7/run.html) 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: RSS: Main: 683274240 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: |0;Richards;167.7570877369569 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;175.53098121818502 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: |2;Encrypt;282.08744710860367 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5122.448979591837 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1564.0625 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: |5;Earley;666.2225183211193 03:37:39 INFO - 2015-10-26 03:37:39,417 DEBUG : BROWSER_OUTPUT: |6;Boyer;12575 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: |7;RegExp;30058.823529411766 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: |8;Splay;791.1617961511048 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4887.804878048781 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: __startTimestamp1445855859414__endTimestamp 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: Number of tests: 10 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.76 Median:167.76 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: Values: 167.8 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:175.53 Median:175.53 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: Values: 175.5 03:37:39 INFO - 2015-10-26 03:37:39,418 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:282.09 Median:282.09 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: Values: 282.1 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5122.45 Median:5122.45 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: Values: 5122.4 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1564.06 Median:1564.06 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: Values: 1564.1 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:666.22 Median:666.22 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: Values: 666.2 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12575.00 Median:12575.00 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: Values: 12575.0 03:37:39 INFO - 2015-10-26 03:37:39,420 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:30058.82 Median:30058.82 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: Values: 30058.8 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:791.16 Median:791.16 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: Values: 791.2 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4887.80 Median:4887.80 stddev:0.00 (0.0%) 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: Values: 4887.8 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:37:39 INFO - 2015-10-26 03:37:39,421 DEBUG : BROWSER_OUTPUT: 03:37:40 INFO - 2015-10-26 03:37:40,098 INFO : Browser exited with error code: 0 03:37:40 INFO - 2015-10-26 03:37:40,836 INFO : Completed test v8_7 (00:00:41) 03:37:41 INFO - 2015-10-26 03:37:41,177 INFO : Completed test suite (00:20:21) 03:37:41 INFO - 2015-10-26 03:37:41,177 DEBUG : Working with test: dromaeo_css 03:37:41 INFO - 2015-10-26 03:37:41,177 DEBUG : Generating results file: dromaeo_css 03:37:41 INFO - 2015-10-26 03:37:41,178 DEBUG : Working with test: kraken 03:37:41 INFO - 2015-10-26 03:37:41,178 DEBUG : Generating results file: kraken 03:37:41 INFO - 2015-10-26 03:37:41,178 INFO : javascript benchmark 03:37:41 INFO - 2015-10-26 03:37:41,178 DEBUG : Working with test: v8_7 03:37:41 INFO - 2015-10-26 03:37:41,178 DEBUG : Generating results file: v8_7 03:37:41 INFO - 2015-10-26 03:37:41,178 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:37:41 INFO - 2015-10-26 03:37:41,315 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,201,31]] 03:37:41 INFO - 2015-10-26 03:37:41,315 DEBUG : process_Request line: dromaeo_css 6890.40 graph.html#tests=[[72,201,31]] 03:37:41 INFO - 2015-10-26 03:37:41,315 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:37:41 INFO - 2015-10-26 03:37:41,348 DEBUG : process_Request line: kraken 1449.30 graph.html#tests=[[232,201,31]] 03:37:41 INFO - 2015-10-26 03:37:41,348 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:37:41 INFO - 2015-10-26 03:37:41,454 DEBUG : process_Request line: v8_7 19883.97 graph.html#tests=[[230,201,31]] 03:37:41 INFO - 2015-10-26 03:37:41,456 INFO : javascript benchmark 03:37:41 INFO - 2015-10-26 03:37:41,457 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"jquery.html": [378.8030224283371, 386.54194779186673, 389.4061556126908, 389.89083056744124, 391.358172596941, 14352.011086021337, 15077.849221507782, 16366.918165409172, 18117.094145292747, 18190.181441835117, 17266.568335791613, 17449.563760905985, 17491.300347986064, 17596.472105836812, 17779.64440711185, 5376.059189641806, 5465.72671366432, 5479.424660410639, 5487.42382049885, 5498.5876059295515, 4155.480564929384, 4352.825886964533, 5408.026555220059, 6160.260768707762, 6242.0, 6063.757449702029, 6241.534593835432, 6250.281217659959, 6272.184615999914, 6287.497000239992, 6246.469050130744, 6274.592151510137, 6302.369763023661, 6311.053341998691, 6311.715972781213, 5416.889537644774, 5530.142827861688, 5544.63959842613, 5561.610687251894, 5621.128725047624, 22171.778282217132, 22567.77432225673, 22636.434089147737, 22995.42511437211, 23173.188938387077, 1954.7263383126374, 2000.0599718132455, 2062.9484262893397, 2254.9098036078703, 2419.7822196002267, 9794.118529332394, 9835.409875407428, 9900.405975641484, 10123.139533139618, 10213.79572408555, 1584.5642448326687, 1732.3850033238145, 1734.1762662735225, 1736.713442282022, 1776.5292197567655, 67132.6643366785, 69051.30948690549, 69350.65324673394, 69823.0, 69863.65068174677, 6795.7961261162245, 6848.075509806212, 6860.1081859358965, 6902.620355880475, 6950.443964482829, 8003.599820008976, 8103.635336409876, 8227.8354432911, 8238.217369349903, 8243.546604936786, 5627.493525582635, 5640.576956728261, 5647.491725744702, 5661.745221465044, 5690.374058853523, 6011.789587364421, 6056.394360563909, 6067.513459202523, 6109.969450152721, 6213.751449941951, 3202.1034110449114, 3220.7423406127455, 3231.6929891660257, 3233.757468189895, 3264.8857289995212, 1117.2570240789946, 1150.2121047082608, 1185.6502331812137, 1192.9105317101078, 1221.9327936963375, 460.59697764456104, 475.6028716022091, 479.27868557820955, 490.7668857292757, 502.8541722900318, 10101.292909496266, 11572.42137893102, 11698.415079246173, 11760.529578816751, 12008.819867701992, 508.8778693113626, 513.0508559165588, 516.3700285651499, 532.6191772882407, 534.1107056750544, 6353.841153971188, 6432.935670643327, 6445.291017988017, 6470.09418681385, 6579.83550411234, 524314.0, 553668.0, 559663.0, 586318.0, 612452.0, 45825.54174458213, 82181.0, 83023.48703242025, 84000.0, 86628.0, 567.5941701683316, 567.7984315567913, 572.8367415286623, 573.3435216676965, 574.3423779772138, 11016.50425730828, 11090.223684342342, 11156.274842135235, 11223.214374993671, 11228.326300421673, 35871.0, 35890.4616430759, 36002.27995440129, 36418.45372319364, 36558.26883462263, 48691.5130848687, 49689.503104968484, 49706.289650898114, 49821.50178498169, 50863.49136508587, 6704.899426508704, 6747.561408508432, 6765.864682706417, 6767.323267673391, 6783.321667833177, 12042.75914481723, 12341.668405209712, 12413.255204687748, 12478.937605311916, 12586.937065314614, 10806.945965270123, 10913.18151138652, 11157.44212789341, 11176.10591152722, 11207.495662695035, 10565.63020294286, 10734.14126869833, 10763.138948883914, 10776.784464310513, 10976.890231097907, 10767.784644306914, 10944.507497162806, 11185.217034807485, 11256.606018789304, 11280.435978201222, 6594.571352862049, 6636.900446493403, 6667.899981500378, 6676.132102826601, 6690.19717633887, 10753.354798712102, 10958.671324826808, 11100.5559777608, 11122.555097795997, 11212.383318917538], "dojo.html": [2871.0669032564415, 2880.135959212236, 2919.5766613840988, 2922.8684709188083, 2932.7947043706936, 5397.244385785988, 5458.344998600169, 5463.863403414916, 5479.04116779563, 5480.205370221315, 5408.648437851537, 5499.532539734118, 5505.174223866422, 5515.751791169397, 5533.889322213563, 4802.567768900798, 4890.5109489051165, 4896.094222568836, 4911.877203069934, 4971.05549945511, 4813.350197723297, 4844.249141383091, 4912.312276281324, 4917.065757506062, 4934.087193869128, 4834.492378300263, 4868.0, 4870.293807397925, 4875.098106850226, 4914.11545921734, 4835.637327200457, 4850.272459131159, 4851.466338702739, 4854.12625727369, 4899.853004409873, 4739.73931433771, 4797.472278049412, 4836.153673107192, 4842.975785121052, 4904.60763138948, 5079.5428411443145, 5331.093714068617, 5365.731713414352, 5372.91940620891, 5380.112281473552, 5688.0, 5713.0, 5716.3426205986425, 5721.485066344049, 5762.423757624246, 5618.0, 5630.690312032836, 5722.885542289131, 5747.109198074263, 5764.8847023059725, 5281.70950597717, 5359.249705041298, 5362.410134885159, 5367.946320536784, 5374.059539580597, 5911.0, 5911.940880591225, 5917.911231331534, 5928.851778705567, 5933.931892259348, 5493.121100623961, 5537.833864984057, 5624.85937851557, 5643.858903527444, 5648.915266271009, 5738.08190689496, 5752.395998420189, 5755.769769209185, 5770.13447982806, 5795.82612521625, 5690.687012214368, 5691.630044047124, 5694.487496125285, 5694.487496125369, 5731.971340143356, 5552.69460179694, 5611.466910643482, 5630.211770352154, 5653.858653533613, 5660.462256085665, 3632.2553876455263, 3667.339878821838, 3669.3945498992903, 3708.9072773180887, 3709.4435834625024, 3334.433146365075, 3338.5659864217496, 3344.247544302561, 3376.071580315433, 3377.8986630401128, 5416.864578385572, 5466.180072989084, 5561.77752889888, 5579.609427340048, 5590.832275031756, 5743.483086522149, 5784.1034639630925, 5811.970940145272, 5815.9709201453725, 5817.7963771267805, 5530.723463826793, 5534.335879694462, 5539.390667026624, 5541.5289700375115, 5548.5838562108, 4567.223571992769, 4588.541145885385, 4592.678512504092, 4656.301554766745, 4662.673612847136, 6018.668973206516, 6027.0, 6041.214642096499, 6043.758249669964, 6063.696815159225, 5728.112142617901, 5780.190773291826, 5802.361740208658, 5809.360970293349, 5815.982203114522, 5878.794242201502, 5903.2325797646035, 5934.881302374015, 5935.851603709942, 5939.851503712442, 5694.544436445157, 5723.8569035774435, 5742.59801813869, 5746.310442746897, 5754.0, 5765.94234057654, 5790.652560846363, 5805.970970145122, 5842.415758424124, 5843.503302219359, 5610.158476228598, 5624.803131890364, 5638.943610564006, 5723.31320241557, 5740.339860916049, 5584.972075139598, 5670.0, 5671.602987790815, 5684.459976302244, 5690.231818704422, 5577.581681373832, 5613.635113717595, 5618.831435056954, 5620.4098569649595, 5627.324721033502, 5484.2596249505805, 5605.131204663284, 5641.4922656959425, 5667.744951477276, 5676.772929082956, 5571.041713249392, 5593.888122237451, 5599.468050535193, 5605.327360716577, 5620.4660557247], "mootools.html": [1430.0633085329107, 1554.8756099512036, 1582.707199168154, 1699.2098674116532, 1735.6615459985308, 8494.150584941503, 8664.39349245553, 9247.398919070256, 9552.856707149389, 9609.126220387026, 9304.674336398228, 9611.66359177429, 9620.27847911406, 9632.903670963287, 9634.855477167848, 2338.228384633067, 2362.515684284726, 2364.2316247219637, 2367.562001029806, 2372.6559648850907, 2101.464126647703, 2134.8185404240658, 2332.9650055249185, 2364.858108513486, 2422.63660450932, 2335.32275640065, 2354.2466410748566, 2356.4108972756812, 2357.54027964547, 2360.6695062691238, 2117.6717608770664, 2344.871032093234, 2353.5645905507536, 2362.161432691387, 2365.44412063165, 2090.9790902090936, 2325.9767402326097, 2326.5230627721276, 2370.1467471710343, 2370.3363058343693, 9286.72139835806, 9352.485613291266, 9360.672376466859, 9364.531773411301, 9374.85937710935, 5348.037353276409, 5389.676619402849, 5406.216098665711, 5417.133258678593, 5422.620416570841, 3603.621619729917, 3639.5632524097277, 3672.2839046385966, 3681.6193927277272, 3707.9814600926825, 1099.3349023840542, 1118.6084870295413, 1130.9264897781618, 1132.8074227381364, 1167.130487786596, 14549.490767823076, 14771.778423323658, 14794.852051479455, 14795.40818367264, 14823.629409264855, 4624.421947256593, 4701.576858082789, 4718.787654555554, 4735.100330937111, 4768.3085952537385, 11821.285913542488, 11849.229800062969, 11874.940625296991, 11878.40607969598, 11916.106292028133, 5314.681119132864, 5365.141577347606, 5372.7313634318125, 5387.649802762808, 5395.62230643859, 4846.466888642247, 4852.951470485321, 4857.344258525124, 4897.191963326082, 4909.239067944402, 4000.2799496090984, 4052.169305292408, 4059.167870586523, 4085.4893138357706, 4089.3048181809163, 1237.4307818403636, 1238.6407941696987, 1245.1719606461784, 1248.581725122076, 1265.5507294910358, 1245.6636708088947, 1261.987380126187, 1277.9488820447264, 1279.4882047181202, 1308.4112149532748, 8372.916270837335, 8427.283680887193, 8431.536265505456, 8572.156986247863, 8865.468071915706, 470.97409642469995, 487.15964960442955, 495.3659316075429, 497.45031739927583, 500.0748615062106, 6228.439440450381, 6473.579217350952, 6507.414332710079, 6706.228783689938, 6732.158480189976, 11432.714182145346, 11496.0, 11626.76746465066, 11731.824022639667, 11791.469383877746, 11602.477888495037, 11641.825372619418, 11655.30068195911, 11685.88314116865, 11726.482055383522, 8382.83234335322, 8665.306775457948, 8672.479651220821, 8682.999999999874, 8685.305175585938, 8806.031336553102, 8818.5590720465, 8900.42147260426, 8945.73162805117, 8956.68651597191, 10473.633422830164, 10601.522931467947, 10613.0, 10662.8933710665, 10691.679249622524, 10856.0, 11041.282316649393, 11171.72070698239, 11215.158863085464, 11240.943795280971, 8274.917250827655, 8285.668573257002, 8295.875561866698, 8329.625166867385, 8577.699780507652, 9462.195713364166, 9538.713838584854, 9567.043295670377, 9567.521623918916, 9572.856407153758, 8383.790405239917, 8397.160283971554, 8450.408471406943, 8659.82680346377, 8691.087435819149, 8204.138565450541, 8278.0, 8293.626786794488, 8321.6671333146, 8485.236328730198, 8165.632546535301, 8201.056878459054, 8217.876731849146, 8231.135730748185, 8257.256846883809, 7397.852042959218, 7420.220876807857, 7424.331810137113, 7485.88771168422, 7525.88711169322, 6844.589324640538, 7062.752803651848, 7389.224131466119, 7431.665575049244, 7457.6271186441545], "ext.html": [563.9323281206256, 602.4487593851625, 607.9118527813467, 610.2067312493757, 626.6991843914918, 31057.0, 31479.68520314796, 31510.10939343641, 31606.36787264256, 39538.0, 42151.78924105375, 42426.0, 42533.36199957003, 42636.3604545932, 42877.57122428775, 8647.437916535437, 8652.567371631425, 8694.782630434227, 8781.42920710155, 8783.077776833436, 7901.288884000439, 8000.839983200332, 8745.912540874606, 10685.41230232337, 10839.295445796037, 10635.415052172128, 10766.0, 10769.515371808287, 10769.62306319279, 10809.567617295299, 7746.535207887545, 10479.580816767359, 10546.314489558154, 10556.894431055707, 10605.84091238632, 7695.499792513525, 10677.466126693635, 10746.838797418044, 10823.83764243537, 10835.566577336896, 39222.80388598067, 39806.60193398087, 39815.40276895849, 40106.59893401058, 40219.59780402217, 3367.44437167867, 3458.8097654628987, 3729.869454569104, 3952.486176797027, 3971.324874771296, 31094.378112437626, 31788.0, 31879.840600796848, 31942.361152777048, 31984.20039499008, 3207.23026473647, 3235.110344655215, 3298.5876765404323, 3305.5702758641464, 3338.7996720196866, 308618.0, 309091.0, 311682.0, 311849.99999999773, 313428.0, 47216.0, 57238.7138064307, 57910.0, 58060.70969645209, 58093.0, 46526.0, 58156.41843581595, 59259.0, 59290.40709592935, 60155.39844601498, 15336.539903802905, 15410.53599908007, 15473.613159671098, 15482.22588870552, 15499.845001550066, 11607.709807254718, 11644.592439264585, 11745.17783755146, 11751.353675547927, 11781.941090294666, 18554.474649816493, 18556.81443185578, 18630.293094485973, 18690.81309186918, 18716.53208669767, 7796.727114550963, 7942.046954365514, 7995.520268783892, 8005.519668819889, 8014.879776803353, 967.4678926590348, 967.8755132484271, 975.0054943957266, 986.40815510693, 1132.0999805154922, 28557.57163642547, 29195.56206656902, 29343.706562934523, 30016.0, 30194.698053019627, 1037.662759603132, 1039.4646756920192, 1072.7639919217759, 1142.8742240511747, 1206.6500714792787, 14979.325930333162, 15832.28754706041, 15862.365505379656, 15880.285387157606, 16158.192090395669, 434652.0, 435981.0, 440062.0, 441149.0, 441222.0, 52203.47796522062, 52481.7375913118, 52526.21210681843, 53088.73455632697, 53096.0, 1298.266479439114, 1301.7136230029378, 1310.233513394675, 1317.4203350525738, 1414.731201071793, 29839.403211936075, 30076.0, 30186.84906575453, 30291.545626815176, 30525.694743053173, 45699.77150114361, 45905.31142032806, 46138.0, 46361.76819115883, 46390.30414543717, 319441.0, 376938.0, 377089.0, 378158.0, 378445.0, 26898.731012690405, 31456.842715786275, 31547.211319716273, 31892.202694932814, 31973.20066998344, 40112.1977560453, 40634.0, 41320.586794131676, 43044.13911721811, 43529.78235108931, 36694.753465871, 37139.81430092832, 37215.255694886495, 37314.8134259327, 37533.81233093817, 28286.0, 36208.108509479105, 38376.0, 38933.0, 39497.407538886364, 36306.474266378056, 36910.0, 36923.63076369202, 36975.63024369722, 36985.63014369822, 31580.0, 33405.66594334123, 33832.323353533284, 33904.491432628034, 33938.49092263568, 25353.366165846, 25538.361540961625, 25556.0, 25670.358241044123, 25905.74094259033], "prototype.html": [357.5637721979185, 362.6319285924787, 364.6754388594152, 370.9054191181248, 373.8168297534209, 5881.647101173927, 5919.73361198746, 5921.20063791388, 5939.910901336478, 5943.524518038557, 5741.569382296334, 5796.07262837946, 5830.0380437227805, 5831.504322132613, 5890.911636325459, 4498.190325741365, 4498.482674492426, 4543.727376357429, 4553.430821147356, 4562.771861406933, 4504.684672072956, 4568.406107206059, 4571.7942692578745, 4573.885652858688, 4578.336141259515, 4393.670474714393, 4442.97778511107, 4484.730916145042, 4494.325851122341, 4534.047849951514, 4469.687121901468, 4505.121501307246, 4526.230540808054, 4526.6831321807485, 4536.342230376594, 3988.3618621020496, 4044.090079732066, 4044.1911617676583, 4048.7773172475504, 4052.918941621151, 5686.800961966353, 5687.971560142214, 5690.118031705092, 5695.1741997410145, 5698.544116470673, 5106.106431374495, 5167.199084141964, 5174.120399532088, 5183.170692689189, 5188.688678679291, 6072.210612620331, 6080.543959203077, 6086.969565152191, 6090.33006369299, 6090.48230900374, 4287.356896465555, 4309.5690430956965, 4313.784310784448, 4314.050908800059, 4314.460692413449, 6247.0, 6315.810525684236, 6372.553921225516, 6396.200474940632, 6410.61536307823, 4395.956040439619, 4409.867703968886, 4434.224010798098, 4435.290353543418, 4435.401220835146, 4450.755208463565, 4513.210188217049, 4513.932291015637, 4515.751633660115, 4524.7511386873375, 5072.365954255718, 5087.592992560586, 5121.001404726043, 5124.436312005751, 5128.205128205134, 5974.701264936823, 6105.603135796159, 6111.236095488064, 6122.816315510542, 6196.969015154896, 1627.4385337058695, 1644.4162322375623, 1647.6457561624309, 1650.9009459432473, 1651.7026935151548, 1107.0534692837507, 1114.409363037575, 1117.3519358771894, 1123.7977164110375, 1125.3641692443744, 5989.041753319448, 6022.156898034279, 6051.485623721945, 6060.696965151725, 6070.119832624245, 6355.650439225887, 6390.137331460286, 6399.55203135776, 6408.871822563616, 6410.455111315498, 5912.586118971631, 5987.5808693391045, 5988.760449582055, 6062.9090563641585, 6066.059760737093, 3493.94759078614, 3511.877084302037, 3521.7710848794745, 3529.3647143514413, 3530.064532898784, 6662.001399580204, 6663.333666633298, 6664.600123992576, 6669.399754022064, 6683.699233534406, 6309.716062777186, 6433.292337842833, 6441.420272175433, 6464.353564643498, 6467.256265529524, 6643.368879956396, 6731.562448440835, 6731.596104233664, 6737.595744255164, 6738.393544581011, 6412.102305677302, 6426.485881129405, 6430.228372595318, 6434.967825160844, 6438.3883531064475, 6211.09939058843, 6282.685865706605, 6286.654234017173, 6301.6219026858535, 6315.621062736251, 6667.399934005963, 6673.265940746611, 6684.465242780663, 6688.598684078971, 6691.5650482718465, 4478.596926276651, 4479.731216127043, 4508.346289787897, 4509.143262780192, 4520.728756274634, 6293.150424692791, 6332.620042797447, 6364.809055728336, 6382.170317858649, 6391.840203994751, 6224.533160013108, 6227.252729672468, 6253.78111766086, 6261.217347831521, 6267.843303917439, 6187.0, 6194.690265486617, 6195.411435913581, 6196.1015652731, 6196.597221180609, 6011.699415029144, 6120.816375508742, 6121.32665406796, 6127.417895299939, 6128.111423843607, 6228.719707613078, 6229.719662615284, 6230.096635987847, 6231.065340199007, 6236.438720515175, 6179.443850053517, 6190.81427557174, 6220.066989951543, 6229.875402491834, 6232.594881332699], "yui.html": [753.4462170304824, 759.255929189394, 761.8171638806684, 762.5958242131669, 811.3995643224013, 14812.407503699838, 14965.700685986274, 14995.400183992655, 14995.850041499569, 15052.548423547298, 12590.181638193511, 12710.300933448656, 12773.169743966635, 12787.552435664753, 12793.232406055644, 600.4865839707053, 606.457220787395, 743.7731491894983, 750.680960591749, 755.101429299135, 737.634870738985, 746.4700062955309, 749.052448652455, 749.2170681637674, 749.7713197474768, 766.390719378093, 772.1043589436255, 775.1899265267781, 776.7553220735479, 776.8019155115437, 624.8437890527368, 631.7630888416844, 767.8349154931716, 769.926856948589, 774.8915151878741, 562.5696342298145, 562.8874225154985, 564.4017341617898, 566.0518631292609, 569.3793764796351, 13077.869221307761, 13144.145630533985, 13163.73672526544, 13170.736585268336, 13183.0, 9921.85117223235, 9936.70189894297, 9985.151262142726, 10075.748106297327, 10254.333468324536, 9787.510624468749, 9830.803383932353, 10132.692384092841, 10236.692899213036, 10356.119729822974, 3217.3082787200865, 3241.7406607471344, 3250.0, 3261.6086069671555, 3307.933841323184, 41437.0, 42133.15733685309, 45397.0, 45784.0, 45870.31194532085, 7191.568505889663, 7237.674304656303, 7272.0, 7340.302671246223, 7401.888971665429, 10666.73333166677, 10681.359118452763, 10733.0, 10735.83896241557, 10747.978942000498, 6711.0, 6724.798256052326, 6823.69293381799, 6836.658167091725, 6850.212225594021, 4296.892577685583, 4362.065361298897, 4373.1488659629895, 4387.583179597933, 4394.846180383671, 2774.667039955178, 2792.6090347350982, 2832.730890565393, 2833.688294287627, 2840.7585355244623, 466.9859904202879, 490.4947903659237, 491.67058071091736, 549.5795716277051, 564.3453593831136, 741.695904679079, 771.8340556780207, 782.6165179062332, 792.809725665836, 793.6150966781106, 9128.863067053999, 9206.76983075415, 9342.906570934203, 9369.812603747887, 9438.197753191056, 537.1995726367684, 703.2967032967033, 752.9134149572869, 802.9116797152309, 816.2286639126088, 4059.414338673743, 4108.157827645386, 4891.3396691446915, 4949.82675606352, 4959.181735013754, 53662.463375366526, 54532.72733636307, 54668.72665636726, 54702.45297547053, 54761.1785823213, 48757.0, 48810.755946220044, 48822.511774881794, 49638.75180624146, 49701.751491243034, 41816.0, 41833.63389094233, 42022.15955680869, 42362.15275694469, 42616.14767704691, 9442.622295108118, 9446.433214007182, 9450.291228157777, 9452.243820494206, 9592.136707696342, 28994.855025724737, 29891.55162672605, 30186.396272074875, 30409.543856842607, 30716.53925191079, 41594.0, 41650.75047748572, 41829.37255941102, 41945.0, 42175.57824421839, 9042.68350607752, 9045.27637788962, 9095.772605684911, 9145.176934075966, 9250.0, 12879.034072444792, 12981.221126732426, 13032.0, 13138.0, 13180.736385272432, 12266.0, 12327.506899724269, 12455.564055258385, 12468.5012599495, 12471.376431178587, 12100.45547950362, 12111.091668124749, 12129.090318225992, 12265.816012759637, 12468.0, 11896.464659090534, 11964.102692297938, 12022.75954480888, 12033.699157521132, 12156.209846359958, 10097.596096156072, 10446.0, 10550.577976881146, 10802.89197108019, 10842.186835987175, 10525.631602893862, 10658.0, 10760.461976900968, 10817.1346292298, 10817.459127043774]}, "summary": {"suite": 6890.397111199467, "subtests": {"jquery.html": {"filtered": 7421.786789913237, "value": 7421.786789913237}, "dojo.html": {"filtered": 5234.4974163577945, "value": 5234.4974163577945}, "mootools.html": {"filtered": 5061.991461843488, "value": 5061.991461843488}, "ext.html": {"filtered": 19099.36324555769, "value": 19099.36324555769}, "prototype.html": {"filtered": 4729.098584979022, "value": 4729.098584979022}, "yui.html": {"filtered": 6024.591826066624, "value": 6024.591826066624}}}, "test_machine": {"platform": "x86_64", "osversion": "6.2.9200", "os": "win", "name": "T-W864-IX-123"}, "testrun": {"date": 1445854639, "suite": "dromaeo_css", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151026023015", "branch": "B2G-Inbound", "revision": "8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55"}}, {"talos_counters": {}, "results": {"audio-dft": [140.0, 140.0, 141.0, 150.0, 141.0, 141.0, 140.0, 142.0, 140.0, 140.0], "ai-astar": [109.0, 110.0, 104.0, 110.0, 109.0, 109.0, 111.0, 110.0, 103.0, 109.0], "imaging-gaussian-blur": [110.0, 112.0, 109.0, 111.0, 109.0, 111.0, 110.0, 111.0, 110.0, 110.0], "audio-beat-detection": [129.0, 120.0, 137.0, 153.0, 123.0, 125.0, 127.0, 125.0, 145.0, 122.0], "json-stringify-tinderbox": [63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0], "stanford-crypto-pbkdf2": [153.0, 147.0, 153.0, 153.0, 154.0, 144.0, 155.0, 155.0, 154.0, 145.0], "json-parse-financial": [81.0, 73.0, 73.0, 74.0, 74.0, 74.0, 72.0, 83.0, 74.0, 71.0], "stanford-crypto-sha256-iterative": [67.0, 68.0, 68.0, 70.0, 67.0, 69.0, 68.0, 68.0, 67.0, 67.0], "audio-fft": [69.0, 70.0, 70.0, 94.0, 76.0, 67.0, 70.0, 71.0, 102.0, 66.0], "imaging-darkroom": [131.0, 132.0, 131.0, 132.0, 131.0, 131.0, 132.0, 133.0, 132.0, 132.0], "stanford-crypto-ccm": [115.0, 120.0, 115.0, 111.0, 120.0, 122.0, 114.0, 118.0, 119.0, 114.0], "audio-oscillator": [86.0, 86.0, 106.0, 86.0, 86.0, 86.0, 113.0, 87.0, 86.0, 112.0], "stanford-crypto-aes": [86.0, 86.0, 87.0, 88.0, 88.0, 87.0, 87.0, 86.0, 88.0, 88.0], "imaging-desaturate": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 99.0, 96.0, 100.0, 97.0]}, "summary": {"suite": 1449.2999999999997, "subtests": {"audio-fft": {"filtered": 75.5, "value": 75.5}, "stanford-crypto-pbkdf2": {"filtered": 151.3, "value": 151.3}, "audio-beat-detection": {"filtered": 130.6, "value": 130.6}, "stanford-crypto-ccm": {"filtered": 116.8, "value": 116.8}, "imaging-darkroom": {"filtered": 131.7, "value": 131.7}, "json-parse-financial": {"filtered": 74.9, "value": 74.9}, "audio-oscillator": {"filtered": 93.4, "value": 93.4}, "ai-astar": {"filtered": 108.4, "value": 108.4}, "audio-dft": {"filtered": 141.5, "value": 141.5}, "stanford-crypto-sha256-iterative": {"filtered": 67.9, "value": 67.9}, "json-stringify-tinderbox": {"filtered": 63.1, "value": 63.1}, "imaging-gaussian-blur": {"filtered": 110.3, "value": 110.3}, "stanford-crypto-aes": {"filtered": 87.1, "value": 87.1}, "imaging-desaturate": {"filtered": 96.8, "value": 96.8}}}, "test_machine": {"platform": "x86_64", "osversion": "6.2.9200", "os": "win", "name": "T-W864-IX-123"}, "testrun": {"date": 1445854639, "suite": "kraken", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151026023015", "branch": "B2G-Inbound", "revision": "8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55"}}, {"talos_counters": {}, "results": {"DeltaBlue": [175.53098121818502], "Encrypt": [282.08744710860367], "NavierStokes": [4887.804878048781], "Decrypt": [5122.448979591837], "Splay": [791.1617961511048], "Richards": [167.7570877369569], "RegExp": [30058.823529411766], "Earley": [666.2225183211193], "Boyer": [12575.0], "RayTrace": [1564.0625]}, "summary": {"suite": 19883.96642792118, "subtests": {"DeltaBlue": {"filtered": 376.6742459999999, "value": 376.6742459999999}, "Encrypt": {"filtered": 943.6116450000002, "value": 943.6116450000002}, "NavierStokes": {"filtered": 303.61277445109783, "value": 303.61277445109783}, "Decrypt": {"filtered": 51.96362151394425, "value": 51.96362151394425}, "Splay": {"filtered": 103.00168738738736, "value": 103.00168738738736}, "RegExp": {"filtered": 30.306741682974582, "value": 30.306741682974582}, "Richards": {"filtered": 210.43522200000004, "value": 210.43522200000004}, "Earley": {"filtered": 1000.3609630000004, "value": 1000.3609630000004}, "Boyer": {"filtered": 52.99904572564615, "value": 52.99904572564615}, "RayTrace": {"filtered": 473.11984015984024, "value": 473.11984015984024}}}, "test_machine": {"platform": "x86_64", "osversion": "6.2.9200", "os": "win", "name": "T-W864-IX-123"}, "testrun": {"date": 1445854639, "suite": "v8_7", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "44.0a1", "id": "20151026023015", "branch": "B2G-Inbound", "revision": "8631cff3e8183a5cc08e7c5b3478c8c21b7d4a55"}}] 03:37:41 INFO - RETURN: dromaeo_css: 6890.40 03:37:41 INFO - RETURN: kraken: 1449.30 03:37:41 INFO - RETURN: v8_7: 19883.97 03:37:41 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,31]]", "result": "19883.97"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,31]]", "result": "1449.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,31]]", "result": "6890.40"}}} 03:37:41 INFO - Return code: 0 03:37:41 INFO - # TBPL SUCCESS # 03:37:41 INFO - Running post-action listener: _resource_record_post_action 03:37:41 INFO - Running post-run listener: _resource_record_post_run 03:37:42 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 117805568; Write bytes: 383319552; Read time: 1299840; Write time: 4245630 03:37:42 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 102400; Read time: 0; Write time: 150 03:37:42 INFO - run-tests - Wall time: 1224s; CPU: 13.0%; Read bytes: 117805568; Write bytes: 345775616; Read time: 1299840; Write time: 4166160 03:37:42 INFO - Running post-run listener: _upload_blobber_files 03:37:42 INFO - Blob upload gear active. 03:37:42 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 03:37:42 INFO - Copying logs to upload dir... 03:37:42 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1387.838000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 10 secs) (at 2015-10-26 03:37:44.984383) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-26 03:37:44.987284) ========= '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-123\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-123 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-123 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-123\AppData\Local LOGONSERVER=\\T-W864-IX-123 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-123 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-123 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-123 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False build_url:https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/a5JHnii2T5--agh9aAnbWg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-26 03:37:45.151933) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 03:37:45.152294) ========= '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-123\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-123 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-123 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-123\AppData\Local LOGONSERVER=\\T-W864-IX-123 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-123 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-123 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-123 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.100000 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 03:37:45.270213) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-26 03:37:45.270606) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-26 03:37:45.716718) =========