builder: fx-team_win8_64_test-dromaeojs-pgo slave: t-w864-ix-030 starttime: 1445664228.53 results: success (0) buildid: 20151023173019 builduid: 3aef88cdf0dc441e8360aa882f3adcce revision: 335ea7a95492650e23c9ba14648d4f43620babab ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 22:23:48.528134) ========= master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 22:23:48.530145) ========= ========= Started set props: basedir (results: 0, elapsed: 4 secs) (at 2015-10-23 22:23:48.530547) ========= '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-030\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-030 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-030 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-030\AppData\Local LOGONSERVER=\\T-W864-IX-030 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-030 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-030 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-030 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False C:\slave\test-pgo program finished with exit code 0 elapsedTime=1.282000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 4 secs) (at 2015-10-23 22:23:52.856749) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 26 secs) (at 2015-10-23 22:23:52.858889) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 26 secs) (at 2015-10-23 22:24:18.903593) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2015-10-23 22:24:18.903844) ========= '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-030\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-030 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-030 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-030\AppData\Local LOGONSERVER=\\T-W864-IX-030 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-030 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-030 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-030 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.301000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2015-10-23 22:24:32.610776) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 22:24:32.611112) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 22:24:32.611525) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 26 secs) (at 2015-10-23 22:24:32.611802) ========= '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-030\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-030 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-030 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-030\AppData\Local LOGONSERVER=\\T-W864-IX-030 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-030 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-030 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-030 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False --22:24:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 7.81 MB/s 22:24:34 (7.81 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.664000 ========= Finished 'bash -c ...' (results: 0, elapsed: 26 secs) (at 2015-10-23 22:24:58.723252) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2015-10-23 22:24:58.723549) ========= '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-030\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-030 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-030 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-030\AppData\Local LOGONSERVER=\\T-W864-IX-030 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-030 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-030 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-030 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=2.306000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2015-10-23 22:25:10.316085) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 21 secs) (at 2015-10-23 22:25:10.320467) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 335ea7a95492650e23c9ba14648d4f43620babab --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/fx-team --rev 335ea7a95492650e23c9ba14648d4f43620babab --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-030\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-030 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-030 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-030\AppData\Local LOGONSERVER=\\T-W864-IX-030 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-030 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-030 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-030 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-23 22:25:11,536 truncating revision to first 12 chars 2015-10-23 22:25:11,536 Setting DEBUG logging. 2015-10-23 22:25:11,536 attempt 1/10 2015-10-23 22:25:11,536 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/335ea7a95492?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-23 22:25:12,130 unpacking tar archive at: fx-team-335ea7a95492/testing/mozharness/ program finished with exit code 0 elapsedTime=1.194000 ========= Finished 'bash -c ...' (results: 0, elapsed: 21 secs) (at 2015-10-23 22:25:32.290897) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 9 secs) (at 2015-10-23 22:25:32.291178) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 9 secs) (at 2015-10-23 22:25:41.754284) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 22:25:41.754587) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 22:25:41.758367) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 2 secs) (at 2015-10-23 22:25:41.758680) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--system-bits' '64' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team' 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', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-030\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-030 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-030 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-030\AppData\Local LOGONSERVER=\\T-W864-IX-030 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-030 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-030 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-030 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 22:25:42 INFO - MultiFileLogger online at 20151023 22:25:42 in C:\slave\test-pgo 22:25:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:25:42 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 22:25:42 INFO - {'append_to_log': False, 22:25:42 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 22:25:42 INFO - 'blob_upload_branch': 'Fx-Team', 22:25:42 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 22:25:42 INFO - 'branch': 'Fx-Team', 22:25:42 INFO - 'buildbot_json_path': 'buildprops.json', 22:25:42 INFO - 'config_files': ('talos/windows_config.py',), 22:25:42 INFO - 'default_actions': ('clobber', 22:25:42 INFO - 'read-buildbot-config', 22:25:42 INFO - 'download-and-extract', 22:25:42 INFO - 'populate-webroot', 22:25:42 INFO - 'create-virtualenv', 22:25:42 INFO - 'install', 22:25:42 INFO - 'run-tests'), 22:25:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:25:42 INFO - 'download_minidump_stackwalk': True, 22:25:42 INFO - 'download_symbols': 'ondemand', 22:25:42 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 22:25:42 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 22:25:42 INFO - 'hg': 'c:/mozilla-build/hg/hg', 22:25:42 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 22:25:42 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 22:25:42 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 22:25:42 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 22:25:42 INFO - 'C:/mozilla-build/tooltool.py'), 22:25:42 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 22:25:42 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 22:25:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:25:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:25:42 INFO - 'installer_path': 'installer.exe', 22:25:42 INFO - 'log_level': 'info', 22:25:42 INFO - 'log_name': 'talos', 22:25:42 INFO - 'log_to_console': True, 22:25:42 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 22:25:42 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 22:25:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 22:25:42 INFO - 'opt_config_files': (), 22:25:42 INFO - 'pip_index': False, 22:25:42 INFO - 'sps_profile': False, 22:25:42 INFO - 'sps_profile_interval': 0, 22:25:42 INFO - 'suite': 'dromaeojs', 22:25:42 INFO - 'system_bits': '64', 22:25:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:25:42 INFO - 'title': 'T-W864-IX-030', 22:25:42 INFO - 'use_talos_json': True, 22:25:42 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 22:25:42 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 22:25:42 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 22:25:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:25:42 INFO - 'work_dir': 'build'} 22:25:42 INFO - ##### 22:25:42 INFO - ##### Running clobber step. 22:25:42 INFO - ##### 22:25:42 INFO - Running pre-action listener: _resource_record_pre_action 22:25:42 INFO - Running main action method: clobber 22:25:42 INFO - rmtree: C:\slave\test-pgo\build 22:25:42 INFO - Using _rmtree_windows ... 22:25:42 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 22:26:49 INFO - Running post-action listener: _resource_record_post_action 22:26:49 INFO - ##### 22:26:49 INFO - ##### Running read-buildbot-config step. 22:26:49 INFO - ##### 22:26:49 INFO - Running pre-action listener: _resource_record_pre_action 22:26:49 INFO - Running main action method: read_buildbot_config 22:26:49 INFO - Using buildbot properties: 22:26:49 INFO - { 22:26:49 INFO - "properties": { 22:26:49 INFO - "buildnumber": 252, 22:26:49 INFO - "product": "firefox", 22:26:49 INFO - "script_repo_revision": "production", 22:26:49 INFO - "builddir": "fx-team_win8_64_test-dromaeojs", 22:26:49 INFO - "repository": "", 22:26:49 INFO - "buildername": "Windows 8 64-bit fx-team pgo talos dromaeojs", 22:26:49 INFO - "buildid": "20151023173019", 22:26:49 INFO - "slavename": "t-w864-ix-030", 22:26:49 INFO - "pgo_build": "True", 22:26:49 INFO - "basedir": "C:\\slave\\test-pgo", 22:26:49 INFO - "project": "", 22:26:49 INFO - "platform": "win8_64", 22:26:49 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/", 22:26:49 INFO - "slavebuilddir": "test", 22:26:49 INFO - "scheduler": "tests-fx-team-win64-pgo-talos", 22:26:49 INFO - "branch": "fx-team", 22:26:49 INFO - "repo_path": "integration/fx-team", 22:26:49 INFO - "stage_platform": "win64-pgo", 22:26:49 INFO - "builduid": "3aef88cdf0dc441e8360aa882f3adcce", 22:26:49 INFO - "revision": "335ea7a95492650e23c9ba14648d4f43620babab" 22:26:49 INFO - }, 22:26:49 INFO - "sourcestamp": { 22:26:49 INFO - "repository": "", 22:26:49 INFO - "hasPatch": false, 22:26:49 INFO - "project": "", 22:26:49 INFO - "branch": "fx-team-win64-pgo-talos", 22:26:49 INFO - "changes": [ 22:26:49 INFO - { 22:26:49 INFO - "category": null, 22:26:49 INFO - "files": [ 22:26:49 INFO - { 22:26:49 INFO - "url": null, 22:26:49 INFO - "name": "https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.zip" 22:26:49 INFO - } 22:26:49 INFO - ], 22:26:49 INFO - "repository": "", 22:26:49 INFO - "rev": "335ea7a95492650e23c9ba14648d4f43620babab", 22:26:49 INFO - "who": "sendchange", 22:26:49 INFO - "when": 1445664125, 22:26:49 INFO - "number": 6569569, 22:26:49 INFO - "comments": "", 22:26:49 INFO - "project": "", 22:26:49 INFO - "at": "Fri 23 Oct 2015 22:22:05", 22:26:49 INFO - "branch": "fx-team-win64-pgo-talos", 22:26:49 INFO - "revlink": "", 22:26:49 INFO - "properties": [ 22:26:49 INFO - [ 22:26:49 INFO - "buildid", 22:26:49 INFO - "20151023173019", 22:26:49 INFO - "Change" 22:26:49 INFO - ], 22:26:49 INFO - [ 22:26:49 INFO - "builduid", 22:26:49 INFO - "3aef88cdf0dc441e8360aa882f3adcce", 22:26:49 INFO - "Change" 22:26:49 INFO - ], 22:26:49 INFO - [ 22:26:49 INFO - "pgo_build", 22:26:49 INFO - "True", 22:26:49 INFO - "Change" 22:26:49 INFO - ] 22:26:49 INFO - ], 22:26:49 INFO - "revision": "335ea7a95492650e23c9ba14648d4f43620babab" 22:26:49 INFO - } 22:26:49 INFO - ], 22:26:49 INFO - "revision": "335ea7a95492650e23c9ba14648d4f43620babab" 22:26:49 INFO - } 22:26:49 INFO - } 22:26:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.zip. 22:26:49 INFO - Running post-action listener: _resource_record_post_action 22:26:49 INFO - ##### 22:26:49 INFO - ##### Running download-and-extract step. 22:26:49 INFO - ##### 22:26:49 INFO - Running pre-action listener: _resource_record_pre_action 22:26:49 INFO - Running main action method: download_and_extract 22:26:49 INFO - mkdir: C:\slave\test-pgo\build\tests 22:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:49 INFO - https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:26:49 INFO - trying https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/test_packages.json 22:26:49 INFO - Downloading https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 22:26:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 22:26:50 INFO - Downloaded 1221 bytes. 22:26:50 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 22:26:50 INFO - Using the following test package requirements: 22:26:50 INFO - {u'common': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 22:26:50 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:26:50 INFO - u'firefox-44.0a1.en-US.win64.cppunittest.tests.zip'], 22:26:50 INFO - u'jittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:26:50 INFO - u'jsshell-win64.zip'], 22:26:50 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:26:50 INFO - u'firefox-44.0a1.en-US.win64.mochitest.tests.zip'], 22:26:50 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 22:26:50 INFO - u'reftest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:26:50 INFO - u'firefox-44.0a1.en-US.win64.reftest.tests.zip'], 22:26:50 INFO - u'talos': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:26:50 INFO - u'firefox-44.0a1.en-US.win64.talos.tests.zip'], 22:26:50 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:26:50 INFO - u'firefox-44.0a1.en-US.win64.web-platform.tests.zip'], 22:26:50 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 22:26:50 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:26:50 INFO - u'firefox-44.0a1.en-US.win64.xpcshell.tests.zip']} 22:26:50 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win64.common.tests.zip'] for test suite category: common 22:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:50 INFO - https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 22:26:50 INFO - trying https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip 22:26:50 INFO - Downloading https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/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 22:26:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/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 22:26:51 INFO - Downloaded 18100954 bytes. 22:26:51 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 22:26:51 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.common.tests.zip 22:26:51 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 22:27:31 INFO - Return code: 0 22:27:31 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 22:27: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')]} 22:27:31 INFO - https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 22:27:31 INFO - trying https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip 22:27:31 INFO - Downloading https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/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 22:27:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/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 22:27:32 INFO - Downloaded 18100954 bytes. 22:27:32 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 22:27:32 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.common.tests.zip 22:27:32 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 22:27:59 INFO - Return code: 0 22:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:59 INFO - https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip matches https://queue.taskcluster.net 22:27:59 INFO - trying https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip 22:27:59 INFO - Downloading https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/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 22:27:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/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 22:28:01 INFO - Downloaded 11131307 bytes. 22:28:01 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 22:28:01 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.talos.tests.zip 22:28:01 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 22:28:03 INFO - Return code: 0 22:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:03 INFO - https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.zip matches https://queue.taskcluster.net 22:28:03 INFO - trying https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 22:28:03 INFO - Downloading https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.zip 22:28:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/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 22:28:05 INFO - Downloaded 55553221 bytes. 22:28:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 22:28:05 INFO - mkdir: C:\slave\test-pgo\properties 22:28:05 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 22:28:05 INFO - Writing to file C:\slave\test-pgo\properties\build_url 22:28:05 INFO - Contents: 22:28:05 INFO - build_url:https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 22:28:05 INFO - Running post-action listener: _resource_record_post_action 22:28:05 INFO - Running post-action listener: set_extra_try_arguments 22:28:05 INFO - ##### 22:28:05 INFO - ##### Running populate-webroot step. 22:28:05 INFO - ##### 22:28:05 INFO - Running pre-action listener: _resource_record_pre_action 22:28:05 INFO - Running main action method: populate_webroot 22:28:05 INFO - Guessing talos json url... 22:28:05 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')]} 22:28:05 INFO - https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.txt matches https://queue.taskcluster.net 22:28:05 INFO - trying https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.txt 22:28:05 INFO - Downloading https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.txt 22:28:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/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 22:28:05 INFO - Downloaded 103 bytes. 22:28:05 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.txt 22:28:05 INFO - Contents: 22:28:05 INFO - 20151023173019 22:28:05 INFO - https://hg.mozilla.org/integration/fx-team/rev/335ea7a95492650e23c9ba14648d4f43620babab 22:28:05 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')]} 22:28:05 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/335ea7a954/testing/talos/talos.json 22:28:05 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/335ea7a954/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 22:28:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/335ea7a954/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 22:28:06 INFO - Downloaded 4264 bytes. 22:28:06 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 22:28:06 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 22:28:06 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 22:28:06 INFO - u'../fennec_ids.txt'], 22:28:06 INFO - u'tests': [u'tcheck2']}, 22:28:06 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 22:28:06 INFO - u'--tppagecycles', 22:28:06 INFO - u'7'], 22:28:06 INFO - u'tests': [u'tsvgm']}}, 22:28:06 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 22:28:06 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 22:28:06 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 22:28:06 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 22:28:06 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 22:28:06 INFO - u'tests': [u'dromaeo_css', 22:28:06 INFO - u'kraken', 22:28:06 INFO - u'v8_7']}, 22:28:06 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:06 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 22:28:06 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:06 INFO - u'talos_options': [u'--e10s'], 22:28:06 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 22:28:06 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:06 INFO - u'tests': [u'damp', u'tps']}, 22:28:06 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:06 INFO - u'talos_options': [u'--e10s'], 22:28:06 INFO - u'tests': [u'damp', u'tps']}, 22:28:06 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 22:28:06 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 22:28:06 INFO - u'other': {u'tests': [u'a11yr', 22:28:06 INFO - u'ts_paint', 22:28:06 INFO - u'tpaint', 22:28:06 INFO - u'sessionrestore', 22:28:06 INFO - u'sessionrestore_no_auto_restore']}, 22:28:06 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 22:28:06 INFO - u'tests': [u'a11yr', 22:28:06 INFO - u'ts_paint', 22:28:06 INFO - u'tpaint', 22:28:06 INFO - u'sessionrestore', 22:28:06 INFO - u'sessionrestore_no_auto_restore']}, 22:28:06 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 22:28:06 INFO - u'tests': [u'a11yr', 22:28:06 INFO - u'ts_paint', 22:28:06 INFO - u'tpaint', 22:28:06 INFO - u'sessionrestore', 22:28:06 INFO - u'sessionrestore_no_auto_restore']}, 22:28:06 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 22:28:06 INFO - u'tests': [u'a11yr', 22:28:06 INFO - u'ts_paint', 22:28:06 INFO - u'tpaint', 22:28:06 INFO - u'sessionrestore', 22:28:06 INFO - u'sessionrestore_no_auto_restore']}, 22:28:06 INFO - u'other_l64': {u'tests': [u'a11yr', 22:28:06 INFO - u'ts_paint', 22:28:06 INFO - u'tpaint', 22:28:06 INFO - u'sessionrestore', 22:28:06 INFO - u'sessionrestore_no_auto_restore']}, 22:28:06 INFO - u'other_nol64': {u'tests': [u'a11yr', 22:28:06 INFO - u'ts_paint', 22:28:06 INFO - u'tpaint', 22:28:06 INFO - u'sessionrestore', 22:28:06 INFO - u'sessionrestore_no_auto_restore']}, 22:28:06 INFO - u'svgr': {u'tests': [u'tsvgx', 22:28:06 INFO - u'tsvgr_opacity', 22:28:06 INFO - u'tart', 22:28:06 INFO - u'tscrollx', 22:28:06 INFO - u'cart']}, 22:28:06 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 22:28:06 INFO - u'tests': [u'tsvgx', 22:28:06 INFO - u'tsvgr_opacity', 22:28:06 INFO - u'tart', 22:28:06 INFO - u'tscrollx', 22:28:06 INFO - u'cart']}, 22:28:06 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:06 INFO - u'tests': [u'tp5o']}, 22:28:06 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:06 INFO - u'talos_options': [u'--e10s'], 22:28:06 INFO - u'tests': [u'tp5o']}, 22:28:06 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:06 INFO - u'talos_options': [u'--xperf_path', 22:28:06 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:28:06 INFO - u'tests': [u'tp5n']}, 22:28:06 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:06 INFO - u'talos_options': [u'--e10s', 22:28:06 INFO - u'--xperf_path', 22:28:06 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 22:28:06 INFO - u'C:/slave/talos-data/talos/xperf.config'], 22:28:06 INFO - u'tests': [u'tp5n']}}, 22:28:06 INFO - u'talos.zip': {u'path': u'', 22:28:06 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:28:06 INFO - Running post-action listener: _resource_record_post_action 22:28:06 INFO - ##### 22:28:06 INFO - ##### Running create-virtualenv step. 22:28:06 INFO - ##### 22:28:06 INFO - Running pre-action listener: _resource_record_pre_action 22:28:06 INFO - Running main action method: create_virtualenv 22:28:06 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 22:28:06 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 22:28:06 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 22:28:06 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'! 22:28:06 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 22:28:06 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 22:28:20 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 22:28:25 INFO - Installing distribute......................................................................................................................................................................................done. 22:28:27 INFO - Return code: 0 22:28:27 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 22:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:27 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023CDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02392EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0246AD98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01DB8B48>, '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 0x01DDA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0243F660>, '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-030', 'USERDOMAIN': 'T-W864-IX-030', '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-030', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-030', '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-030\\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 22:28:27 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 22:28:27 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:28:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:28:27 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Roaming', 22:28:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:28:27 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:28:27 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:28:27 INFO - 'COMPUTERNAME': 'T-W864-IX-030', 22:28:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:28:27 INFO - 'DCLOCATION': 'SCL3', 22:28:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:28:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:28:27 INFO - 'HOMEDRIVE': 'C:', 22:28:27 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 22:28:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:28:27 INFO - 'KTS_VERSION': '1.19c', 22:28:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 22:28:27 INFO - 'LOGONSERVER': '\\\\T-W864-IX-030', 22:28:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:28:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:27 INFO - 'MOZ_NO_REMOTE': '1', 22:28:27 INFO - 'NO_EM_RESTART': '1', 22:28:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:28:27 INFO - 'OS': 'Windows_NT', 22:28:27 INFO - 'OURDRIVE': 'C:', 22:28:27 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', 22:28:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:28:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:28:27 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:28:27 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:28:27 INFO - 'PROCESSOR_LEVEL': '6', 22:28:27 INFO - 'PROCESSOR_REVISION': '1e05', 22:28:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:28:27 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:28:27 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:28:27 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:28:27 INFO - 'PROMPT': '$P$G', 22:28:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:28:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:28:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:28:27 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:28:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:28:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:28:27 INFO - 'SYSTEMDRIVE': 'C:', 22:28:27 INFO - 'SYSTEMROOT': 'C:\\windows', 22:28:27 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:27 INFO - 'TEST1': 'testie', 22:28:27 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:27 INFO - 'USERDOMAIN': 'T-W864-IX-030', 22:28:27 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 22:28:27 INFO - 'USERNAME': 'cltbld', 22:28:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-030', 22:28:27 INFO - 'WINDIR': 'C:\\windows', 22:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:28:29 INFO - Ignoring indexes: http://pypi.python.org/simple/ 22:28:29 INFO - Downloading/unpacking pip>=1.5 22:28:29 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 22:28:37 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 22:28:37 INFO - Running setup.py egg_info for package pip 22:28:37 INFO - warning: no files found matching 'pip\cacert.pem' 22:28:37 INFO - warning: no files found matching '*.html' under directory 'docs' 22:28:37 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 22:28:37 INFO - no previously-included directories found matching 'docs\_build\_sources' 22:28:37 INFO - Installing collected packages: pip 22:28:37 INFO - Found existing installation: pip 0.8.2 22:28:37 INFO - Uninstalling pip: 22:28:37 INFO - Successfully uninstalled pip 22:28:37 INFO - Running setup.py install for pip 22:28:37 INFO - warning: no files found matching 'pip\cacert.pem' 22:28:37 INFO - warning: no files found matching '*.html' under directory 'docs' 22:28:37 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 22:28:37 INFO - no previously-included directories found matching 'docs\_build\_sources' 22:28:37 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:37 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:37 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:37 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:37 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:37 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:37 INFO - Successfully installed pip 22:28:37 INFO - Cleaning up... 22:28:37 INFO - Return code: 0 22:28:37 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 22:28:37 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')]} 22:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:37 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')]} 22:28:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:37 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 0x023CDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02392EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0246AD98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01DB8B48>, '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 0x01DDA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0243F660>, '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-030', 'USERDOMAIN': 'T-W864-IX-030', '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-030', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-030', '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-030\\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 22:28:37 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 22:28:37 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 22:28:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:28:37 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Roaming', 22:28:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:28:37 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:28:37 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:28:37 INFO - 'COMPUTERNAME': 'T-W864-IX-030', 22:28:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:28:37 INFO - 'DCLOCATION': 'SCL3', 22:28:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:28:37 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:28:37 INFO - 'HOMEDRIVE': 'C:', 22:28:37 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 22:28:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:28:37 INFO - 'KTS_VERSION': '1.19c', 22:28:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 22:28:37 INFO - 'LOGONSERVER': '\\\\T-W864-IX-030', 22:28:37 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:28:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:28:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:37 INFO - 'MOZ_NO_REMOTE': '1', 22:28:37 INFO - 'NO_EM_RESTART': '1', 22:28:37 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:28:37 INFO - 'OS': 'Windows_NT', 22:28:37 INFO - 'OURDRIVE': 'C:', 22:28:37 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', 22:28:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:28:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:28:37 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:28:37 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:28:37 INFO - 'PROCESSOR_LEVEL': '6', 22:28:37 INFO - 'PROCESSOR_REVISION': '1e05', 22:28:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:28:37 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:28:37 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:28:37 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:28:37 INFO - 'PROMPT': '$P$G', 22:28:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:28:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:28:37 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:28:37 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:28:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:28:37 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:28:37 INFO - 'SYSTEMDRIVE': 'C:', 22:28:37 INFO - 'SYSTEMROOT': 'C:\\windows', 22:28:37 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:37 INFO - 'TEST1': 'testie', 22:28:37 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:37 INFO - 'USERDOMAIN': 'T-W864-IX-030', 22:28:37 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 22:28:37 INFO - 'USERNAME': 'cltbld', 22:28:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-030', 22:28:37 INFO - 'WINDIR': 'C:\\windows', 22:28:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:28:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:40 INFO - Downloading/unpacking psutil>=0.7.1 22:28:40 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 22:28:40 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 22:28:41 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 22:28:41 INFO - Installing collected packages: psutil 22:28:41 INFO - Successfully installed psutil 22:28:41 INFO - Cleaning up... 22:28:42 INFO - Return code: 0 22:28:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 22:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:42 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 0x023CDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02392EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0246AD98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01DB8B48>, '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 0x01DDA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0243F660>, '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-030', 'USERDOMAIN': 'T-W864-IX-030', '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-030', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-030', '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-030\\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 22:28: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', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build 22:28: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 mozsystemmonitor==0.0.0 22:28:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:28:42 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Roaming', 22:28:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:28:42 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:28:42 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:28:42 INFO - 'COMPUTERNAME': 'T-W864-IX-030', 22:28:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:28:42 INFO - 'DCLOCATION': 'SCL3', 22:28:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:28:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:28:42 INFO - 'HOMEDRIVE': 'C:', 22:28:42 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 22:28:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:28:42 INFO - 'KTS_VERSION': '1.19c', 22:28:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 22:28:42 INFO - 'LOGONSERVER': '\\\\T-W864-IX-030', 22:28:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:28:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:42 INFO - 'MOZ_NO_REMOTE': '1', 22:28:42 INFO - 'NO_EM_RESTART': '1', 22:28:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:28:42 INFO - 'OS': 'Windows_NT', 22:28:42 INFO - 'OURDRIVE': 'C:', 22:28: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', 22:28:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:28:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:28:42 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:28:42 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:28:42 INFO - 'PROCESSOR_LEVEL': '6', 22:28:42 INFO - 'PROCESSOR_REVISION': '1e05', 22:28:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:28:42 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:28:42 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:28:42 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:28:42 INFO - 'PROMPT': '$P$G', 22:28:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:28:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:28:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:28:42 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:28:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:28:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:28:42 INFO - 'SYSTEMDRIVE': 'C:', 22:28:42 INFO - 'SYSTEMROOT': 'C:\\windows', 22:28:42 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:42 INFO - 'TEST1': 'testie', 22:28:42 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:42 INFO - 'USERDOMAIN': 'T-W864-IX-030', 22:28:42 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 22:28:42 INFO - 'USERNAME': 'cltbld', 22:28:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-030', 22:28:42 INFO - 'WINDIR': 'C:\\windows', 22:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:28:43 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 22:28:43 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 22:28:43 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) 22:28:43 INFO - Installing collected packages: mozsystemmonitor 22:28:43 INFO - Running setup.py install for mozsystemmonitor 22:28:43 INFO - Successfully installed mozsystemmonitor 22:28:43 INFO - Cleaning up... 22:28:44 INFO - Return code: 0 22:28:44 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 22:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:44 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--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 0x023CDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02392EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0246AD98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01DB8B48>, '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 0x01DDA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0243F660>, '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-030', 'USERDOMAIN': 'T-W864-IX-030', '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-030', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-030', '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-030\\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 22:28:44 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 22:28:44 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 22:28:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:28:44 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Roaming', 22:28:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:28:44 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:28:44 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:28:44 INFO - 'COMPUTERNAME': 'T-W864-IX-030', 22:28:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:28:44 INFO - 'DCLOCATION': 'SCL3', 22:28:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:28:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:28:44 INFO - 'HOMEDRIVE': 'C:', 22:28:44 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 22:28:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:28:44 INFO - 'KTS_VERSION': '1.19c', 22:28:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 22:28:44 INFO - 'LOGONSERVER': '\\\\T-W864-IX-030', 22:28:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:28:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:44 INFO - 'MOZ_NO_REMOTE': '1', 22:28:44 INFO - 'NO_EM_RESTART': '1', 22:28:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:28:44 INFO - 'OS': 'Windows_NT', 22:28:44 INFO - 'OURDRIVE': 'C:', 22:28:44 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 22:28:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:28:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:28:44 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:28:44 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:28:44 INFO - 'PROCESSOR_LEVEL': '6', 22:28:44 INFO - 'PROCESSOR_REVISION': '1e05', 22:28:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:28:44 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:28:44 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:28:44 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:28:44 INFO - 'PROMPT': '$P$G', 22:28:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:28:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:28:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:28:44 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:28:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:28:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:28:44 INFO - 'SYSTEMDRIVE': 'C:', 22:28:44 INFO - 'SYSTEMROOT': 'C:\\windows', 22:28:44 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:44 INFO - 'TEST1': 'testie', 22:28:44 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:44 INFO - 'USERDOMAIN': 'T-W864-IX-030', 22:28:44 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 22:28:44 INFO - 'USERNAME': 'cltbld', 22:28:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-030', 22:28:44 INFO - 'WINDIR': 'C:\\windows', 22:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:44 INFO - Downloading/unpacking blobuploader==1.2.4 22:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:45 INFO - Downloading blobuploader-1.2.4.tar.gz 22:28:45 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 22:28:45 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 22:28:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:45 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 22:28:45 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 22:28:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:45 INFO - Downloading docopt-0.6.1.tar.gz 22:28:45 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 22:28:45 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 22:28:46 INFO - Installing collected packages: blobuploader, requests, docopt 22:28:46 INFO - Running setup.py install for blobuploader 22:28:46 INFO - Running setup.py install for requests 22:28:47 INFO - Running setup.py install for docopt 22:28:47 INFO - Successfully installed blobuploader requests docopt 22:28:47 INFO - Cleaning up... 22:28:48 INFO - Return code: 0 22:28:48 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:28:48 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')]} 22:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:48 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')]} 22:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:48 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 0x023CDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02392EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0246AD98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01DB8B48>, '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 0x01DDA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0243F660>, '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-030', 'USERDOMAIN': 'T-W864-IX-030', '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-030', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-030', '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-030\\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 22:28:48 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 22:28:48 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 22:28:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:28:48 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Roaming', 22:28:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:28:48 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:28:48 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:28:48 INFO - 'COMPUTERNAME': 'T-W864-IX-030', 22:28:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:28:48 INFO - 'DCLOCATION': 'SCL3', 22:28:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:28:48 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:28:48 INFO - 'HOMEDRIVE': 'C:', 22:28:48 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 22:28:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:28:48 INFO - 'KTS_VERSION': '1.19c', 22:28:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 22:28:48 INFO - 'LOGONSERVER': '\\\\T-W864-IX-030', 22:28:48 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:28:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:48 INFO - 'MOZ_NO_REMOTE': '1', 22:28:48 INFO - 'NO_EM_RESTART': '1', 22:28:48 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:28:48 INFO - 'OS': 'Windows_NT', 22:28:48 INFO - 'OURDRIVE': 'C:', 22:28:48 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', 22:28:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:28:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:28:48 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:28:48 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:28:48 INFO - 'PROCESSOR_LEVEL': '6', 22:28:48 INFO - 'PROCESSOR_REVISION': '1e05', 22:28:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:28:48 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:28:48 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:28:48 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:28:48 INFO - 'PROMPT': '$P$G', 22:28:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:28:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:28:48 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:28:48 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:28:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:28:48 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:28:48 INFO - 'SYSTEMDRIVE': 'C:', 22:28:48 INFO - 'SYSTEMROOT': 'C:\\windows', 22:28:48 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:48 INFO - 'TEST1': 'testie', 22:28:48 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:48 INFO - 'USERDOMAIN': 'T-W864-IX-030', 22:28:48 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 22:28:48 INFO - 'USERNAME': 'cltbld', 22:28:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-030', 22:28:48 INFO - 'WINDIR': 'C:\\windows', 22:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 22:28:48 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-luoghp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 22:28:48 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 22:28:48 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-wscori-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 22:28:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 22:28:49 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-tjvfmb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 22:28:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 22:28:49 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ncwedq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 22:28:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 22:28:49 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-y12osb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 22:28:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 22:28:49 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-zugaa0-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 22:28:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 22:28:49 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-i2d68o-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 22:28:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 22:28:49 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-zzrjvp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 22:28:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 22:28:49 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-whnnpn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 22:28:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 22:28:49 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-eu9haf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 22:28:49 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 22:28:49 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-dqywpt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 22:28:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 22:28:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-xwvtpk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 22:28:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 22:28:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-sallef-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 22:28:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 22:28:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ds_iow-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 22:28:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 22:28:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ajr2wf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:28:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 22:28:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-5hgyah-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 22:28:50 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 22:28:50 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-98m33o-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 22:28:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:28:50 INFO - Running setup.py install for manifestparser 22:28:50 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:50 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:51 INFO - Running setup.py install for mozcrash 22:28:51 INFO - Running setup.py install for mozdebug 22:28:52 INFO - Running setup.py install for mozdevice 22:28:52 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:52 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:52 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:52 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:52 INFO - Running setup.py install for mozfile 22:28:53 INFO - Running setup.py install for mozhttpd 22:28:53 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:53 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:53 INFO - Running setup.py install for mozinfo 22:28:54 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Running setup.py install for mozInstall 22:28:54 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:54 INFO - Running setup.py install for mozleak 22:28:55 INFO - Running setup.py install for mozlog 22:28:55 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:55 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:55 INFO - Running setup.py install for moznetwork 22:28:56 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:56 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:56 INFO - Running setup.py install for mozprocess 22:28:56 INFO - Running setup.py install for mozprofile 22:28:57 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:57 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:57 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:57 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:57 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:57 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:57 INFO - Running setup.py install for mozrunner 22:28:58 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:58 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:58 INFO - Running setup.py install for mozscreenshot 22:28:58 INFO - Running setup.py install for moztest 22:28:59 INFO - Running setup.py install for mozversion 22:28:59 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:28:59 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 22:28:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:28:59 INFO - Cleaning up... 22:28:59 INFO - Return code: 0 22:28:59 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:59 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 0x023CDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02392EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0246AD98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01DB8B48>, '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 0x01DDA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0243F660>, '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-030', 'USERDOMAIN': 'T-W864-IX-030', '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-030', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-030', '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-030\\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 22:28:59 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 22:28:59 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 22:28:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:28:59 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Roaming', 22:28:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:28:59 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:28:59 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:28:59 INFO - 'COMPUTERNAME': 'T-W864-IX-030', 22:28:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:28:59 INFO - 'DCLOCATION': 'SCL3', 22:28:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:28:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:28:59 INFO - 'HOMEDRIVE': 'C:', 22:28:59 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 22:28:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:28:59 INFO - 'KTS_VERSION': '1.19c', 22:28:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 22:28:59 INFO - 'LOGONSERVER': '\\\\T-W864-IX-030', 22:28:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:28:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:59 INFO - 'MOZ_NO_REMOTE': '1', 22:28:59 INFO - 'NO_EM_RESTART': '1', 22:28:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:28:59 INFO - 'OS': 'Windows_NT', 22:28:59 INFO - 'OURDRIVE': 'C:', 22:28:59 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', 22:28:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:28:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:28:59 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:28:59 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:28:59 INFO - 'PROCESSOR_LEVEL': '6', 22:28:59 INFO - 'PROCESSOR_REVISION': '1e05', 22:28:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:28:59 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:28:59 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:28:59 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:28:59 INFO - 'PROMPT': '$P$G', 22:28:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:28:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:28:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:28:59 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:28:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:28:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:28:59 INFO - 'SYSTEMDRIVE': 'C:', 22:28:59 INFO - 'SYSTEMROOT': 'C:\\windows', 22:28:59 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:59 INFO - 'TEST1': 'testie', 22:28:59 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:28:59 INFO - 'USERDOMAIN': 'T-W864-IX-030', 22:28:59 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 22:28:59 INFO - 'USERNAME': 'cltbld', 22:28:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-030', 22:28:59 INFO - 'WINDIR': 'C:\\windows', 22:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 22:29:00 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-zkhzt3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 22:29:00 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)) 22:29:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 22:29:00 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-0d1uwv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 22:29:00 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)) 22:29:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 22:29:00 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-omw7lg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 22:29:00 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)) 22:29:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 22:29:00 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ldxjj8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 22:29:00 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)) 22:29:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 22:29:00 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ym0doq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 22:29:00 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)) 22:29:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 22:29:00 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-h4v7b2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 22:29:00 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)) 22:29:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 22:29:00 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-qdxvxi-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 22:29:00 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)) 22:29:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 22:29:00 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-7m8ntk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 22:29:00 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)) 22:29:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 22:29:00 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-pjhijl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 22:29:01 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)) 22:29:01 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 22:29:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-we8ydk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 22:29:01 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)) 22:29:01 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 22:29:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-8bcrb3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 22:29:01 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)) 22:29:01 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 22:29:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-yfs3tt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 22:29:01 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)) 22:29:01 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 22:29:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-wcqbla-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 22:29:01 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)) 22:29:01 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 22:29:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-r4tu6d-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 22:29:01 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)) 22:29:01 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 22:29:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-qeajjr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:29:01 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)) 22:29:01 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 22:29:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-akztvu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 22:29:01 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)) 22:29:01 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 22:29:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-orvae6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 22:29:02 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)) 22:29:02 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)) 22:29:02 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)) 22:29:02 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)) 22:29:02 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)) 22:29:02 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)) 22:29:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 22:29:02 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 22:29:02 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 22:29:02 INFO - Downloading blessings-1.5.1.tar.gz 22:29:02 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 22:29:02 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 22:29:03 INFO - Installing collected packages: blessings 22:29:03 INFO - Running setup.py install for blessings 22:29:03 INFO - Successfully installed blessings 22:29:03 INFO - Cleaning up... 22:29:06 INFO - Return code: 0 22:29:06 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 22:29:06 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 22:29:06 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 22:29:07 INFO - Reading from file tmpfile_stdout 22:29:07 INFO - Using _rmtree_windows ... 22:29:07 INFO - Using _rmtree_windows ... 22:29:07 INFO - Current package versions: 22:29:07 INFO - blessings == 1.5.1 22:29:07 INFO - blobuploader == 1.2.4 22:29:07 INFO - docopt == 0.6.1 22:29:07 INFO - manifestparser == 1.1 22:29:07 INFO - mozInstall == 1.12 22:29:07 INFO - mozcrash == 0.16 22:29:07 INFO - mozdebug == 0.1 22:29:07 INFO - mozdevice == 0.46 22:29:07 INFO - mozfile == 1.2 22:29:07 INFO - mozhttpd == 0.7 22:29:07 INFO - mozinfo == 0.8 22:29:07 INFO - mozleak == 0.1 22:29:07 INFO - mozlog == 3.0 22:29:07 INFO - moznetwork == 0.27 22:29:07 INFO - mozprocess == 0.22 22:29:07 INFO - mozprofile == 0.27 22:29:07 INFO - mozrunner == 6.11 22:29:07 INFO - mozscreenshot == 0.1 22:29:07 INFO - mozsystemmonitor == 0.0 22:29:07 INFO - moztest == 0.7 22:29:07 INFO - mozversion == 1.4 22:29:07 INFO - psutil == 3.1.1 22:29:07 INFO - requests == 1.2.3 22:29:07 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:29:07 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')]} 22:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:07 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')]} 22:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:07 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 0x023CDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02392EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0246AD98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01DB8B48>, '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 0x01DDA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0243F660>, '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-030', 'USERDOMAIN': 'T-W864-IX-030', '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-030', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-030', '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-030\\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 22:29:07 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 22:29:07 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 22:29:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:29:07 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Roaming', 22:29:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:29:07 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:29:07 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:29:07 INFO - 'COMPUTERNAME': 'T-W864-IX-030', 22:29:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:29:07 INFO - 'DCLOCATION': 'SCL3', 22:29:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:29:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:29:07 INFO - 'HOMEDRIVE': 'C:', 22:29:07 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 22:29:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:29:07 INFO - 'KTS_VERSION': '1.19c', 22:29:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 22:29:07 INFO - 'LOGONSERVER': '\\\\T-W864-IX-030', 22:29:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:29:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:07 INFO - 'MOZ_NO_REMOTE': '1', 22:29:07 INFO - 'NO_EM_RESTART': '1', 22:29:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:29:07 INFO - 'OS': 'Windows_NT', 22:29:07 INFO - 'OURDRIVE': 'C:', 22:29:07 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', 22:29:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:29:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:29:07 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:29:07 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:29:07 INFO - 'PROCESSOR_LEVEL': '6', 22:29:07 INFO - 'PROCESSOR_REVISION': '1e05', 22:29:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:29:07 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:29:07 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:29:07 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:29:07 INFO - 'PROMPT': '$P$G', 22:29:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:29:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:29:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:29:07 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:29:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:29:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:29:07 INFO - 'SYSTEMDRIVE': 'C:', 22:29:07 INFO - 'SYSTEMROOT': 'C:\\windows', 22:29:07 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:29:07 INFO - 'TEST1': 'testie', 22:29:07 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:29:07 INFO - 'USERDOMAIN': 'T-W864-IX-030', 22:29:07 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 22:29:07 INFO - 'USERNAME': 'cltbld', 22:29:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-030', 22:29:07 INFO - 'WINDIR': 'C:\\windows', 22:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:07 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)) 22:29:07 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)) 22:29:07 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)) 22:29:07 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)) 22:29:07 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)) 22:29:07 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)) 22:29:07 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)) 22:29:07 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)) 22:29:07 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)) 22:29:07 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)) 22:29:07 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)) 22:29:07 INFO - Cleaning up... 22:29:07 INFO - Return code: 0 22:29:07 INFO - Running post-action listener: _resource_record_post_action 22:29:07 INFO - Running post-action listener: _start_resource_monitoring 22:29:07 INFO - Starting resource monitoring. 22:29:07 INFO - ##### 22:29:07 INFO - ##### Running install step. 22:29:07 INFO - ##### 22:29:07 INFO - Running pre-action listener: _resource_record_pre_action 22:29:07 INFO - Running main action method: install 22:29:07 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 22:29:07 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 22:29:07 INFO - Reading from file tmpfile_stdout 22:29:07 INFO - Using _rmtree_windows ... 22:29:07 INFO - Using _rmtree_windows ... 22:29:07 INFO - Detecting whether we're running mozinstall >=1.0... 22:29:07 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 22:29:07 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 22:29:07 INFO - Reading from file tmpfile_stdout 22:29:07 INFO - Output received: 22:29:07 INFO - Usage: mozinstall-script.py [options] installer 22:29:07 INFO - Options: 22:29:07 INFO - -h, --help show this help message and exit 22:29:07 INFO - -d DEST, --destination=DEST 22:29:07 INFO - Directory to install application into. [default: 22:29:07 INFO - "C:\slave\test-pgo"] 22:29:07 INFO - --app=APP Application being installed. [default: firefox] 22:29:07 INFO - Using _rmtree_windows ... 22:29:07 INFO - Using _rmtree_windows ... 22:29:07 INFO - mkdir: C:\slave\test-pgo\build\application 22:29:07 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'] 22:29:07 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 22:29:10 INFO - Reading from file tmpfile_stdout 22:29:10 INFO - Output received: 22:29:10 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 22:29:10 INFO - Using _rmtree_windows ... 22:29:10 INFO - Using _rmtree_windows ... 22:29:10 INFO - Running post-action listener: _resource_record_post_action 22:29:10 INFO - ##### 22:29:10 INFO - ##### Running run-tests step. 22:29:10 INFO - ##### 22:29:10 INFO - Running pre-action listener: _resource_record_pre_action 22:29:10 INFO - Running main action method: run_tests 22:29:10 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 22:29:10 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 22:29:10 INFO - Python 2.7.3 22:29:10 INFO - Return code: 0 22:29:10 INFO - grabbing minidump binary from tooltool 22:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:29:10 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 0x01DB8B48>, '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 0x01DDA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0243F660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 22:29:10 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 22:29:10 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 22:29:10 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 22:29:10 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpcypmuh 22:29:10 INFO - INFO - File integrity verified, renaming tmpcypmuh to win32-minidump_stackwalk.exe 22:29:10 INFO - Return code: 0 22:29:10 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 22:29:10 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 22:29:10 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 22:29:10 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 22:29:10 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 22:29:10 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-030', '--webServer', 'localhost'] in C:\slave\test-pgo\build 22:29:10 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip --title T-W864-IX-030 --webServer localhost 22:29:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:29:10 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Roaming', 22:29:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:29:10 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:29:10 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:29:10 INFO - 'COMPUTERNAME': 'T-W864-IX-030', 22:29:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:29:10 INFO - 'DCLOCATION': 'SCL3', 22:29:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:29:10 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:29:10 INFO - 'HOMEDRIVE': 'C:', 22:29:10 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-030', 22:29:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:29:10 INFO - 'KTS_VERSION': '1.19c', 22:29:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-030\\AppData\\Local', 22:29:10 INFO - 'LOGONSERVER': '\\\\T-W864-IX-030', 22:29:10 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 22:29:10 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 22:29:10 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:29:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:10 INFO - 'MOZ_NO_REMOTE': '1', 22:29:10 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 22:29:10 INFO - 'NO_EM_RESTART': '1', 22:29:10 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:29:10 INFO - 'OS': 'Windows_NT', 22:29:10 INFO - 'OURDRIVE': 'C:', 22:29:10 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', 22:29:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:29:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:29:10 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:29:10 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:29:10 INFO - 'PROCESSOR_LEVEL': '6', 22:29:10 INFO - 'PROCESSOR_REVISION': '1e05', 22:29:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:29:10 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:29:10 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:29:10 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:29:10 INFO - 'PROMPT': '$P$G', 22:29:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:29:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:29:10 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:29:10 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:29:10 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 22:29:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:29:10 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:29:10 INFO - 'SYSTEMDRIVE': 'C:', 22:29:10 INFO - 'SYSTEMROOT': 'C:\\windows', 22:29:10 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:29:10 INFO - 'TEST1': 'testie', 22:29:10 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:29:10 INFO - 'USERDOMAIN': 'T-W864-IX-030', 22:29:10 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-030', 22:29:10 INFO - 'USERNAME': 'cltbld', 22:29:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-030', 22:29:10 INFO - 'WINDIR': 'C:\\windows', 22:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:29:10 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-030', '--webServer', 'localhost'] with output_timeout 3600 ProcessManager UNABLE to use job objects to manage child processes ProcessManager NOT managing child processes 22:29:11 INFO - mozversion INFO | application_buildid: 20151023173019 22:29:11 INFO - mozversion INFO | application_changeset: 335ea7a95492650e23c9ba14648d4f43620babab 22:29:11 INFO - mozversion INFO | application_display_name: Nightly 22:29:11 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:29:11 INFO - mozversion INFO | application_name: Firefox 22:29:11 INFO - mozversion INFO | application_remotingname: firefox 22:29:11 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 22:29:11 INFO - mozversion INFO | application_vendor: Mozilla 22:29:11 INFO - mozversion INFO | application_version: 44.0a1 22:29:11 INFO - mozversion INFO | platform_buildid: 20151023173019 22:29:11 INFO - mozversion INFO | platform_changeset: 335ea7a95492650e23c9ba14648d4f43620babab 22:29:11 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 22:29:11 INFO - mozversion INFO | platform_version: 44.0a1 22:29:11 INFO - 2015-10-23 22:29:11,532 DEBUG : using testdate: 1445664551 22:29:11 INFO - 2015-10-23 22:29:11,533 DEBUG : actual date: 1445664551 22:29:11 INFO - 2015-10-23 22:29:11,545 INFO : starting webserver on 'localhost:49272' 22:29:11 INFO - 2015-10-23 22:29:11,546 INFO : Starting test suite T-W864-IX-030 22:29:11 INFO - 2015-10-23 22:29:11,546 INFO : Starting test dromaeo_css 22:29:11 INFO - 2015-10-23 22:29:11,546 DEBUG : operating with platform_type : w8_ 22:29:11 INFO - 2015-10-23 22:29:11,546 INFO : Initialising browser for dromaeo_css test... 22:29:11 INFO - 2015-10-23 22:29:11,654 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpawqzfr\profile http://localhost:49272/getInfo.html 22:29:21 INFO - 2015-10-23 22:29:21,734 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:29:21 INFO - 2015-10-23 22:29:21,734 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:29:21 INFO - 2015-10-23 22:29:21,734 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 22:29:21 INFO - 2015-10-23 22:29:21,734 DEBUG : BROWSER_OUTPUT: __metrics 22:29:21 INFO - 2015-10-23 22:29:21,937 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: console.error: 22:29:23 INFO - 2015-10-23 22:29:23,476 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] 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: Stack: 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 22:29:23 INFO - 2015-10-23 22:29:23,476 DEBUG : BROWSER_OUTPUT: 22:29:24 INFO - 2015-10-23 22:29:24,242 INFO : Browser initialized. 22:29:24 INFO - 2015-10-23 22:29:24,242 INFO : Running cycle 1/1 for dromaeo_css test... 22:29:24 INFO - 2015-10-23 22:29:24,242 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpawqzfr\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:29:35 INFO - 2015-10-23 22:29:35,594 DEBUG : BROWSER_OUTPUT: RSS: Main: 169873408 22:29:35 INFO - 2015-10-23 22:29:35,594 DEBUG : BROWSER_OUTPUT: 22:30:27 INFO - 2015-10-23 22:30:27,563 DEBUG : BROWSER_OUTPUT: 1445664627563 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:32:35 INFO - 2015-10-23 22:32:35,834 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 22:32:36 INFO - 2015-10-23 22:32:36,947 DEBUG : BROWSER_OUTPUT: RSS: Main: 228683776 22:32:36 INFO - 2015-10-23 22:32:36,947 DEBUG : BROWSER_OUTPUT: 22:35:39 INFO - 2015-10-23 22:35:39,667 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 22:35:40 INFO - 2015-10-23 22:35:40,229 DEBUG : BROWSER_OUTPUT: RSS: Main: 227643392 22:35:40 INFO - 2015-10-23 22:35:40,229 DEBUG : BROWSER_OUTPUT: 22:38:42 INFO - 2015-10-23 22:38:42,295 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 22:38:42 INFO - 2015-10-23 22:38:42,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 175366144 22:38:42 INFO - 2015-10-23 22:38:42,858 DEBUG : BROWSER_OUTPUT: 22:41:44 INFO - 2015-10-23 22:41:44,888 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 22:41:45 INFO - 2015-10-23 22:41:45,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 163635200 22:41:45 INFO - 2015-10-23 22:41:45,451 DEBUG : BROWSER_OUTPUT: 22:44:48 INFO - 2015-10-23 22:44:48,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 22:44:48 INFO - 2015-10-23 22:44:48,788 DEBUG : BROWSER_OUTPUT: RSS: Main: 161357824 22:44:48 INFO - 2015-10-23 22:44:48,788 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-10-23 22:47:51,118 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 22:47:51 INFO - 2015-10-23 22:47:51,369 DEBUG : BROWSER_OUTPUT: RSS: Main: 230912000 22:47:51 INFO - 2015-10-23 22:47:51,369 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-10-23 22:47:51,369 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:47:51 INFO - 2015-10-23 22:47:51,369 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:47:51 INFO - 2015-10-23 22:47:51,369 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:47:51 INFO - 2015-10-23 22:47:51,371 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:47:51 INFO - 2015-10-23 22:47:51,371 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2908.7963842531026;2917.8978735744254;2921.956170657439;2954.778391620629;2961.082064559985;5319.78720851166;5361.061814182517;5398.379186393566;5402.8919421611645;5464.890702185964;5405.054115529786;5420.83737487875;5439.864003399917;5441.755121019553;5444.237806707064;4700.106979673851;4759.762011899391;4803.423589169304;4832.178529649968;4866.683665561727;4835.637327200474;4836.056968891067;4837.153498137829;4840.75796210188;4845.127876982142;4846.151923413407;4847.854564363057;4850.223964165734;4851.878703032417;4853.635977301698;4773.618110551148;4848.20004699226;4856.635752318555;4863.586595139417;4902.534259245366;4744.16977029022;4746.169420351424;4793.616510679172;4793.760311984422;4851.442084160331;5259.894802103937;5297.125974214288;5307.363116426053;5338.332708411449;5339.11904535755;5303.443138470444;5467.097928841737;5469.425710300441;5565.30433695788;5608.747606357683;5589.80435684749;5613.971930140364;5618.325800903917;5620.606557540974;5641.435856414326;5133.281340612317;5149.897002059938;5157.896842063138;5167.741612919376;5170.379554453452;5616.691081990448;5664.54683625309;5685.630434021776;5730.799422020209;5736.368999410061;5447.96488667152;5483.588730845203;5494.395616482184;5500.257465242221;5545.805896793593;5553.389127196006;5590.133529302964;5596.524295434852;5597.692126932976;5599.468050535193;5416.187571864173;5473.890522189614;5483.616146869761;5526.723663816873;5544.1960915667005;5579.972100139474;5599.552035837123;5604.887902241932;5615.915761263503;5617.578681598897;3570.5001299818045;3593.8023408712247;3599.7300202484917;3637.727170462226;3639.053846000059;3202.151429871086;3225.9838700806345;3265.134739294089;3271.6728327167093;3310.470324748029;5406.405295417501;5419.376771671308;5419.918701219485;5420.91868621971;5497.615166938356;5624.043912534797;5625.296837895263;5627.915581266284;5636.210930469738;5691.601587888808;5328.147496400635;5344.331958505187;5428.4842939920645;5429.212764149176;5430;4544.590986811137;4586.1057093866375;4587.17430862448;4595.517470665599;4645.628349732081;5914.881702366015;5986.042233242661;5992.880142397127;5997.190379298739;5999.190109335271;5459.645123067146;5531.446855314436;5554.666719996813;5583.609147359646;5633.295838020247;5941.881162376815;5984.910226346696;6000.519958403404;6004.1294012368835;6014.969925150522;5482.369527504388;5594.13290939905;5596.5242954347705;5611.102223644198;5613.354464236665;5421.376541697755;5511.090670039399;5591.720413979366;5623.3533143689;5624.803131890364;5511.696856672921;5546.833594992157;5574.442555744556;5577.247071645276;5589.441055894378;5521.337439507285;5530.004599172187;5542;5547.362053363915;5597.216389705363;5323.6273460857365;5334.546563542142;5403.324584426947;5407.945450637088;5447.291852059207;5536.91694624589;5543.722813859372;5558.972205138948;5584.994700953867;5590.468905453975;5443.891122177613;5453.26380938584;5461.918071228855;5489.149181876815;5499.790046189832 22:47:51 INFO - 2015-10-23 22:47:51,371 DEBUG : BROWSER_OUTPUT: |1;ext.html;553.9280958721704;585.8740370820274;589.1163255117322;590.654467136725;596.9791057312995;32107.357852842957;38786.806065969664;39147.60852391475;39301.21397572047;39323.21353572927;40645.796771016176;40870.38694419586;40894.795526022404;41309;41327;8124.796880077986;8381.161883811632;8441.620127094266;8447.070822209553;8586.184312490303;10287.845682314772;10451.26841121118;10493.422861742602;10551.10315623173;10566.101881340057;10247.38515689061;10281.897181028206;10369.896301036968;10378.065974062332;10406.895931040668;8399.70601028961;8499.320054395634;10190.6942791716;10338.431386273753;10679.83980240297;10447.791044179074;10599.046085852311;10603.363798172135;10605.416702081382;10610.151187904949;35956.64043359559;36204.27591448183;36256.63743362559;36279.4558081629;36447.81776091103;3409.4203985322556;3494.5631796025496;3619.1133172372674;3731.738778285521;3878.5733569307354;32907.83546082254;33427.665723342696;33811.32377352464;33934;33988.66011339879;3044.269375349922;3133.8433078345993;3145.7168854803176;3180.45932191528;3251.4147453458368;298286.99999999785;299152;299261.5036924823;299942;301482;51520.48479515232;52808.207876881876;55209;55452.72273638606;55526.444735552934;60338;60996.69501652463;61533.384666152764;61599.6920015406;61612.38387616156;13994;14046.85953140476;14086;14095.85904140966;14106.858931410554;10987.890121098846;11012;11012.229143960007;11026.558937642565;11031.172662050218;18194.818051819577;18224.726629100573;18301.816981830278;18306.45080647556;18360.35738749164;7762.223777622193;7956.801079972931;7969.163237859942;8003.759887203393;8039.598020098972;939.8543225800012;946.1437399153776;947.6541062512272;972.1979367022235;977.8288799460066;28439;28553.286167845556;28618.856905715755;29005.56491652627;31050.534241985937;1010.166612544639;1025.8563801067708;1029.341221618165;1029.7785976015193;1094;14429;14503.492377766728;14695.632609184855;15953.361865525481;16004.519864404087;462158;465061;467376;467553;467867;53528.39414817484;53811.461885381425;53895.73052134714;53936.73031634895;53938;1211.6486218996567;1212.2908098762146;1235.3144005077268;1251.4368534159664;1286.9034822388357;25123.497530049663;29953.70046299509;30217.697823021488;31769.364612707155;31964.20089497781;46341.30488042615;46664.30003549882;47084.29373559331;47159.292610611556;47339.76330118327;345650;351942;353459;353607;355752;31843;42664.36003459888;43370.85616027517;43382;43977.78011109924;43086.35370469383;43530.34704479499;43679.78160109179;43902;44932.77533612311;36734.44898326581;37252;37618.623813761515;37902.431463527515;38161.268876436334;30869.69130308668;36270.27459450849;36606.267874642894;37525.81237093797;37755.244895101394;37329.25341493101;37371.62628373681;37505.06237344088;37528.437073443376;37563.2487350246;30104.24739381533;32844.67155328416;32904;33649.495257571645;33661.49507757337;27019.324516887234;27816.304592384542;27820.86089569539;27842.860785695942;27858.86070569634 22:47:51 INFO - 2015-10-23 22:47:51,372 DEBUG : BROWSER_OUTPUT: |2;jquery.html;362.10559917004997;363.38587786640574;368.40870403003163;370.2317690791608;376.24938248194854;14056.508022219225;14150;15696.84303156968;16322.673546529077;16923.74614380784;10689.358638481697;11012.119030477563;16070.517884463456;16599.751003734953;16601.66796664066;4256.382824490447;4375.562443755631;4379.4525684289465;4382.364557139213;4393.253146965024;3522.418800897849;3578.4632305154173;4314.741115533062;4385.605295523403;4780.995990841928;4802.543758342952;4816.253480710496;4819.7108173509705;4824.7105173689515;4835.830745923893;4553.408056952608;4716.9292460613115;4801.591864691505;4808.735519546423;4824.758762061901;4030.8992275193064;4078.6125318094732;4080.204360149772;4086.2031903778775;4086.734362266473;11739.119566032496;11780.823287650692;11860.703482413008;11918.821217681741;11955.282683038959;2090.822280106193;2101.2015434135083;2142.282335417621;2165.2096984600507;2177.433867194525;8868.201861832466;9273;9334.626614935394;9395;9432.151106400388;1628;1639.9344026238937;1680.420255012019;1718.9226484808214;1728.5160155156577;64067;64474.388140296396;64647.35352646507;64994;66089;6519.282878883319;6583.814913315649;6602.372774586503;6609.702563384659;6677.465802735769;7714.228577142241;7756.185600511865;7767.844643107219;7831.334336581341;7871.252231038042;5065.56942659878;5087.923681144785;5138.820141295037;5148.639595228373;5178.093833579184;5266.92099618506;5300.337457817773;5301.708406037628;5302.70835104073;5316.627836051439;2950.7639388848843;2958.2456473599523;2968.643762748484;2986.731194192533;2988.1035689293126;1079.8002369561657;1093.4478088565384;1102.08526922654;1119.1047162270152;1134.5575225661999;385.383386581473;389.9356606159952;397.5090762907806;448.91021795641;451.3500559194749;9588.808223835624;10186.694399168036;10871;10987.450627468595;11072.889271107348;406.59746850617677;448.07025422248967;455.44435788338177;455.6241101091546;463.4415529287268;5784.797532086441;5856.209411729447;5874.383189765099;5885.352611212763;5941.494972927349;593947;597775;601272;612620;621858;76527.23472765202;78038;78088.26691065785;78704.21295787083;79098.20901790909;493.93331900193016;495.3609843302172;498.24018371983016;498.6310130503474;498.81793145502354;7811.648475818715;9993.550290236812;10037.69886903394;10134.543945522617;10172;31387;31808.36383272368;32973.50539741858;33609.327813444084;33919.491207631414;43902.560974389846;44444.55555444404;44449.77775111104;44456.77771611121;44762.3285650709;6232.25212974446;6427.93572064292;6445.70994305248;6448.774292899726;6454.1609590752905;10764.40728575713;11087.113030957342;11100.056495197998;11133.83299250528;11160.218784684994;8539.957300213458;8644.438111522732;9456.621735130793;9629.951850240705;9683.080107389787;9572.234221262142;9727.270454715783;9765.804683906425;9878;9899.307048506824;9897.109260166619;10130;10167.084962353423;10243.84634230502;10254.436006019441;6391.93608063932;6466;6492.707828147651;6494.253160886558;6505.2518960318275;9852.458114803469;9875.506224688881;10038.849417258602;10060.39637621745;10124.392536447836 22:47:51 INFO - 2015-10-23 22:47:51,375 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1272.5164437513743;1395.9092658977165;1510.6676531163146;1567.3338830996825;1612.0891696191643;8085.4340196186295;8327.042390125122;9204.309676774245;9659.227261819055;9672.951635241832;8902.688405905794;9283.118103780147;9341.579628916697;9352;9392.060793920622;2345.659879317502;2356.0458014504065;2371.2530552875787;2371.4782747795543;2376.3583832365252;2115.809577138057;2151.8493705440624;2318.2697450303185;2373.786359227669;2395.5866039036964;2282.8858557072163;2328.2782337475437;2336.9532609347802;2338.812894968407;2340.918067867625;2089.9895500522553;2315.1549684365255;2329.77867102625;2329.94175145621;2332.346942856003;2067.0904801887273;2251.79733823955;2271.6251818449937;2288.141946769961;2288.1533832481923;9211.217046550984;9331.253499720005;9356.859647105299;9374.437533747998;9377.062293770568;5214.921776173361;5274.314339135888;5288;5288.23320618512;5290.814821481268;3427.4344733119005;3448.896533104011;3487.7558570900046;3490.458978858281;3503.8773642922374;1059.9841002384971;1083.8970297821695;1089.2375337263948;1089.6948854320804;1122.1247427006865;14282.785758213635;14439.783403248959;14442.566722998326;14497.275136243146;14499.782503262459;4427.114577084596;4526.977365113154;4543.681942264076;4584.381108550369;4589.449266088091;11320.886791132149;11445.08439324852;11450.54197832094;11460.713482163013;11521.193516453768;5193.2989046479;5195.272661827347;5196.402413722395;5210.296609957683;5216.556592689664;4675.1351000064415;4710;4733.5503127202865;4742.07529531738;4745.881352966135;3972.5431575368625;4007.278689835799;4018.1963607278662;4041.3533834586324;4048.817803198863;1181.8699943006263;1183.5147589488267;1186.7329850783506;1189.756099999498;1194.9223300485442;1199.8260252263374;1212.2726364181422;1226.3868065967017;1226.3868065967017;1235.610782603477;8664.09027052163;8762.649494020296;8764.956175219084;8765.123487651184;8827.073157318517;451.3838610296921;456.0878243512974;460.6568106760485;466.7059752355994;466.82727390865597;6272.153259309935;6499.707513161919;6537.607743535403;6830.35111664401;6964.373206411346;12151.453184606715;12158;12293.385330733428;12498.437570309357;12510.624681259576;12114.030877529776;12296.786578415971;12329.198602090835;12352.567660131852;12446.751064978649;8109.959450202829;8127.471714338549;8138;8251.463654862415;8252.917470825216;8506.617202226038;8701.738947831574;8711.303095752452;8722.084181160886;8764.956175219084;10430.426326552113;10587.470626468492;10620.84068738954;10621.309614875008;10679.092277156218;10778.245522813328;10914.945425272823;10979.451027448755;11119.444027798416;11139.772045591077;8010.91989080125;8021.8395632088195;8155.510669359857;8163.959180204299;8191.221833925768;9085.409448385833;9142.680006199751;9157.31320150978;9171.082891711043;9201.125893040151;8064.717734879251;8288.83422331562;8337.541435221121;8396.488632046046;8436.578171091543;8084.555349455836;8251.95874020626;8255.545944973084;8267.255946964802;8392.16078392156;7444.143923448873;7728.111267204344;7739.690412383442;7748.728794492165;7767.990161279224;7132;7145.4640901931525;7202.171750248579;7220.025296584894;7250.057492525938;6454.548181627241;6581.6709164540625;7184.820379490555;7211.495195336276;7242.058532390755 22:47:51 INFO - 2015-10-23 22:47:51,384 DEBUG : BROWSER_OUTPUT: |4;prototype.html;321.42144140547026;321.9082561469981;325.0103435040301;329.79552677340035;331.3885880550384;5544;5671.517920976717;5740.971295143529;5759.424057594249;5781.82654520364;5533.889322213553;5602.635828671144;5635.042042852715;5663.348714897788;5716.056850619642;4265.616094551489;4312.417823593822;4324.502682191549;4334.653227741789;4335.934960975588;4291.656667466611;4304.548022457644;4333.804978775963;4337.089211265638;4350.54319296474;4161.854335098272;4215.135897141079;4246.087091275375;4255.340422234559;4292.806823692926;4257.276263035261;4288.356746488036;4291.313389857639;4295.162443323522;4295.377170310303;3716.1267102230954;3763.6236376362417;3773.019015056105;3784.791836448994;3788.1666033472593;5496.917546236809;5526.50261476469;5546.084895992157;5553.8333849984565;5554.333479982388;4792.544708252711;4872.512748725135;4874.366332376804;4878.170710979142;4886.53577910098;5790.507806836424;5809.7095145242565;5813.6802475863815;5820.30156381237;5820.35976042635;3981.243563722883;3990.142119444304;4033.737807042562;4039.091204478998;4044.2720310344125;6007.828473447679;6060.939390606081;6087;6117.632942023492;6120.571559990803;4163.333866581393;4188.623023927861;4191.434156388909;4192.3711443283755;4207.389928460356;4194.958050419517;4278.657707383401;4285.507166675872;4285.785710714452;4289.9571004289555;4810.725157833177;4859.854204373874;4898.461169271377;4900;4909.214525675875;5920.733566989496;5923.289205295306;5925.259342582177;5928.703564821742;5929.288485381781;1467.6330917270682;1514;1515.3559737111684;1515.628670975596;1516.438917600473;1014.5890914179769;1014.9035841595037;1022.0392830739081;1022.616518805448;1029.2435060230725;5834.445727655866;5850.473457388855;5872.148538461899;5875.206847075676;5878.000739874232;6080.908786368208;6099.542534309944;6102.664353460602;6106.816795496054;6111.052786818051;5758.568107391962;5778.7864548445295;5844.064949608042;5849;5904.498117660047;3333.283344081034;3343.682350176778;3359.983200083984;3375.038114137459;3382.8985130446126;6421.871562568723;6479.643619600967;6487.7080531376205;6489.383508566679;6493.058506516529;6040.0033994391315;6199.039148931923;6203.193584834033;6218.378162183836;6233.283172435137;6512.511561632897;6522.739090436329;6529.575577587537;6554.279029306773;6556.24603170632;6102.115193296859;6179.258488981351;6181.412765787243;6182.907256391068;6219.533534984804;5919.496842768241;6019.458248757633;6034.21555197839;6041.395860413923;6041.546883983631;6455.709493072729;6492.415682588401;6495.740170393131;6500.772472963424;6503.739850405931;4143.254214241466;4174.686898482687;4181.247375472322;4185.686073544557;4192.119654872511;6027.126066720213;6140.416660417253;6150;6156.753729850756;6158.661273629993;6078.088286757022;6083.969580152071;6091.177691011657;6091.573589848846;6094.603850749864;5996.430339117951;6043.607165534227;6047;6049.18336024631;6054.182685337423;5955.464008239279;5963.016102343065;5966.463018328372;5966.791162309299;5969.403059693996;5900.085486749561;5965.492933100735;5976.342602313655;5977.850553736192;5981.192539007178;5880.529557635294;5890.793822216202;5900.763969441175;5901.852453688693;5906.940930590811 22:47:51 INFO - 2015-10-23 22:47:51,387 DEBUG : BROWSER_OUTPUT: |5;yui.html;797.7487091566156;799.316584320406;803.835213781175;811.08347567249;826.1243082332726;13157.605271841849;13380.933095334534;13420;13426.26155561445;13450.461981520739;11492.310461372323;11495.540178392876;11553.075753939685;11590.304581725102;11614.535418583246;604.320139842678;614.1555361378105;716.9641517924109;727.3490226247505;731.2249016042985;728.9671964761571;729.212450553401;729.5731996781889;733.2594079979233;733.7101844771312;639.728115550889;648.063548172888;769.0886299734805;773.957432341221;774;755.5768769489076;765.490948519234;768.6003278295283;768.6272158003364;768.6809973860869;566.5722379603415;566.7789562070795;566.91496275559;567.0501909301944;567.2200724004493;12155.878441215564;12286.631401057983;12294.815577766341;12303.753924921451;12315.4458049388;11235.550577976872;11508.424578771112;11685.240459370114;11925.642230733092;12078.456469458895;9893.554790034394;9959.402435853872;10025.097741203253;10112.797744045078;10123.848142277871;3556.2531868307688;3559.715222782171;3562.590302115264;3565.180008598037;3571.6428357164336;38849.61150388488;39844.80077599594;40005.59994400048;41590.37614435786;41612.58387416117;6744.325567443217;6756.358145976124;6866.279040700754;6896.517243792987;6901.309869013058;10102.444365559964;10273.845892311621;10276.486175691185;10296.124829389437;10312.535935882903;6218.253809542884;6238.469730072995;6301.306856245809;6303.747850086037;6413.0701048348665;4042.717009809285;4096.774677392772;4141.565135660712;4186.204621121977;4210.536840947493;2627.881745321465;2636.3936294652335;2655.6282120503147;2658;2670.7462791034823;434.5024946436313;435.7494440696612;442.087090158821;448.28498544820957;451.5958217395413;650.1486620416254;677.3192941094268;682.1950098883266;689.8309914071085;692.4495026453981;8304.252617264476;8466.534340611326;8484.703035393732;8619.569021548898;8742.737717868475;627.5262177056385;645.9870802583922;656.8357910522369;690.6650274616807;691.0567075941304;4068.4507591475362;4078.571749966211;4102.938455923164;4211.7262377945335;4216.810243539048;54056.45943540593;55642.16536751952;55747.44252557504;55785;55870.441295587334;46744.5325546747;46903.530964690595;46925.530744692114;46939.76530117328;47087.76456117698;38726.419103713466;39261.214775704895;39330.60669393269;39358;39487.21025579472;8397.664093436439;8824.691135810215;8933.508657023665;8950.865737013819;9176.311776616914;28437.857810710815;28709.28226794347;28750.281242968256;28835;29428.852855735586;36703;37036.6296337044;37180.07049823776;39325.41011884767;39465.210695786496;7848.489848159851;7876.527408355517;7886.566238856918;7896.32881205104;8038.075621303625;12487.93756031214;12557.2412694618;12610.243385396905;12823.230606163286;12982.610521684364;12137.150399471951;12255;12276.447559860006;12387.380630968595;12690.936545317214;11657.475413606579;11875.643730687747;11967.10246731516;11997.76004479888;12198.390080495763;11002.229843910849;11193.328400296008;12042;12281.44733487013;12441.813372799596;10348.926648486047;10413.375197488174;10559;10601.41692206936;10733.677989660322;9734.805303894023;9877.308588398742;10054;10109.962088882761;10138.79722405533 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,807 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: __startTimestamp1445665671369__endTimestamp 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5193.70 Median:5447.96 stddev:699.83 (12.8%) stddev-sans-first:678.63 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: Values: 2908.8 2917.9 2922.0 2954.8 2961.1 5319.8 5361.1 5398.4 5402.9 5464.9 5405.1 5420.8 5439.9 5441.8 5444.2 4700.1 4759.8 4803.4 4832.2 4866.7 4835.6 4836.1 4837.2 4840.8 4845.1 4846.2 4847.9 4850.2 4851.9 4853.6 4773.6 4848.2 4856.6 4863.6 4902.5 4744.2 4746.2 4793.6 4793.8 4851.4 5259.9 5297.1 5307.4 5338.3 5339.1 5303.4 5467.1 5469.4 5565.3 5608.7 5589.8 5614.0 5618.3 5620.6 5641.4 5133.3 5149.9 5157.9 5167.7 5170.4 5616.7 5664.5 5685.6 5730.8 5736.4 5448.0 5483.6 5494.4 5500.3 5545.8 5553.4 5590.1 5596.5 5597.7 5599.5 5416.2 5473.9 5483.6 5526.7 5544.2 5580.0 5599.6 5604.9 5615.9 5617.6 3570.5 3593.8 3599.7 3637.7 3639.1 3202.2 3226.0 3265.1 3271.7 3310.5 5406.4 5419.4 5419.9 5420.9 5497.6 5624.0 5625.3 5627.9 5636.2 5691.6 5328.1 5344.3 5428.5 5429.2 5430.0 4544.6 4586.1 4587.2 4595.5 4645.6 5914.9 5986.0 5992.9 5997.2 5999.2 5459.6 5531.4 5554.7 5583.6 5633.3 5941.9 5984.9 6000.5 6004.1 6015.0 5482.4 5594.1 5596.5 5611.1 5613.4 5421.4 5511.1 5591.7 5623.4 5624.8 5511.7 5546.8 5574.4 5577.2 5589.4 5521.3 5530.0 5542.0 5547.4 5597.2 5323.6 5334.5 5403.3 5407.9 5447.3 5536.9 5543.7 5559.0 5585.0 5590.5 5443.9 5453.3 5461.9 5489.1 5499.8 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:53435.69 Median:353533.00 stddev:100030.90 (28.3%) stddev-sans-first:100232.71 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: Values: 553.9 585.9 589.1 590.7 597.0 32107.4 38786.8 39147.6 39301.2 39323.2 40645.8 40870.4 40894.8 41309.0 41327.0 8124.8 8381.2 8441.6 8447.1 8586.2 10287.8 10451.3 10493.4 10551.1 10566.1 10247.4 10281.9 10369.9 10378.1 10406.9 8399.7 8499.3 10190.7 10338.4 10679.8 10447.8 10599.0 10603.4 10605.4 10610.2 35956.6 36204.3 36256.6 36279.5 36447.8 3409.4 3494.6 3619.1 3731.7 3878.6 32907.8 33427.7 33811.3 33934.0 33988.7 3044.3 3133.8 3145.7 3180.5 3251.4 298287.0 299152.0 299261.5 299942.0 301482.0 51520.5 52808.2 55209.0 55452.7 55526.4 60338.0 60996.7 61533.4 61599.7 61612.4 13994.0 14046.9 14086.0 14095.9 14106.9 10987.9 11012.0 11012.2 11026.6 11031.2 18194.8 18224.7 18301.8 18306.5 18360.4 7762.2 7956.8 7969.2 8003.8 8039.6 939.9 946.1 947.7 972.2 977.8 28439.0 28553.3 28618.9 29005.6 31050.5 1010.2 1025.9 1029.3 1029.8 1094.0 14429.0 14503.5 14695.6 15953.4 16004.5 462158.0 465061.0 467376.0 467553.0 467867.0 53528.4 53811.5 53895.7 53936.7 53938.0 1211.6 1212.3 1235.3 1251.4 1286.9 25123.5 29953.7 30217.7 31769.4 31964.2 46341.3 46664.3 47084.3 47159.3 47339.8 345650.0 351942.0 353459.0 353607.0 355752.0 31843.0 42664.4 43370.9 43382.0 43977.8 43086.4 43530.3 43679.8 43902.0 44932.8 36734.4 37252.0 37618.6 37902.4 38161.3 30869.7 36270.3 36606.3 37525.8 37755.2 37329.3 37371.6 37505.1 37528.4 37563.2 30104.2 32844.7 32904.0 33649.5 33661.5 27019.3 27816.3 27820.9 27842.9 27858.9 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-10-23 22:47:51,388 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28386.10 Median:455.53 stddev:99277.95 (21793.7%) stddev-sans-first:99534.14 22:47:51 INFO - 2015-10-23 22:47:51,390 DEBUG : BROWSER_OUTPUT: Values: 362.1 363.4 368.4 370.2 376.2 14056.5 14150.0 15696.8 16322.7 16923.7 10689.4 11012.1 16070.5 16599.8 16601.7 4256.4 4375.6 4379.5 4382.4 4393.3 3522.4 3578.5 4314.7 4385.6 4781.0 4802.5 4816.3 4819.7 4824.7 4835.8 4553.4 4716.9 4801.6 4808.7 4824.8 4030.9 4078.6 4080.2 4086.2 4086.7 11739.1 11780.8 11860.7 11918.8 11955.3 2090.8 2101.2 2142.3 2165.2 2177.4 8868.2 9273.0 9334.6 9395.0 9432.2 1628.0 1639.9 1680.4 1718.9 1728.5 64067.0 64474.4 64647.4 64994.0 66089.0 6519.3 6583.8 6602.4 6609.7 6677.5 7714.2 7756.2 7767.8 7831.3 7871.3 5065.6 5087.9 5138.8 5148.6 5178.1 5266.9 5300.3 5301.7 5302.7 5316.6 2950.8 2958.2 2968.6 2986.7 2988.1 1079.8 1093.4 1102.1 1119.1 1134.6 385.4 389.9 397.5 448.9 451.4 9588.8 10186.7 10871.0 10987.5 11072.9 406.6 448.1 455.4 455.6 463.4 5784.8 5856.2 5874.4 5885.4 5941.5 593947.0 597775.0 601272.0 612620.0 621858.0 76527.2 78038.0 78088.3 78704.2 79098.2 493.9 495.4 498.2 498.6 498.8 7811.6 9993.6 10037.7 10134.5 10172.0 31387.0 31808.4 32973.5 33609.3 33919.5 43902.6 44444.6 44449.8 44456.8 44762.3 6232.3 6427.9 6445.7 6448.8 6454.2 10764.4 11087.1 11100.1 11133.8 11160.2 8540.0 8644.4 9456.6 9630.0 9683.1 9572.2 9727.3 9765.8 9878.0 9899.3 9897.1 10130.0 10167.1 10243.8 10254.4 6391.9 6466.0 6492.7 6494.3 6505.3 9852.5 9875.5 10038.8 10060.4 10124.4 22:47:51 INFO - 2015-10-23 22:47:51,391 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-10-23 22:47:51,391 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6434.23 Median:4586.92 stddev:3759.88 (82.0%) stddev-sans-first:3750.42 22:47:51 INFO - 2015-10-23 22:47:51,391 DEBUG : BROWSER_OUTPUT: Values: 1272.5 1395.9 1510.7 1567.3 1612.1 8085.4 8327.0 9204.3 9659.2 9673.0 8902.7 9283.1 9341.6 9352.0 9392.1 2345.7 2356.0 2371.3 2371.5 2376.4 2115.8 2151.8 2318.3 2373.8 2395.6 2282.9 2328.3 2337.0 2338.8 2340.9 2090.0 2315.2 2329.8 2329.9 2332.3 2067.1 2251.8 2271.6 2288.1 2288.2 9211.2 9331.3 9356.9 9374.4 9377.1 5214.9 5274.3 5288.0 5288.2 5290.8 3427.4 3448.9 3487.8 3490.5 3503.9 1060.0 1083.9 1089.2 1089.7 1122.1 14282.8 14439.8 14442.6 14497.3 14499.8 4427.1 4527.0 4543.7 4584.4 4589.4 11320.9 11445.1 11450.5 11460.7 11521.2 5193.3 5195.3 5196.4 5210.3 5216.6 4675.1 4710.0 4733.6 4742.1 4745.9 3972.5 4007.3 4018.2 4041.4 4048.8 1181.9 1183.5 1186.7 1189.8 1194.9 1199.8 1212.3 1226.4 1226.4 1235.6 8664.1 8762.6 8765.0 8765.1 8827.1 451.4 456.1 460.7 466.7 466.8 6272.2 6499.7 6537.6 6830.4 6964.4 12151.5 12158.0 12293.4 12498.4 12510.6 12114.0 12296.8 12329.2 12352.6 12446.8 8110.0 8127.5 8138.0 8251.5 8252.9 8506.6 8701.7 8711.3 8722.1 8765.0 10430.4 10587.5 10620.8 10621.3 10679.1 10778.2 10914.9 10979.5 11119.4 11139.8 8010.9 8021.8 8155.5 8164.0 8191.2 9085.4 9142.7 9157.3 9171.1 9201.1 8064.7 8288.8 8337.5 8396.5 8436.6 8084.6 8252.0 8255.5 8267.3 8392.2 7444.1 7728.1 7739.7 7748.7 7768.0 7132.0 7145.5 7202.2 7220.0 7250.1 6454.5 6581.7 7184.8 7211.5 7242.1 22:47:51 INFO - 2015-10-23 22:47:51,392 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-10-23 22:47:51,392 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4997.90 Median:5780.31 stddev:1515.84 (26.2%) stddev-sans-first:1478.90 22:47:51 INFO - 2015-10-23 22:47:51,392 DEBUG : BROWSER_OUTPUT: Values: 321.4 321.9 325.0 329.8 331.4 5544.0 5671.5 5741.0 5759.4 5781.8 5533.9 5602.6 5635.0 5663.3 5716.1 4265.6 4312.4 4324.5 4334.7 4335.9 4291.7 4304.5 4333.8 4337.1 4350.5 4161.9 4215.1 4246.1 4255.3 4292.8 4257.3 4288.4 4291.3 4295.2 4295.4 3716.1 3763.6 3773.0 3784.8 3788.2 5496.9 5526.5 5546.1 5553.8 5554.3 4792.5 4872.5 4874.4 4878.2 4886.5 5790.5 5809.7 5813.7 5820.3 5820.4 3981.2 3990.1 4033.7 4039.1 4044.3 6007.8 6060.9 6087.0 6117.6 6120.6 4163.3 4188.6 4191.4 4192.4 4207.4 4195.0 4278.7 4285.5 4285.8 4290.0 4810.7 4859.9 4898.5 4900.0 4909.2 5920.7 5923.3 5925.3 5928.7 5929.3 1467.6 1514.0 1515.4 1515.6 1516.4 1014.6 1014.9 1022.0 1022.6 1029.2 5834.4 5850.5 5872.1 5875.2 5878.0 6080.9 6099.5 6102.7 6106.8 6111.1 5758.6 5778.8 5844.1 5849.0 5904.5 3333.3 3343.7 3360.0 3375.0 3382.9 6421.9 6479.6 6487.7 6489.4 6493.1 6040.0 6199.0 6203.2 6218.4 6233.3 6512.5 6522.7 6529.6 6554.3 6556.2 6102.1 6179.3 6181.4 6182.9 6219.5 5919.5 6019.5 6034.2 6041.4 6041.5 6455.7 6492.4 6495.7 6500.8 6503.7 4143.3 4174.7 4181.2 4185.7 4192.1 6027.1 6140.4 6150.0 6156.8 6158.7 6078.1 6084.0 6091.2 6091.6 6094.6 5996.4 6043.6 6047.0 6049.2 6054.2 5955.5 5963.0 5966.5 5966.8 5969.4 5900.1 5965.5 5976.3 5977.9 5981.2 5880.5 5890.8 5900.8 5901.9 5906.9 22:47:51 INFO - 2015-10-23 22:47:51,394 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-10-23 22:47:51,394 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12392.23 Median:41601.48 stddev:14139.94 (34.0%) stddev-sans-first:14152.79 22:47:51 INFO - 2015-10-23 22:47:51,394 DEBUG : BROWSER_OUTPUT: Values: 797.7 799.3 803.8 811.1 826.1 13157.6 13380.9 13420.0 13426.3 13450.5 11492.3 11495.5 11553.1 11590.3 11614.5 604.3 614.2 717.0 727.3 731.2 729.0 729.2 729.6 733.3 733.7 639.7 648.1 769.1 774.0 774.0 755.6 765.5 768.6 768.6 768.7 566.6 566.8 566.9 567.1 567.2 12155.9 12286.6 12294.8 12303.8 12315.4 11235.6 11508.4 11685.2 11925.6 12078.5 9893.6 9959.4 10025.1 10112.8 10123.8 3556.3 3559.7 3562.6 3565.2 3571.6 38849.6 39844.8 40005.6 41590.4 41612.6 6744.3 6756.4 6866.3 6896.5 6901.3 10102.4 10273.8 10276.5 10296.1 10312.5 6218.3 6238.5 6301.3 6303.7 6413.1 4042.7 4096.8 4141.6 4186.2 4210.5 2627.9 2636.4 2655.6 2658.0 2670.7 434.5 435.7 442.1 448.3 451.6 650.1 677.3 682.2 689.8 692.4 8304.3 8466.5 8484.7 8619.6 8742.7 627.5 646.0 656.8 690.7 691.1 4068.5 4078.6 4102.9 4211.7 4216.8 54056.5 55642.2 55747.4 55785.0 55870.4 46744.5 46903.5 46925.5 46939.8 47087.8 38726.4 39261.2 39330.6 39358.0 39487.2 8397.7 8824.7 8933.5 8950.9 9176.3 28437.9 28709.3 28750.3 28835.0 29428.9 36703.0 37036.6 37180.1 39325.4 39465.2 7848.5 7876.5 7886.6 7896.3 8038.1 12487.9 12557.2 12610.2 12823.2 12982.6 12137.2 12255.0 12276.4 12387.4 12690.9 11657.5 11875.6 11967.1 11997.8 12198.4 11002.2 11193.3 12042.0 12281.4 12441.8 10348.9 10413.4 10559.0 10601.4 10733.7 9734.8 9877.3 10054.0 10110.0 10138.8 22:47:51 INFO - 2015-10-23 22:47:51,394 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:47:51 INFO - 2015-10-23 22:47:51,394 DEBUG : BROWSER_OUTPUT: 22:47:52 INFO - 2015-10-23 22:47:52,009 INFO : Browser exited with error code: 0 22:47:52 INFO - 2015-10-23 22:47:52,874 INFO : Completed test dromaeo_css (00:18:41) 22:47:52 INFO - 2015-10-23 22:47:52,874 INFO : Starting test kraken 22:47:52 INFO - 2015-10-23 22:47:52,874 DEBUG : operating with platform_type : w8_ 22:47:52 INFO - 2015-10-23 22:47:52,874 INFO : Initialising browser for kraken test... 22:47:52 INFO - 2015-10-23 22:47:52,894 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmputdkdk\profile http://localhost:49272/getInfo.html 22:47:58 INFO - 2015-10-23 22:47:58,572 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:47:58 INFO - 2015-10-23 22:47:58,572 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:47:58 INFO - 2015-10-23 22:47:58,572 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 22:47:58 INFO - 2015-10-23 22:47:58,572 DEBUG : BROWSER_OUTPUT: __metrics 22:47:58 INFO - 2015-10-23 22:47:58,792 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: console.error: 22:47:59 INFO - 2015-10-23 22:47:59,736 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] 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: Stack: 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:791:13 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 22:47:59 INFO - 2015-10-23 22:47:59,736 DEBUG : BROWSER_OUTPUT: 22:48:00 INFO - 2015-10-23 22:48:00,414 INFO : Browser initialized. 22:48:00 INFO - 2015-10-23 22:48:00,414 INFO : Running cycle 1/1 for kraken test... 22:48:00 INFO - 2015-10-23 22:48:00,414 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmputdkdk\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:48:11 INFO - 2015-10-23 22:48:11,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 187219968 22:48:11 INFO - 2015-10-23 22:48:11,772 DEBUG : BROWSER_OUTPUT: 22:48:14 INFO - 2015-10-23 22:48:14,403 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 22:48:14 INFO - 2015-10-23 22:48:14,990 DEBUG : BROWSER_OUTPUT: RSS: Main: 226791424 22:48:14 INFO - 2015-10-23 22:48:14,990 DEBUG : BROWSER_OUTPUT: 22:48:17 INFO - 2015-10-23 22:48:17,487 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 22:48:18 INFO - 2015-10-23 22:48:18,056 DEBUG : BROWSER_OUTPUT: RSS: Main: 227303424 22:48:18 INFO - 2015-10-23 22:48:18,056 DEBUG : BROWSER_OUTPUT: 22:48:20 INFO - 2015-10-23 22:48:20,671 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 22:48:21 INFO - 2015-10-23 22:48:21,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 210718720 22:48:21 INFO - 2015-10-23 22:48:21,253 DEBUG : BROWSER_OUTPUT: 22:48:23 INFO - 2015-10-23 22:48:23,171 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 22:48:23 INFO - 2015-10-23 22:48:23,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 213925888 22:48:23 INFO - 2015-10-23 22:48:23,743 DEBUG : BROWSER_OUTPUT: 22:48:25 INFO - 2015-10-23 22:48:25,815 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:48:26 INFO - 2015-10-23 22:48:26,378 DEBUG : BROWSER_OUTPUT: RSS: Main: 211435520 22:48:26 INFO - 2015-10-23 22:48:26,378 DEBUG : BROWSER_OUTPUT: 22:48:29 INFO - 2015-10-23 22:48:29,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 22:48:29 INFO - 2015-10-23 22:48:29,934 DEBUG : BROWSER_OUTPUT: RSS: Main: 205791232 22:48:29 INFO - 2015-10-23 22:48:29,934 DEBUG : BROWSER_OUTPUT: 22:48:33 INFO - 2015-10-23 22:48:33,227 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 22:48:33 INFO - 2015-10-23 22:48:33,799 DEBUG : BROWSER_OUTPUT: RSS: Main: 205496320 22:48:33 INFO - 2015-10-23 22:48:33,799 DEBUG : BROWSER_OUTPUT: 22:48:36 INFO - 2015-10-23 22:48:36,598 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 22:48:37 INFO - 2015-10-23 22:48:37,171 DEBUG : BROWSER_OUTPUT: RSS: Main: 204075008 22:48:37 INFO - 2015-10-23 22:48:37,171 DEBUG : BROWSER_OUTPUT: 22:48:38 INFO - 2015-10-23 22:48:38,891 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:48:39 INFO - 2015-10-23 22:48:39,457 DEBUG : BROWSER_OUTPUT: RSS: Main: 232882176 22:48:39 INFO - 2015-10-23 22:48:39,457 DEBUG : BROWSER_OUTPUT: 22:48:41 INFO - 2015-10-23 22:48:41,203 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:48:41 INFO - 2015-10-23 22:48:41,778 DEBUG : BROWSER_OUTPUT: RSS: Main: 237223936 22:48:41 INFO - 2015-10-23 22:48:41,778 DEBUG : BROWSER_OUTPUT: 22:48:43 INFO - 2015-10-23 22:48:43,915 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:48:44 INFO - 2015-10-23 22:48:44,486 DEBUG : BROWSER_OUTPUT: RSS: Main: 222130176 22:48:44 INFO - 2015-10-23 22:48:44,486 DEBUG : BROWSER_OUTPUT: 22:48:46 INFO - 2015-10-23 22:48:46,875 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:48:47 INFO - 2015-10-23 22:48:47,459 DEBUG : BROWSER_OUTPUT: RSS: Main: 225935360 22:48:47 INFO - 2015-10-23 22:48:47,459 DEBUG : BROWSER_OUTPUT: 22:48:50 INFO - 2015-10-23 22:48:50,059 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:48:50 INFO - 2015-10-23 22:48:50,624 DEBUG : BROWSER_OUTPUT: RSS: Main: 226455552 22:48:50 INFO - 2015-10-23 22:48:50,624 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,302 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 22:48:52 INFO - 2015-10-23 22:48:52,556 DEBUG : BROWSER_OUTPUT: RSS: Main: 281874432 22:48:52 INFO - 2015-10-23 22:48:52,556 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,556 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:48:52 INFO - 2015-10-23 22:48:52,556 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:48:52 INFO - 2015-10-23 22:48:52,556 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:48:52 INFO - 2015-10-23 22:48:52,556 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:48:52 INFO - 2015-10-23 22:48:52,558 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;103;109;110;109;99;109;109;111 22:48:52 INFO - 2015-10-23 22:48:52,558 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;126;121;143;151;124;129;130;125;149;123 22:48:52 INFO - 2015-10-23 22:48:52,558 DEBUG : BROWSER_OUTPUT: |2;audio-dft;141;140;141;147;139;140;140;143;139;139 22:48:52 INFO - 2015-10-23 22:48:52,558 DEBUG : BROWSER_OUTPUT: |3;audio-fft;69;70;70;89;68;69;71;69;99;66 22:48:52 INFO - 2015-10-23 22:48:52,558 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;86;86;107;86;87;86;114;86;86;114 22:48:52 INFO - 2015-10-23 22:48:52,558 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;112;114;110;110;111;110;110;111;110 22:48:52 INFO - 2015-10-23 22:48:52,558 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;132;132;132;131;132;131;132;132;132;133 22:48:52 INFO - 2015-10-23 22:48:52,558 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;97;95;98;105;95;96;97;96 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;73;73;73;73;73;77;73;84;72;71 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;65;65;65;64;64;65;67 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;87;89;87;87;90;86;87;92;87;88 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;114;115;114;119;120;119;113;119;122 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;154;143;153;153;154;145;156;153;153;143 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;68;68;68;68;69;67;69;68;69 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,940 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: __startTimestamp1445665732554__endTimestamp 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.60 Median:109.00 stddev:3.69 (3.4%) stddev-sans-first:3.91 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 103.0 109.0 110.0 109.0 99.0 109.0 109.0 111.0 22:48:52 INFO - 2015-10-23 22:48:52,559 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:132.10 Median:129.50 stddev:11.23 (8.7%) stddev-sans-first:11.69 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: Values: 126.0 121.0 143.0 151.0 124.0 129.0 130.0 125.0 149.0 123.0 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:140.90 Median:140.50 stddev:2.47 (1.8%) stddev-sans-first:2.62 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: Values: 141.0 140.0 141.0 147.0 139.0 140.0 140.0 143.0 139.0 139.0 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:74.00 Median:70.00 stddev:10.88 (15.5%) stddev-sans-first:11.39 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 70.0 89.0 68.0 69.0 71.0 69.0 99.0 66.0 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:93.80 Median:86.00 stddev:12.48 (14.5%) stddev-sans-first:12.91 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: Values: 86.0 86.0 107.0 86.0 87.0 86.0 114.0 86.0 86.0 114.0 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.80 Median:110.50 stddev:1.32 (1.2%) stddev-sans-first:1.36 22:48:52 INFO - 2015-10-23 22:48:52,561 DEBUG : BROWSER_OUTPUT: Values: 110.0 112.0 114.0 110.0 110.0 111.0 110.0 110.0 111.0 110.0 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:131.90 Median:132.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: Values: 132.0 132.0 132.0 131.0 132.0 131.0 132.0 132.0 132.0 133.0 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:97.10 Median:96.00 stddev:2.92 (3.0%) stddev-sans-first:3.07 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 97.0 95.0 98.0 105.0 95.0 96.0 97.0 96.0 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.20 Median:73.00 stddev:3.77 (5.2%) stddev-sans-first:3.97 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 73.0 73.0 73.0 77.0 73.0 84.0 72.0 71.0 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.95 (1.5%) stddev-sans-first:0.97 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 65.0 65.0 65.0 64.0 64.0 65.0 67.0 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:87.50 stddev:1.83 (2.1%) stddev-sans-first:1.90 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: Values: 87.0 89.0 87.0 87.0 90.0 86.0 87.0 92.0 87.0 88.0 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.30 Median:119.00 stddev:3.06 (2.6%) stddev-sans-first:3.23 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: Values: 118.0 114.0 115.0 114.0 119.0 120.0 119.0 113.0 119.0 122.0 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.70 Median:153.00 stddev:4.97 (3.2%) stddev-sans-first:5.12 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: Values: 154.0 143.0 153.0 153.0 154.0 145.0 156.0 153.0 153.0 143.0 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 22:48:52 INFO - 2015-10-23 22:48:52,562 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 68.0 68.0 68.0 69.0 67.0 69.0 68.0 69.0 22:48:52 INFO - 2015-10-23 22:48:52,563 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:48:52 INFO - 2015-10-23 22:48:52,563 DEBUG : BROWSER_OUTPUT: 22:48:53 INFO - 2015-10-23 22:48:53,249 INFO : Browser exited with error code: 0 22:48:54 INFO - 2015-10-23 22:48:54,312 INFO : Completed test kraken (00:01:01) 22:48:54 INFO - 2015-10-23 22:48:54,312 INFO : Starting test v8_7 22:48:54 INFO - 2015-10-23 22:48:54,312 DEBUG : operating with platform_type : w8_ 22:48:54 INFO - 2015-10-23 22:48:54,312 INFO : Initialising browser for v8_7 test... 22:48:54 INFO - 2015-10-23 22:48:54,332 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpyvxozt\profile http://localhost:49272/getInfo.html 22:48:59 INFO - 2015-10-23 22:48:59,894 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:48:59 INFO - 2015-10-23 22:48:59,894 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:48:59 INFO - 2015-10-23 22:48:59,894 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 22:48:59 INFO - 2015-10-23 22:48:59,894 DEBUG : BROWSER_OUTPUT: __metrics 22:49:00 INFO - 2015-10-23 22:49:00,186 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:49:01 INFO - 2015-10-23 22:49:01,086 DEBUG : BROWSER_OUTPUT: console.error: 22:49:01 INFO - 2015-10-23 22:49:01,088 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] 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: Stack: 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 22:49:01 INFO - 2015-10-23 22:49:01,088 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 22:49:01 INFO - 2015-10-23 22:49:01,089 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 22:49:01 INFO - 2015-10-23 22:49:01,089 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6604.06 22:49:37 INFO - RETURN: kraken: 1451.20 22:49:37 INFO - RETURN: v8_7: 19785.32 22:49:37 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,31]]", "result": "19785.32"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,31]]", "result": "1451.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,31]]", "result": "6604.06"}}} 22:49:37 INFO - Return code: 0 22:49:37 INFO - # TBPL SUCCESS # 22:49:37 INFO - Running post-action listener: _resource_record_post_action 22:49:37 INFO - Running post-run listener: _resource_record_post_run 22:49:38 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 102226944; Write bytes: 384976384; Read time: 1411490; Write time: 4473780 22:49:38 INFO - install - Wall time: 3s; CPU: 8.0%; Read bytes: 0; Write bytes: 28203008; Read time: 0; Write time: 87790 22:49:38 INFO - run-tests - Wall time: 1227s; CPU: 13.0%; Read bytes: 102226944; Write bytes: 336140288; Read time: 1411490; Write time: 4300930 22:49:38 INFO - Running post-run listener: _upload_blobber_files 22:49:38 INFO - Blob upload gear active. 22:49:38 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:49:38 INFO - Copying logs to upload dir... 22:49:38 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1436.114000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 2 secs) (at 2015-10-23 22:49:44.357084) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 22:49:44.360601) ========= '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-030\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-030 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-030 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-030\AppData\Local LOGONSERVER=\\T-W864-IX-030 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-030 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-030 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-030 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False build_url:https://queue.taskcluster.net/v1/task/vzNZR8ncSJWPTT0gfGWaOQ/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/vzNZR8ncSJWPTT0gfGWaOQ/artifacts/public/build/firefox-44.0a1.en-US.win64.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 22:49:44.500427) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 22:49:44.500764) ========= '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-030\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-030 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-030 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-030\AppData\Local LOGONSERVER=\\T-W864-IX-030 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-030 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-030 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-030 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-23 22:49:44.616933) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-23 22:49:44.617257) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-23 22:49:44.929959) =========