builder: fx-team_win8_64_test-dromaeojs-pgo slave: t-w864-ix-140 starttime: 1445578952.28 results: success (0) buildid: 20151022173021 builduid: d49e6285f48b401c8330900f9a64e50a revision: 935d934a5f436ac40098d836604348042bcc14b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:32.277164) ========= master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:32.279688) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:32.279983) ========= '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-140\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-140 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-140 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-140\AppData\Local LOGONSERVER=\\T-W864-IX-140 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-140 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-140 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-140 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False C:\slave\test-pgo program finished with exit code 0 elapsedTime=0.745000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:33.087766) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:33.088077) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:33.376651) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:33.377048) ========= '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-140\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-140 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-140 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-140\AppData\Local LOGONSERVER=\\T-W864-IX-140 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-140 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-140 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-140 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.302000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:33.722426) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:33.722727) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:33.723138) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-22 22:42:33.723507) ========= '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-140\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-140 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-140 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-140\AppData\Local LOGONSERVER=\\T-W864-IX-140 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-140 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-140 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-140 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False --22:42:34-- 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% 11.61 MB/s 22:42:35 (11.61 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.132000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-22 22:42:34.906896) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-22 22:42:34.907323) ========= '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-140\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-140 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-140 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-140\AppData\Local LOGONSERVER=\\T-W864-IX-140 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-140 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-140 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-140 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=2.503000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-22 22:42:37.462483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-22 22:42:37.463489) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 935d934a5f436ac40098d836604348042bcc14b5 --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 935d934a5f436ac40098d836604348042bcc14b5 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-140\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-140 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-140 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-140\AppData\Local LOGONSERVER=\\T-W864-IX-140 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-140 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-140 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-140 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-22 22:42:38,828 truncating revision to first 12 chars 2015-10-22 22:42:38,828 Setting DEBUG logging. 2015-10-22 22:42:38,828 attempt 1/10 2015-10-22 22:42:38,828 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/935d934a5f43?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-22 22:42:39,371 unpacking tar archive at: fx-team-935d934a5f43/testing/mozharness/ program finished with exit code 0 elapsedTime=1.195000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-22 22:42:38.823548) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:38.823883) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:38.960917) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:38.961308) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 22:42:38.961783) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 44 secs) (at 2015-10-22 22:42:38.962086) ========= '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-140\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-140 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-140 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-140\AppData\Local LOGONSERVER=\\T-W864-IX-140 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-140 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-140 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-140 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 22:42:40 INFO - MultiFileLogger online at 20151022 22:42:40 in C:\slave\test-pgo 22:42:40 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:42:40 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 22:42:40 INFO - {'append_to_log': False, 22:42:40 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 22:42:40 INFO - 'blob_upload_branch': 'Fx-Team', 22:42:40 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 22:42:40 INFO - 'branch': 'Fx-Team', 22:42:40 INFO - 'buildbot_json_path': 'buildprops.json', 22:42:40 INFO - 'config_files': ('talos/windows_config.py',), 22:42:40 INFO - 'default_actions': ('clobber', 22:42:40 INFO - 'read-buildbot-config', 22:42:40 INFO - 'download-and-extract', 22:42:40 INFO - 'populate-webroot', 22:42:40 INFO - 'create-virtualenv', 22:42:40 INFO - 'install', 22:42:40 INFO - 'run-tests'), 22:42:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:42:40 INFO - 'download_minidump_stackwalk': True, 22:42:40 INFO - 'download_symbols': 'ondemand', 22:42:40 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 22:42:40 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 22:42:40 INFO - 'hg': 'c:/mozilla-build/hg/hg', 22:42:40 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 22:42:40 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 22:42:40 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 22:42:40 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 22:42:40 INFO - 'C:/mozilla-build/tooltool.py'), 22:42:40 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 22:42:40 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 22:42:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:42:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:42:40 INFO - 'installer_path': 'installer.exe', 22:42:40 INFO - 'log_level': 'info', 22:42:40 INFO - 'log_name': 'talos', 22:42:40 INFO - 'log_to_console': True, 22:42:40 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 22:42:40 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 22:42:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 22:42:40 INFO - 'opt_config_files': (), 22:42:40 INFO - 'pip_index': False, 22:42:40 INFO - 'sps_profile': False, 22:42:40 INFO - 'sps_profile_interval': 0, 22:42:40 INFO - 'suite': 'dromaeojs', 22:42:40 INFO - 'system_bits': '64', 22:42:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:42:40 INFO - 'title': 'T-W864-IX-140', 22:42:40 INFO - 'use_talos_json': True, 22:42:40 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 22:42:40 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 22:42:40 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 22:42:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:42:40 INFO - 'work_dir': 'build'} 22:42:40 INFO - ##### 22:42:40 INFO - ##### Running clobber step. 22:42:40 INFO - ##### 22:42:40 INFO - Running pre-action listener: _resource_record_pre_action 22:42:40 INFO - Running main action method: clobber 22:42:40 INFO - rmtree: C:\slave\test-pgo\build 22:42:40 INFO - Using _rmtree_windows ... 22:42:40 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 22:43:50 INFO - Running post-action listener: _resource_record_post_action 22:43:50 INFO - ##### 22:43:50 INFO - ##### Running read-buildbot-config step. 22:43:50 INFO - ##### 22:43:50 INFO - Running pre-action listener: _resource_record_pre_action 22:43:50 INFO - Running main action method: read_buildbot_config 22:43:50 INFO - Using buildbot properties: 22:43:50 INFO - { 22:43:50 INFO - "properties": { 22:43:50 INFO - "buildnumber": 246, 22:43:50 INFO - "product": "firefox", 22:43:50 INFO - "script_repo_revision": "production", 22:43:50 INFO - "builddir": "fx-team_win8_64_test-dromaeojs", 22:43:50 INFO - "repository": "", 22:43:50 INFO - "buildername": "Windows 8 64-bit fx-team pgo talos dromaeojs", 22:43:50 INFO - "buildid": "20151022173021", 22:43:50 INFO - "slavename": "t-w864-ix-140", 22:43:50 INFO - "pgo_build": "True", 22:43:50 INFO - "basedir": "C:\\slave\\test-pgo", 22:43:50 INFO - "project": "", 22:43:50 INFO - "platform": "win8_64", 22:43:50 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/", 22:43:50 INFO - "slavebuilddir": "test", 22:43:50 INFO - "scheduler": "tests-fx-team-win64-pgo-talos", 22:43:50 INFO - "branch": "fx-team", 22:43:50 INFO - "repo_path": "integration/fx-team", 22:43:50 INFO - "stage_platform": "win64-pgo", 22:43:50 INFO - "builduid": "d49e6285f48b401c8330900f9a64e50a", 22:43:50 INFO - "revision": "935d934a5f436ac40098d836604348042bcc14b5" 22:43:50 INFO - }, 22:43:50 INFO - "sourcestamp": { 22:43:50 INFO - "repository": "", 22:43:50 INFO - "hasPatch": false, 22:43:50 INFO - "project": "", 22:43:50 INFO - "branch": "fx-team-win64-pgo-talos", 22:43:50 INFO - "changes": [ 22:43:50 INFO - { 22:43:50 INFO - "category": null, 22:43:50 INFO - "files": [ 22:43:50 INFO - { 22:43:50 INFO - "url": null, 22:43:50 INFO - "name": "https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip" 22:43:50 INFO - } 22:43:50 INFO - ], 22:43:50 INFO - "repository": "", 22:43:50 INFO - "rev": "935d934a5f436ac40098d836604348042bcc14b5", 22:43:50 INFO - "who": "sendchange", 22:43:50 INFO - "when": 1445578789, 22:43:50 INFO - "number": 6563922, 22:43:50 INFO - "comments": "", 22:43:50 INFO - "project": "", 22:43:50 INFO - "at": "Thu 22 Oct 2015 22:39:49", 22:43:50 INFO - "branch": "fx-team-win64-pgo-talos", 22:43:50 INFO - "revlink": "", 22:43:50 INFO - "properties": [ 22:43:50 INFO - [ 22:43:50 INFO - "buildid", 22:43:50 INFO - "20151022173021", 22:43:50 INFO - "Change" 22:43:50 INFO - ], 22:43:50 INFO - [ 22:43:50 INFO - "builduid", 22:43:50 INFO - "d49e6285f48b401c8330900f9a64e50a", 22:43:50 INFO - "Change" 22:43:50 INFO - ], 22:43:50 INFO - [ 22:43:50 INFO - "pgo_build", 22:43:50 INFO - "True", 22:43:50 INFO - "Change" 22:43:50 INFO - ] 22:43:50 INFO - ], 22:43:50 INFO - "revision": "935d934a5f436ac40098d836604348042bcc14b5" 22:43:50 INFO - } 22:43:50 INFO - ], 22:43:50 INFO - "revision": "935d934a5f436ac40098d836604348042bcc14b5" 22:43:50 INFO - } 22:43:50 INFO - } 22:43:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip. 22:43:50 INFO - Running post-action listener: _resource_record_post_action 22:43:50 INFO - ##### 22:43:50 INFO - ##### Running download-and-extract step. 22:43:50 INFO - ##### 22:43:50 INFO - Running pre-action listener: _resource_record_pre_action 22:43:50 INFO - Running main action method: download_and_extract 22:43:50 INFO - mkdir: C:\slave\test-pgo\build\tests 22:43: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:43:50 INFO - https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:43:50 INFO - trying https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/test_packages.json 22:43:50 INFO - Downloading https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 22:43:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 22:43:51 INFO - Downloaded 1221 bytes. 22:43:51 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 22:43:51 INFO - Using the following test package requirements: 22:43:51 INFO - {u'common': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 22:43:51 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:43:51 INFO - u'firefox-44.0a1.en-US.win64.cppunittest.tests.zip'], 22:43:51 INFO - u'jittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:43:51 INFO - u'jsshell-win64.zip'], 22:43:51 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:43:51 INFO - u'firefox-44.0a1.en-US.win64.mochitest.tests.zip'], 22:43:51 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 22:43:51 INFO - u'reftest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:43:51 INFO - u'firefox-44.0a1.en-US.win64.reftest.tests.zip'], 22:43:51 INFO - u'talos': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:43:51 INFO - u'firefox-44.0a1.en-US.win64.talos.tests.zip'], 22:43:51 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:43:51 INFO - u'firefox-44.0a1.en-US.win64.web-platform.tests.zip'], 22:43:51 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 22:43:51 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 22:43:51 INFO - u'firefox-44.0a1.en-US.win64.xpcshell.tests.zip']} 22:43:51 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win64.common.tests.zip'] for test suite category: common 22:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:51 INFO - https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 22:43:51 INFO - trying https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip 22:43:51 INFO - Downloading https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/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:43:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/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:43:52 INFO - Downloaded 18096388 bytes. 22:43:52 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:43:52 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.common.tests.zip 22:43:52 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:44:25 INFO - Return code: 0 22:44:25 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:44:25 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:44:25 INFO - https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 22:44:25 INFO - trying https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip 22:44:25 INFO - Downloading https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/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:44:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/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:44:27 INFO - Downloaded 18096388 bytes. 22:44:27 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:44:27 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.common.tests.zip 22:44:27 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:44:49 INFO - Return code: 0 22:44: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:44:49 INFO - https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip matches https://queue.taskcluster.net 22:44:49 INFO - trying https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip 22:44:49 INFO - Downloading https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/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:44:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/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:44:51 INFO - Downloaded 11131307 bytes. 22:44:51 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:44:51 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.talos.tests.zip 22:44:51 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:44:52 INFO - Return code: 0 22:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:52 INFO - https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip matches https://queue.taskcluster.net 22:44:52 INFO - trying https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 22:44:52 INFO - Downloading https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.zip 22:44:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/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:44:57 INFO - Downloaded 55531719 bytes. 22:44:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 22:44:57 INFO - mkdir: C:\slave\test-pgo\properties 22:44:57 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 22:44:57 INFO - Writing to file C:\slave\test-pgo\properties\build_url 22:44:57 INFO - Contents: 22:44:57 INFO - build_url:https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 22:44:57 INFO - Running post-action listener: _resource_record_post_action 22:44:57 INFO - Running post-action listener: set_extra_try_arguments 22:44:57 INFO - ##### 22:44:57 INFO - ##### Running populate-webroot step. 22:44:57 INFO - ##### 22:44:57 INFO - Running pre-action listener: _resource_record_pre_action 22:44:57 INFO - Running main action method: populate_webroot 22:44:57 INFO - Guessing talos json url... 22:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:57 INFO - https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt matches https://queue.taskcluster.net 22:44:57 INFO - trying https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt 22:44:57 INFO - Downloading https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.txt 22:44:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/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:44:58 INFO - Downloaded 103 bytes. 22:44:58 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win64.txt 22:44:58 INFO - Contents: 22:44:58 INFO - 20151022173021 22:44:58 INFO - https://hg.mozilla.org/integration/fx-team/rev/935d934a5f436ac40098d836604348042bcc14b5 22:44:58 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:44:58 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/935d934a5f/testing/talos/talos.json 22:44:58 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/935d934a5f/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 22:44:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/935d934a5f/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 22:44:59 INFO - Downloaded 4264 bytes. 22:44:59 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 22:44:59 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 22:44:59 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 22:44:59 INFO - u'../fennec_ids.txt'], 22:44:59 INFO - u'tests': [u'tcheck2']}, 22:44:59 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 22:44:59 INFO - u'--tppagecycles', 22:44:59 INFO - u'7'], 22:44:59 INFO - u'tests': [u'tsvgm']}}, 22:44:59 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 22:44:59 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 22:44:59 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 22:44:59 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 22:44:59 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 22:44:59 INFO - u'tests': [u'dromaeo_css', 22:44:59 INFO - u'kraken', 22:44:59 INFO - u'v8_7']}, 22:44:59 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 22:44:59 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:59 INFO - u'talos_options': [u'--e10s'], 22:44:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 22:44:59 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:59 INFO - u'tests': [u'damp', u'tps']}, 22:44:59 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:59 INFO - u'talos_options': [u'--e10s'], 22:44:59 INFO - u'tests': [u'damp', u'tps']}, 22:44:59 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 22:44:59 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 22:44:59 INFO - u'other': {u'tests': [u'a11yr', 22:44:59 INFO - u'ts_paint', 22:44:59 INFO - u'tpaint', 22:44:59 INFO - u'sessionrestore', 22:44:59 INFO - u'sessionrestore_no_auto_restore']}, 22:44:59 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 22:44:59 INFO - u'tests': [u'a11yr', 22:44:59 INFO - u'ts_paint', 22:44:59 INFO - u'tpaint', 22:44:59 INFO - u'sessionrestore', 22:44:59 INFO - u'sessionrestore_no_auto_restore']}, 22:44:59 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 22:44:59 INFO - u'tests': [u'a11yr', 22:44:59 INFO - u'ts_paint', 22:44:59 INFO - u'tpaint', 22:44:59 INFO - u'sessionrestore', 22:44:59 INFO - u'sessionrestore_no_auto_restore']}, 22:44:59 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 22:44:59 INFO - u'tests': [u'a11yr', 22:44:59 INFO - u'ts_paint', 22:44:59 INFO - u'tpaint', 22:44:59 INFO - u'sessionrestore', 22:44:59 INFO - u'sessionrestore_no_auto_restore']}, 22:44:59 INFO - u'other_l64': {u'tests': [u'a11yr', 22:44:59 INFO - u'ts_paint', 22:44:59 INFO - u'tpaint', 22:44:59 INFO - u'sessionrestore', 22:44:59 INFO - u'sessionrestore_no_auto_restore']}, 22:44:59 INFO - u'other_nol64': {u'tests': [u'a11yr', 22:44:59 INFO - u'ts_paint', 22:44:59 INFO - u'tpaint', 22:44:59 INFO - u'sessionrestore', 22:44:59 INFO - u'sessionrestore_no_auto_restore']}, 22:44:59 INFO - u'svgr': {u'tests': [u'tsvgx', 22:44:59 INFO - u'tsvgr_opacity', 22:44:59 INFO - u'tart', 22:44:59 INFO - u'tscrollx', 22:44:59 INFO - u'cart']}, 22:44:59 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 22:44:59 INFO - u'tests': [u'tsvgx', 22:44:59 INFO - u'tsvgr_opacity', 22:44:59 INFO - u'tart', 22:44:59 INFO - u'tscrollx', 22:44:59 INFO - u'cart']}, 22:44:59 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:59 INFO - u'tests': [u'tp5o']}, 22:44:59 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:59 INFO - u'talos_options': [u'--e10s'], 22:44:59 INFO - u'tests': [u'tp5o']}, 22:44:59 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:59 INFO - u'talos_options': [u'--xperf_path', 22:44:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:44:59 INFO - u'tests': [u'tp5n']}, 22:44:59 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:59 INFO - u'talos_options': [u'--e10s', 22:44:59 INFO - u'--xperf_path', 22:44:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 22:44:59 INFO - u'C:/slave/talos-data/talos/xperf.config'], 22:44:59 INFO - u'tests': [u'tp5n']}}, 22:44:59 INFO - u'talos.zip': {u'path': u'', 22:44:59 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:44:59 INFO - Running post-action listener: _resource_record_post_action 22:44:59 INFO - ##### 22:44:59 INFO - ##### Running create-virtualenv step. 22:44:59 INFO - ##### 22:44:59 INFO - Running pre-action listener: _resource_record_pre_action 22:44:59 INFO - Running main action method: create_virtualenv 22:44:59 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 22:44:59 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 22:44:59 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 22:44:59 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:44:59 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:44:59 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:45:02 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 22:45:05 INFO - Installing distribute......................................................................................................................................................................................done. 22:45:06 INFO - Return code: 0 22:45:06 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 22:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45: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', '--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 0x02484B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02440EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x007F75C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00738B48>, '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 0x0075A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02864660>, '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-140', 'USERDOMAIN': 'T-W864-IX-140', '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-140', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-140', '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-140\\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:45:07 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:45:07 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:45:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:07 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Roaming', 22:45:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:45:07 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:45:07 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:45:07 INFO - 'COMPUTERNAME': 'T-W864-IX-140', 22:45:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:07 INFO - 'DCLOCATION': 'SCL3', 22:45:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:07 INFO - 'HOMEDRIVE': 'C:', 22:45:07 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 22:45:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:07 INFO - 'KTS_VERSION': '1.19c', 22:45:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 22:45:07 INFO - 'LOGONSERVER': '\\\\T-W864-IX-140', 22:45:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:07 INFO - 'MOZ_NO_REMOTE': '1', 22:45:07 INFO - 'NO_EM_RESTART': '1', 22:45:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:07 INFO - 'OS': 'Windows_NT', 22:45:07 INFO - 'OURDRIVE': 'C:', 22:45: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:45:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:07 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:45:07 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:07 INFO - 'PROCESSOR_LEVEL': '6', 22:45:07 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:07 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:45:07 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:45:07 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:45:07 INFO - 'PROMPT': '$P$G', 22:45:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:07 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:07 INFO - 'SYSTEMDRIVE': 'C:', 22:45:07 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:07 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:07 INFO - 'TEST1': 'testie', 22:45:07 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:07 INFO - 'USERDOMAIN': 'T-W864-IX-140', 22:45:07 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 22:45:07 INFO - 'USERNAME': 'cltbld', 22:45:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-140', 22:45:07 INFO - 'WINDIR': 'C:\\windows', 22:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:08 INFO - Ignoring indexes: http://pypi.python.org/simple/ 22:45:08 INFO - Downloading/unpacking pip>=1.5 22:45:08 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 22:45:13 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:45:13 INFO - Running setup.py egg_info for package pip 22:45:13 INFO - warning: no files found matching 'pip\cacert.pem' 22:45:13 INFO - warning: no files found matching '*.html' under directory 'docs' 22:45:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 22:45:13 INFO - no previously-included directories found matching 'docs\_build\_sources' 22:45:13 INFO - Installing collected packages: pip 22:45:13 INFO - Found existing installation: pip 0.8.2 22:45:13 INFO - Uninstalling pip: 22:45:13 INFO - Successfully uninstalled pip 22:45:13 INFO - Running setup.py install for pip 22:45:13 INFO - warning: no files found matching 'pip\cacert.pem' 22:45:13 INFO - warning: no files found matching '*.html' under directory 'docs' 22:45:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 22:45:13 INFO - no previously-included directories found matching 'docs\_build\_sources' 22:45:13 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:13 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:13 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:13 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:13 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:13 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:13 INFO - Successfully installed pip 22:45:13 INFO - Cleaning up... 22:45:13 INFO - Return code: 0 22:45:13 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 22:45:13 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:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:13 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:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:13 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 0x02484B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02440EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x007F75C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00738B48>, '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 0x0075A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02864660>, '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-140', 'USERDOMAIN': 'T-W864-IX-140', '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-140', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-140', '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-140\\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:45:13 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:45:13 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:45:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:13 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Roaming', 22:45:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:45:13 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:45:13 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:45:13 INFO - 'COMPUTERNAME': 'T-W864-IX-140', 22:45:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:13 INFO - 'DCLOCATION': 'SCL3', 22:45:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:13 INFO - 'HOMEDRIVE': 'C:', 22:45:13 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 22:45:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:13 INFO - 'KTS_VERSION': '1.19c', 22:45:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 22:45:13 INFO - 'LOGONSERVER': '\\\\T-W864-IX-140', 22:45:13 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:13 INFO - 'MOZ_NO_REMOTE': '1', 22:45:13 INFO - 'NO_EM_RESTART': '1', 22:45:13 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:13 INFO - 'OS': 'Windows_NT', 22:45:13 INFO - 'OURDRIVE': 'C:', 22:45:13 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:45:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:13 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:45:13 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:13 INFO - 'PROCESSOR_LEVEL': '6', 22:45:13 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:13 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:45:13 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:45:13 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:45:13 INFO - 'PROMPT': '$P$G', 22:45:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:13 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:13 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:13 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:13 INFO - 'SYSTEMDRIVE': 'C:', 22:45:13 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:13 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:13 INFO - 'TEST1': 'testie', 22:45:13 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:13 INFO - 'USERDOMAIN': 'T-W864-IX-140', 22:45:13 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 22:45:13 INFO - 'USERNAME': 'cltbld', 22:45:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-140', 22:45:13 INFO - 'WINDIR': 'C:\\windows', 22:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:14 INFO - Downloading/unpacking psutil>=0.7.1 22:45:14 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:45:14 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:45:17 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:45:17 INFO - Installing collected packages: psutil 22:45:17 INFO - Successfully installed psutil 22:45:17 INFO - Cleaning up... 22:45:17 INFO - Return code: 0 22:45:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 22:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:17 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 0x02484B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02440EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x007F75C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00738B48>, '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 0x0075A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02864660>, '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-140', 'USERDOMAIN': 'T-W864-IX-140', '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-140', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-140', '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-140\\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:45:17 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:45:17 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:45:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:17 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Roaming', 22:45:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:45:17 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:45:17 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:45:17 INFO - 'COMPUTERNAME': 'T-W864-IX-140', 22:45:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:17 INFO - 'DCLOCATION': 'SCL3', 22:45:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:17 INFO - 'HOMEDRIVE': 'C:', 22:45:17 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 22:45:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:17 INFO - 'KTS_VERSION': '1.19c', 22:45:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 22:45:17 INFO - 'LOGONSERVER': '\\\\T-W864-IX-140', 22:45:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:17 INFO - 'MOZ_NO_REMOTE': '1', 22:45:17 INFO - 'NO_EM_RESTART': '1', 22:45:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:17 INFO - 'OS': 'Windows_NT', 22:45:17 INFO - 'OURDRIVE': 'C:', 22:45:17 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 22:45:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:17 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:45:17 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:17 INFO - 'PROCESSOR_LEVEL': '6', 22:45:17 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:17 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:45:17 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:45:17 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:45:17 INFO - 'PROMPT': '$P$G', 22:45:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:17 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:17 INFO - 'SYSTEMDRIVE': 'C:', 22:45:17 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:17 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:17 INFO - 'TEST1': 'testie', 22:45:17 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:17 INFO - 'USERDOMAIN': 'T-W864-IX-140', 22:45:17 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 22:45:17 INFO - 'USERNAME': 'cltbld', 22:45:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-140', 22:45:17 INFO - 'WINDIR': 'C:\\windows', 22:45:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:45:19 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:45:19 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 22:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 22:45:19 INFO - Installing collected packages: mozsystemmonitor 22:45:19 INFO - Running setup.py install for mozsystemmonitor 22:45:20 INFO - Successfully installed mozsystemmonitor 22:45:20 INFO - Cleaning up... 22:45:20 INFO - Return code: 0 22:45:20 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 22:45:20 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:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:20 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:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:20 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 0x02484B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02440EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x007F75C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00738B48>, '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 0x0075A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02864660>, '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-140', 'USERDOMAIN': 'T-W864-IX-140', '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-140', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-140', '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-140\\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:45:20 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:45:20 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:45:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:20 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Roaming', 22:45:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:45:20 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:45:20 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:45:20 INFO - 'COMPUTERNAME': 'T-W864-IX-140', 22:45:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:20 INFO - 'DCLOCATION': 'SCL3', 22:45:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:20 INFO - 'HOMEDRIVE': 'C:', 22:45:20 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 22:45:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:20 INFO - 'KTS_VERSION': '1.19c', 22:45:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 22:45:20 INFO - 'LOGONSERVER': '\\\\T-W864-IX-140', 22:45:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:20 INFO - 'MOZ_NO_REMOTE': '1', 22:45:20 INFO - 'NO_EM_RESTART': '1', 22:45:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:20 INFO - 'OS': 'Windows_NT', 22:45:20 INFO - 'OURDRIVE': 'C:', 22:45:20 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:45:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:20 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:45:20 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:20 INFO - 'PROCESSOR_LEVEL': '6', 22:45:20 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:20 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:45:20 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:45:20 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:45:20 INFO - 'PROMPT': '$P$G', 22:45:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:20 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:20 INFO - 'SYSTEMDRIVE': 'C:', 22:45:20 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:20 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:20 INFO - 'TEST1': 'testie', 22:45:20 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:20 INFO - 'USERDOMAIN': 'T-W864-IX-140', 22:45:20 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 22:45:20 INFO - 'USERNAME': 'cltbld', 22:45:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-140', 22:45:20 INFO - 'WINDIR': 'C:\\windows', 22:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:20 INFO - Downloading/unpacking blobuploader==1.2.4 22:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:23 INFO - Downloading blobuploader-1.2.4.tar.gz 22:45:23 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:45:23 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 22:45:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:45:23 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:45:23 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:45:24 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:45:24 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 22:45:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:45:25 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:45:25 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:45:25 INFO - Downloading docopt-0.6.1.tar.gz 22:45:25 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:45:25 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 22:45:25 INFO - Installing collected packages: blobuploader, requests, docopt 22:45:25 INFO - Running setup.py install for blobuploader 22:45:25 INFO - Running setup.py install for requests 22:45:26 INFO - Running setup.py install for docopt 22:45:26 INFO - Successfully installed blobuploader requests docopt 22:45:26 INFO - Cleaning up... 22:45:27 INFO - Return code: 0 22:45:27 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:45: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:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45: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:45:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:27 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 0x02484B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02440EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x007F75C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00738B48>, '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 0x0075A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02864660>, '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-140', 'USERDOMAIN': 'T-W864-IX-140', '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-140', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-140', '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-140\\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:45:27 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:45:27 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:45:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:27 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Roaming', 22:45:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:45:27 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:45:27 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:45:27 INFO - 'COMPUTERNAME': 'T-W864-IX-140', 22:45:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:27 INFO - 'DCLOCATION': 'SCL3', 22:45:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:27 INFO - 'HOMEDRIVE': 'C:', 22:45:27 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 22:45:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:27 INFO - 'KTS_VERSION': '1.19c', 22:45:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 22:45:27 INFO - 'LOGONSERVER': '\\\\T-W864-IX-140', 22:45:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:27 INFO - 'MOZ_NO_REMOTE': '1', 22:45:27 INFO - 'NO_EM_RESTART': '1', 22:45:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:27 INFO - 'OS': 'Windows_NT', 22:45:27 INFO - 'OURDRIVE': 'C:', 22:45: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:45:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:27 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:45:27 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:27 INFO - 'PROCESSOR_LEVEL': '6', 22:45:27 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:27 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:45:27 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:45:27 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:45:27 INFO - 'PROMPT': '$P$G', 22:45:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:27 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:27 INFO - 'SYSTEMDRIVE': 'C:', 22:45:27 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:27 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:27 INFO - 'TEST1': 'testie', 22:45:27 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:27 INFO - 'USERDOMAIN': 'T-W864-IX-140', 22:45:27 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 22:45:27 INFO - 'USERNAME': 'cltbld', 22:45:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-140', 22:45:27 INFO - 'WINDIR': 'C:\\windows', 22:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-yuxd4j-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 22:45:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-t5g00g-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 22:45:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-xauvhe-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 22:45:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-s4agzk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 22:45:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-m61dar-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 22:45:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-omduzc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 22:45:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-495xdr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 22:45:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-9ei5oj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 22:45:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-teez8s-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 22:45:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 22:45:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-31mgdq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 22:45:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 22:45:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-bn9a27-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 22:45:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 22:45:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-bgz0jt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 22:45:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 22:45:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-mqp_nc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 22:45:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 22:45:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-squmh6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 22:45:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 22:45:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-qebexq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:45:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 22:45:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ghrj31-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 22:45:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 22:45:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-zilzxg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 22:45:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:45:29 INFO - Running setup.py install for manifestparser 22:45:30 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:30 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:30 INFO - Running setup.py install for mozcrash 22:45:30 INFO - Running setup.py install for mozdebug 22:45:31 INFO - Running setup.py install for mozdevice 22:45:31 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:31 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:31 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:31 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:31 INFO - Running setup.py install for mozfile 22:45:32 INFO - Running setup.py install for mozhttpd 22:45:32 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:32 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:32 INFO - Running setup.py install for mozinfo 22:45:33 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Running setup.py install for mozInstall 22:45:33 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Running setup.py install for mozleak 22:45:33 INFO - Running setup.py install for mozlog 22:45:34 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:34 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:34 INFO - Running setup.py install for moznetwork 22:45:34 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:34 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:35 INFO - Running setup.py install for mozprocess 22:45:35 INFO - Running setup.py install for mozprofile 22:45:35 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:35 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:35 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:35 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:35 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:35 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:36 INFO - Running setup.py install for mozrunner 22:45:36 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:36 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:36 INFO - Running setup.py install for mozscreenshot 22:45:37 INFO - Running setup.py install for moztest 22:45:37 INFO - Running setup.py install for mozversion 22:45:37 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:37 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:45:38 INFO - Cleaning up... 22:45:38 INFO - Return code: 0 22:45:38 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:45:38 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:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:38 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:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:38 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 0x02484B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02440EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x007F75C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00738B48>, '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 0x0075A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02864660>, '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-140', 'USERDOMAIN': 'T-W864-IX-140', '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-140', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-140', '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-140\\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:45:38 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:45:38 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:45:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:38 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Roaming', 22:45:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:45:38 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:45:38 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:45:38 INFO - 'COMPUTERNAME': 'T-W864-IX-140', 22:45:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:38 INFO - 'DCLOCATION': 'SCL3', 22:45:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:38 INFO - 'HOMEDRIVE': 'C:', 22:45:38 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 22:45:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:38 INFO - 'KTS_VERSION': '1.19c', 22:45:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 22:45:38 INFO - 'LOGONSERVER': '\\\\T-W864-IX-140', 22:45:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:38 INFO - 'MOZ_NO_REMOTE': '1', 22:45:38 INFO - 'NO_EM_RESTART': '1', 22:45:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:38 INFO - 'OS': 'Windows_NT', 22:45:38 INFO - 'OURDRIVE': 'C:', 22:45:38 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:45:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:38 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:45:38 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:38 INFO - 'PROCESSOR_LEVEL': '6', 22:45:38 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:38 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:45:38 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:45:38 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:45:38 INFO - 'PROMPT': '$P$G', 22:45:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:38 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:38 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:38 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:38 INFO - 'SYSTEMDRIVE': 'C:', 22:45:38 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:38 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:38 INFO - 'TEST1': 'testie', 22:45:38 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:38 INFO - 'USERDOMAIN': 'T-W864-IX-140', 22:45:38 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 22:45:38 INFO - 'USERNAME': 'cltbld', 22:45:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-140', 22:45:38 INFO - 'WINDIR': 'C:\\windows', 22:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 22:45:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ksflaq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 22:45:38 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:45:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 22:45:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-yj4hny-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 22:45:38 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:45:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 22:45:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-vgudrm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 22:45:38 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:45:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 22:45:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-wpegw4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 22:45:38 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:45:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 22:45:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-zcgwhs-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 22:45:39 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:45:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 22:45:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-oojbkz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 22:45:39 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:45:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 22:45:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-auvb3e-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 22:45:39 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:45:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 22:45:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ucyvwq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 22:45:39 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:45:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 22:45:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ocrpl4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 22:45:39 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:45:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 22:45:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-vtr9iv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 22:45:39 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:45:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 22:45:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-nonaaj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 22:45:39 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:45:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 22:45:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-crrly3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 22:45:39 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:45:39 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 22:45:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ow6j0k-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 22:45:40 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:45:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 22:45:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-u540_c-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 22:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 22:45:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 22:45:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-zsqvy3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:45:40 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:45:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 22:45:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-eyef8u-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 22:45:40 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:45:40 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 22:45:40 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-wqm4xk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 22:45:40 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:45:40 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:45:40 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:45:40 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:45:40 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:45:40 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:45:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 22:45: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:45: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:45:42 INFO - Downloading blessings-1.5.1.tar.gz 22:45:42 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:45:42 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 22:45:42 INFO - Installing collected packages: blessings 22:45:42 INFO - Running setup.py install for blessings 22:45:42 INFO - Successfully installed blessings 22:45:42 INFO - Cleaning up... 22:45:45 INFO - Return code: 0 22:45:45 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 22:45:45 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 22:45:45 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 22:45:46 INFO - Reading from file tmpfile_stdout 22:45:46 INFO - Using _rmtree_windows ... 22:45:46 INFO - Using _rmtree_windows ... 22:45:46 INFO - Current package versions: 22:45:46 INFO - blessings == 1.5.1 22:45:46 INFO - blobuploader == 1.2.4 22:45:46 INFO - docopt == 0.6.1 22:45:46 INFO - manifestparser == 1.1 22:45:46 INFO - mozInstall == 1.12 22:45:46 INFO - mozcrash == 0.16 22:45:46 INFO - mozdebug == 0.1 22:45:46 INFO - mozdevice == 0.46 22:45:46 INFO - mozfile == 1.2 22:45:46 INFO - mozhttpd == 0.7 22:45:46 INFO - mozinfo == 0.8 22:45:46 INFO - mozleak == 0.1 22:45:46 INFO - mozlog == 3.0 22:45:46 INFO - moznetwork == 0.27 22:45:46 INFO - mozprocess == 0.22 22:45:46 INFO - mozprofile == 0.27 22:45:46 INFO - mozrunner == 6.10 22:45:46 INFO - mozscreenshot == 0.1 22:45:46 INFO - mozsystemmonitor == 0.0 22:45:46 INFO - moztest == 0.7 22:45:46 INFO - mozversion == 1.4 22:45:46 INFO - psutil == 3.1.1 22:45:46 INFO - requests == 1.2.3 22:45:46 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:46 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 0x02484B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02440EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x007F75C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00738B48>, '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 0x0075A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02864660>, '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-140', 'USERDOMAIN': 'T-W864-IX-140', '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-140', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-140', '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-140\\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:45:46 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:45:46 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:45:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:46 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Roaming', 22:45:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:45:46 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:45:46 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:45:46 INFO - 'COMPUTERNAME': 'T-W864-IX-140', 22:45:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:46 INFO - 'DCLOCATION': 'SCL3', 22:45:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:46 INFO - 'HOMEDRIVE': 'C:', 22:45:46 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 22:45:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:46 INFO - 'KTS_VERSION': '1.19c', 22:45:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 22:45:46 INFO - 'LOGONSERVER': '\\\\T-W864-IX-140', 22:45:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:46 INFO - 'MOZ_NO_REMOTE': '1', 22:45:46 INFO - 'NO_EM_RESTART': '1', 22:45:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:46 INFO - 'OS': 'Windows_NT', 22:45:46 INFO - 'OURDRIVE': 'C:', 22:45:46 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:45:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:46 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:45:46 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:46 INFO - 'PROCESSOR_LEVEL': '6', 22:45:46 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:46 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:45:46 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:45:46 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:45:46 INFO - 'PROMPT': '$P$G', 22:45:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:46 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:46 INFO - 'SYSTEMDRIVE': 'C:', 22:45:46 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:46 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:46 INFO - 'TEST1': 'testie', 22:45:46 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:46 INFO - 'USERDOMAIN': 'T-W864-IX-140', 22:45:46 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 22:45:46 INFO - 'USERNAME': 'cltbld', 22:45:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-140', 22:45:46 INFO - 'WINDIR': 'C:\\windows', 22:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:46 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:45:46 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:45:46 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:45:46 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:45:46 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:45:46 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:45:46 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:45:46 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:45:46 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:45:46 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:45:46 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:45:46 INFO - Cleaning up... 22:45:46 INFO - Return code: 0 22:45:46 INFO - Running post-action listener: _resource_record_post_action 22:45:46 INFO - Running post-action listener: _start_resource_monitoring 22:45:46 INFO - Starting resource monitoring. 22:45:46 INFO - ##### 22:45:46 INFO - ##### Running install step. 22:45:46 INFO - ##### 22:45:46 INFO - Running pre-action listener: _resource_record_pre_action 22:45:46 INFO - Running main action method: install 22:45:46 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 22:45:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 22:45:46 INFO - Reading from file tmpfile_stdout 22:45:46 INFO - Using _rmtree_windows ... 22:45:46 INFO - Using _rmtree_windows ... 22:45:46 INFO - Detecting whether we're running mozinstall >=1.0... 22:45:46 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:45:46 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 22:45:46 INFO - Reading from file tmpfile_stdout 22:45:46 INFO - Output received: 22:45:46 INFO - Usage: mozinstall-script.py [options] installer 22:45:46 INFO - Options: 22:45:46 INFO - -h, --help show this help message and exit 22:45:46 INFO - -d DEST, --destination=DEST 22:45:46 INFO - Directory to install application into. [default: 22:45:46 INFO - "C:\slave\test-pgo"] 22:45:46 INFO - --app=APP Application being installed. [default: firefox] 22:45:46 INFO - Using _rmtree_windows ... 22:45:46 INFO - Using _rmtree_windows ... 22:45:46 INFO - mkdir: C:\slave\test-pgo\build\application 22:45:46 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:45:46 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:45:48 INFO - Reading from file tmpfile_stdout 22:45:48 INFO - Output received: 22:45:48 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 22:45:48 INFO - Using _rmtree_windows ... 22:45:48 INFO - Using _rmtree_windows ... 22:45:48 INFO - Running post-action listener: _resource_record_post_action 22:45:48 INFO - ##### 22:45:48 INFO - ##### Running run-tests step. 22:45:48 INFO - ##### 22:45:48 INFO - Running pre-action listener: _resource_record_pre_action 22:45:48 INFO - Running main action method: run_tests 22:45:48 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 22:45:48 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 22:45:48 INFO - Python 2.7.3 22:45:48 INFO - Return code: 0 22:45:48 INFO - grabbing minidump binary from tooltool 22:45: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:45:48 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 0x00738B48>, '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 0x0075A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02864660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 22:45:48 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:45:48 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:45:48 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 22:45:50 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpmpmrkk 22:45:50 INFO - INFO - File integrity verified, renaming tmpmpmrkk to win32-minidump_stackwalk.exe 22:45:50 INFO - Return code: 0 22:45:50 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 22:45:50 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 22:45:50 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 22:45:50 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 22:45:50 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 22:45:50 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-140', '--webServer', 'localhost'] in C:\slave\test-pgo\build 22:45:50 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip --title T-W864-IX-140 --webServer localhost 22:45:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:50 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Roaming', 22:45:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 22:45:50 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 22:45:50 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 22:45:50 INFO - 'COMPUTERNAME': 'T-W864-IX-140', 22:45:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:50 INFO - 'DCLOCATION': 'SCL3', 22:45:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:50 INFO - 'HOMEDRIVE': 'C:', 22:45:50 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-140', 22:45:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:50 INFO - 'KTS_VERSION': '1.19c', 22:45:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-140\\AppData\\Local', 22:45:50 INFO - 'LOGONSERVER': '\\\\T-W864-IX-140', 22:45:50 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 22:45:50 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 22:45:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:50 INFO - 'MOZ_NO_REMOTE': '1', 22:45:50 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 22:45:50 INFO - 'NO_EM_RESTART': '1', 22:45:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:50 INFO - 'OS': 'Windows_NT', 22:45:50 INFO - 'OURDRIVE': 'C:', 22:45:50 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 22:45:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:50 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 22:45:50 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:50 INFO - 'PROCESSOR_LEVEL': '6', 22:45:50 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:50 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 22:45:50 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 22:45:50 INFO - 'PROGRAMW6432': 'C:\\Program Files', 22:45:50 INFO - 'PROMPT': '$P$G', 22:45:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:50 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:50 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 22:45:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:50 INFO - 'SYSTEMDRIVE': 'C:', 22:45:50 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:50 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:50 INFO - 'TEST1': 'testie', 22:45:50 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 22:45:50 INFO - 'USERDOMAIN': 'T-W864-IX-140', 22:45:50 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-140', 22:45:50 INFO - 'USERNAME': 'cltbld', 22:45:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-140', 22:45:50 INFO - 'WINDIR': 'C:\\windows', 22:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:50 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/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-140', '--webServer', 'localhost'] with output_timeout 3600 ProcessManager NOT managing child processes ProcessManager UNABLE to use job objects to manage child processes 22:45:51 INFO - mozversion INFO | application_buildid: 20151022173021 22:45:51 INFO - mozversion INFO | application_changeset: 935d934a5f436ac40098d836604348042bcc14b5 22:45:51 INFO - mozversion INFO | application_display_name: Nightly 22:45:51 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:45:51 INFO - mozversion INFO | application_name: Firefox 22:45:51 INFO - mozversion INFO | application_remotingname: firefox 22:45:51 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 22:45:51 INFO - mozversion INFO | application_vendor: Mozilla 22:45:51 INFO - mozversion INFO | application_version: 44.0a1 22:45:51 INFO - mozversion INFO | platform_buildid: 20151022173021 22:45:51 INFO - mozversion INFO | platform_changeset: 935d934a5f436ac40098d836604348042bcc14b5 22:45:51 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 22:45:51 INFO - mozversion INFO | platform_version: 44.0a1 22:45:51 INFO - 2015-10-22 22:45:51,642 DEBUG : using testdate: 1445579151 22:45:51 INFO - 2015-10-22 22:45:51,642 DEBUG : actual date: 1445579151 22:45:51 INFO - 2015-10-22 22:45:51,836 INFO : starting webserver on 'localhost:49237' 22:45:51 INFO - 2015-10-22 22:45:51,838 INFO : Starting test suite T-W864-IX-140 22:45:51 INFO - 2015-10-22 22:45:51,838 INFO : Starting test dromaeo_css 22:45:51 INFO - 2015-10-22 22:45:51,838 DEBUG : operating with platform_type : w8_ 22:45:51 INFO - 2015-10-22 22:45:51,838 INFO : Initialising browser for dromaeo_css test... 22:45:51 INFO - 2015-10-22 22:45:51,941 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmp51un2_\profile http://localhost:49237/getInfo.html 22:46:01 INFO - 2015-10-22 22:46:01,253 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:46:01 INFO - 2015-10-22 22:46:01,253 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:46:01 INFO - 2015-10-22 22:46:01,253 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 22:46:01 INFO - 2015-10-22 22:46:01,253 DEBUG : BROWSER_OUTPUT: __metrics 22:46:01 INFO - 2015-10-22 22:46:01,391 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:46:02 INFO - 2015-10-22 22:46:02,411 DEBUG : BROWSER_OUTPUT: console.error: 22:46:02 INFO - 2015-10-22 22:46:02,411 DEBUG : BROWSER_OUTPUT: Message: Error: Transaction canceled due to a closed connection. 22:46:02 INFO - 2015-10-22 22:46:02,411 DEBUG : BROWSER_OUTPUT: Stack: 22:46:02 INFO - 2015-10-22 22:46:02,411 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise<@resource://gre/modules/Sqlite.jsm:562:1 22:46:02 INFO - 2015-10-22 22:46:02,411 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 22:46:02 INFO - 2015-10-22 22:46:02,411 DEBUG : BROWSER_OUTPUT: 22:46:03 INFO - 2015-10-22 22:46:03,174 INFO : Browser initialized. 22:46:03 INFO - 2015-10-22 22:46:03,174 INFO : Running cycle 1/1 for dromaeo_css test... 22:46:03 INFO - 2015-10-22 22:46:03,174 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmp51un2_\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:46:14 INFO - 2015-10-22 22:46:14,503 DEBUG : BROWSER_OUTPUT: RSS: Main: 165769216 22:46:14 INFO - 2015-10-22 22:46:14,503 DEBUG : BROWSER_OUTPUT: 22:47:05 INFO - 2015-10-22 22:47:05,740 DEBUG : BROWSER_OUTPUT: 1445579225739 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:49:14 INFO - 2015-10-22 22:49:14,713 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 22:49:15 INFO - 2015-10-22 22:49:15,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 250818560 22:49:15 INFO - 2015-10-22 22:49:15,832 DEBUG : BROWSER_OUTPUT: 22:52:18 INFO - 2015-10-22 22:52:18,413 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 22:52:18 INFO - 2015-10-22 22:52:18,976 DEBUG : BROWSER_OUTPUT: RSS: Main: 232427520 22:52:18 INFO - 2015-10-22 22:52:18,976 DEBUG : BROWSER_OUTPUT: 22:55:21 INFO - 2015-10-22 22:55:21,035 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 22:55:21 INFO - 2015-10-22 22:55:21,604 DEBUG : BROWSER_OUTPUT: RSS: Main: 181493760 22:55:21 INFO - 2015-10-22 22:55:21,604 DEBUG : BROWSER_OUTPUT: 22:58:23 INFO - 2015-10-22 22:58:23,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 22:58:24 INFO - 2015-10-22 22:58:24,111 DEBUG : BROWSER_OUTPUT: RSS: Main: 174321664 22:58:24 INFO - 2015-10-22 22:58:24,111 DEBUG : BROWSER_OUTPUT: 23:01:26 INFO - 2015-10-22 23:01:26,779 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 23:01:27 INFO - 2015-10-22 23:01:27,371 DEBUG : BROWSER_OUTPUT: RSS: Main: 164667392 23:01:27 INFO - 2015-10-22 23:01:27,371 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,032 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 23:04:30 INFO - 2015-10-22 23:04:30,286 DEBUG : BROWSER_OUTPUT: RSS: Main: 242401280 23:04:30 INFO - 2015-10-22 23:04:30,286 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,286 DEBUG : BROWSER_OUTPUT: __start_tp_report 23:04:30 INFO - 2015-10-22 23:04:30,286 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 23:04:30 INFO - 2015-10-22 23:04:30,286 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 23:04:30 INFO - 2015-10-22 23:04:30,286 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 23:04:30 INFO - 2015-10-22 23:04:30,286 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2963.140689200132;2965.421742760161;3011;3032.044905854657;3040.7719421043425;5466.28938238029;5518.061929471989;5540.833774986747;5546.805861794838;5574.331080270368;5594.664320140784;5598.356189038252;5607.411221821701;5615.5507559395255;5616.606837521375;4789.712617242959;4880.8047678092835;4929.975350123244;4969.826056088046;4997.450280469146;4900.632452566054;4921.926171107436;4927.408710954691;4936.555709986101;4949.257611358289;4923.138450771101;4930.235813448921;4933.186024306003;4937.6790508617005;4941.234108713155;4909.582685471739;4924.039812236615;4928.926066109011;4935.777889994923;4937.876553086184;4873.147199240154;4876.683015603974;4901.607871370282;4913.950860491385;4918.01639672067;5396.136618141118;5414.07960646687;5416.512513873731;5428.972855135738;5437.8368648940595;5705.828825135252;5791.768329266824;5802.767889284423;5858.47273745365;5868.618539794899;5832.795852145197;5885.941140588625;5902.704864756788;5915.905557471881;5920.496757775594;5197.506236907488;5396.487333703292;5448.210009548633;5448.291722076145;5455.672659640434;5755.827325180251;5930.851728706731;5940.108983652401;6036;6107.816765497042;5724.370319264877;5771.134329850472;5771.163181338682;5814.767409303665;5843.298804143445;5642.097264437671;5840.8247752567495;5883.323417807007;5885.588008839426;5898.8230353089475;5663.66018038919;5722.4277572242445;5744.885102297931;5757.395473475309;5841.50347220491;5700.144978253213;5758.7696492140685;5807.245058142499;5817.825465236049;5839.795607153729;3627.1113577173232;3689.059289881117;3715.888523344304;3738.12154143776;3778.6410291022307;3333.749968752353;3374.4263475209273;3378.9493157602656;3385.7460690448315;3417.5898892133105;5689.317281926195;5695.7152142392715;5695.715214239354;5697.658140511499;5759.913601295984;5718.485336319751;5883.470487656132;5900.704964751745;5904.61619994699;5910.911336329958;5579.218909352694;5583.860403489945;5591.440855914376;5634.4928956393305;5663.773449062074;4737.93396485788;4769.070031343855;4772.236442169236;4780.235162374003;4797.880052998703;5932.110183472428;6197.597156184834;6201.255849298113;6202.255729312511;6220.688965551704;5779.624324418899;5795.623284486495;5836.212111364912;5841.824745257649;5867.706614669335;6115.877682446415;6151;6152.292486364126;6157.9384206157365;6171.567990240819;5663.971680141738;5799.159121927381;5801.651900885791;5805.419458054161;5824.242848429677;5796.855078623068;5825.912611310919;5883.235179426647;5904.763809447574;5907.4683278505145;5804.100364443518;5823.650580965155;5850.707464626837;5860.794872179454;5875.764969401176;5587.8882422352135;5795.82612521625;5822.73797679097;5823.534117270693;5834.18321435008;5563.638363506359;5625.746841392229;5659.830205093854;5682.232898558807;5682.460166284196;5755.7122143893475;5760.308762948473;5792.4497172768515;5805.651660900359;5829.47534721877;5502.119660854244;5722.341930678025;5737.942620573908;5739.885202295847;5748 23:04:30 INFO - 2015-10-22 23:04:30,288 DEBUG : BROWSER_OUTPUT: |1;ext.html;558.0262442038643;596.3857227056133;596.7165596341738;607.8298076538568;614.4193736918613;31967.36065278696;32367.51448728271;32457.837710811444;32553.348933021356;38842.30998055187;41024.794876025655;41491.377629335584;41539;41540.37689434653;41613.375799363035;8778.015474738571;8925.33060020501;8938.106189381075;8947.50788706621;8949.552522373888;8122.878156827653;8266.338692904554;8671.13288671134;8801.163889430494;10552.577896884115;10602.734931626694;10684.465776711173;10753.946230268837;10756.78486430271;10758.139348852073;10583.206259530527;10707.089897358655;10740.677779666623;10742.516586753614;10749.785004299909;8023.799405014863;8207.917920820775;10491.370517768883;10572.471376431147;10899.83650245247;38976.80511597452;39763.403548946786;39830.20339593221;39856;39873;3399.8980030599123;3426.8971930842113;3489.2498112905846;3725.608811074825;3747.081964918585;30922.381552368828;31108.688913111033;31323.84338078295;31616;32006.519902201486;3213.7829853882945;3218.855151518187;3228.983855080733;3265.0857759827286;3314.867405303785;288437;288566;289152;289163;289185;51657.225141622155;52255.25192228852;54071.18893216605;54314.45685543173;54354.7282263594;55682.44317556854;56353.43646563564;56514.71742641261;56627.15059274114;56865.43134568601;14667.266636668124;14729.337179826933;14799.70400591982;14809.111453312837;14842.480513181987;11669.533218671328;11736.354500502553;11752.70618234551;11754.412279385997;11799.941000295115;16846.578835528966;17193.226304816064;17261.39585114515;17551.210195541233;17591.986283909024;8084.474509156886;8285.58572071394;8305.584720763938;8342.290905273;8388.32893368529;964.3683387381277;1024.5748014574017;1031.7162780235494;1038.8493668418037;1084.0839490630406;29074;29107.27231819179;29306.267343316587;29385.706142938296;29678.109656710334;1039.896010398954;1047.2459828923163;1093.1254996003324;1102.5699977008974;1109.5007246738999;14982.550523484313;15102.622434439007;15351.15568643713;15682.451114210948;15824.92087539578;387649;391004;392716;393348;393384;52645.7367713159;52917;53250.46749532533;53360.73319633377;53467;1271.961841144767;1278.6100239426878;1281.88463038327;1288.2592509306999;1298.5519995601503;29594.260143495725;29681.554776677935;29796.1265035544;29963.906116018836;30333.544996824625;45705.771471142434;45712.771436142604;45738.77130614459;45864.54135458603;45914.770426147654;271723;272870;275031;275528;328841;27504.862475688293;32049.359012820078;32290;32397.35205295928;32533.18667033343;40165.397519036655;40337.1932561353;40393.59606404016;41262.58737412588;41464.585354146075;36887.44668830023;37391.25217495689;37474.81262593779;37941.62058379492;38108.23783524369;29320.70679293265;33815.49276760801;35878.46182307215;36317.81841090778;36805.90906822357;33779.66220337863;34660.613575457704;34760.82619586987;34797.652023480456;35234;30021.849890750407;30177.698223018368;31166.54585447583;31749.682503175598;31779;25753.484930301664;25775.26741977437;26630.86684566565;27203.183904482896;27249.86375068112 23:04:30 INFO - 2015-10-22 23:04:30,293 DEBUG : BROWSER_OUTPUT: |2;jquery.html;364.95255616769816;373.9065233691577;374.19547971860516;374.64970252813623;378.3851241732185;10869.347839129658;11145.108391328695;12276.263424194554;12592.622221333351;16751.246193921274;10625.946870265658;11480.425978701074;12077;12193.756124877496;12217.877821221786;4538.931916021263;4887.3890763654545;4985.900281994358;4987.675801072936;5003.824866129687;3731.0485826114304;3799.164183879556;4897.706137631736;5044.445111037783;5146.9485305146845;5078.974605126969;5096.057229412552;5096.413912400066;5097.7196254206;5098.923516147261;4974.552290293873;5068.290439338495;5069.163588007974;5072;5079.593632509409;4352.36890650857;4352.455943007124;4352.564743525654;4357.978210108961;4369.519352871182;13009.284489353082;13151.342432878319;13738.587842364745;13749.106308089942;13763.31183440834;2018.2532462988788;2194.9780502194935;2196.1105752170397;2229.398062523126;2297.5634629420356;10178.745531361701;10292.897071029343;10360.274780765349;10386.532606032672;10428.739281517946;1690.2393922734695;1732.5148958291697;1737.3050779688099;1748.8863223890407;1794.6231291428685;62407.687961560354;63553.364466355204;65273.34726652768;67441.66279168622;67829.3217067828;6969.23338432772;7059.082319298561;7110.288971102848;7120.9643951780945;7176.708192525291;8218.876716849252;8333.250007499355;8337.374696897758;8337.95831020841;8340.165983401732;5590.993621148233;5621.15682647598;5676.545876329884;5714.285714285715;5729.541636669056;5904.114382842522;5906.173135760997;5940.34656187819;5947.345791962881;5956.940430595725;3225.6774322568026;3253.089135042192;3261.771675982706;3276.819774912403;3279.5572597699484;1155.849739533872;1176.499987505324;1203.1938601137258;1223.473906220316;1223.853137623491;445.45876759737075;463.25647336025867;476.4758765358148;477.7730577975434;487.57093757493885;10151.796964060677;11441.221829759681;11872.287662740264;11999.400029998465;12001.939990299992;468.7094001718956;476.2403965674743;478.5119178437974;481.71097341594765;488.2675986020969;6324.1778568786685;6495.967520162463;6549.377809108222;6567.376099270562;6575.868482630416;450216;513752.81677933194;535672;634477;635365;89711.55144224237;90985.54507227421;91363.54318228367;92921.5353923226;93721;504.568593852251;520.9140491818883;536.551979097458;537.0976759044764;537.2719964448195;10352.844707329245;10416.531256093342;10558.683239502827;10834.837477437686;11098.05666518355;25780.613290800276;35798.4630230552;35978.4603230957;36596.0850978717;39731.60268397279;49963;50938.74530627323;51044.74477627588;51160.48839511557;51668.48331516787;8244.422890397615;8410.495370277802;8414.6213420395;8417.873731893904;8452.366072544706;10449.477526123512;12143.817842732542;12467.251964882136;12484.063695222712;12509.499620015098;9551.331406801457;10611.787764244826;10687.839682404925;10903.073238774476;10920.617778377718;10388.48057597102;10479.895201047892;10656.89343106559;10721;10779.784404312026;3655.8903232901994;10348.137335279422;11062.889371106186;11187.105031597615;11209.831852522055;8244.093149753642;8284.212999765012;8372.162783721578;8454.873176902474;8461.91538084636;10475.371477711056;10868.67393978182;10908.61819836302;11128.944355278172;11159.27464714791 23:04:30 INFO - 2015-10-22 23:04:30,295 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1332.6002199340196;1350.5745690107613;1527.717372286127;1536.7387544117498;1764.576501639607;8261.669533218672;9104.590293436795;9441;9445.102715242041;9460.385074970121;8864.512451815148;8900.37697361185;8964.417312874659;8970.955145224281;9004.68483603074;2042.6731722924335;2068.214078650118;2321.4080409495555;2337.2520793346134;2344.132670911765;2370.229675355508;2375.9168429104984;2380.916682909281;2381.9761802382013;2384.988075059622;2283.4291427143216;2294.3461113582634;2325.767423257669;2329.4292898239955;2330.0563271874835;2089.101686274901;2125.798049185325;2328.3829785106877;2372.205311220743;2380.6071998120287;2368.5026144509675;2388.8772277029766;2404.4589967257402;2405.6391541268777;2410.9517809643885;9402.576884040234;9446.669366572205;9463.716088517356;9489;9530;5427.972860135714;5451.127819548853;5477.589180811416;5528.226048353234;5530.668159910419;3513.104158439607;3542.71658267338;3555.964440355615;3571.785692858437;3698.685611723007;1201.741625550502;1204.163106640885;1226.1539537718945;1252.874712528749;1261.87381261874;13953.441862325495;14156.929215353857;14160.645983850383;14175.243560456487;14183.716325673531;4726.598239149711;4733.9763301183275;4765.547273009059;4834.613230941516;4954.653174277766;11433.142514311294;11441.885581144248;11442;11449.885501145049;11466.54133834654;5463.4263402342185;5468.562514998791;5468.617196796186;5475.09660905954;5479.561635069185;4916.0905232532095;4940.135476291634;4983.401991760939;4985.626078044161;4988.401391832931;4064.4309796628495;4073.2260870434525;4096.692748043849;4096.774677392712;4131.256373852675;1270.4791035675328;1271.5168236070233;1284.524725851441;1293.9870601293867;1307.718840449308;967.5307475874196;989.50030234731;1028.5165971993151;1047.3087762076993;1172.7478592102739;8655.350848686374;8774.341924355698;8869.822603548022;8876.911230887608;8900.42147260439;669.437672355224;677.7153595489906;678.9015592739025;683.5454422808876;689.6034780001519;6409.230892292955;6519.674016299166;6560.278369379364;6565.507586930999;6569.441597464269;11680.299182048935;11751.529938802523;11855.881441185651;12045.457954391899;12089.335086570323;11959.880401195876;11972.461239244254;11972.760544789055;11979.161458697807;11992.76014479723;8294.58527073644;8499.36254780894;8507.234348908503;8512.574371281411;8513.872291915626;8279.95860020696;8534.189252021048;8534.74395768128;8581.95709021451;8618.224359807647;10348.793024139626;10406.635767748396;10522.894771052192;10563;10600.46997650099;10551.472426378803;10751.086157676373;10807.783844323227;10864.348139111678;10915.181361397768;8323.334133269203;8356.791080223044;8375.162483751576;8378.162183781573;8387.287080597973;9211.953940230524;9245.399049061789;9256.444613323221;9259.953700231456;9272.397294175857;8206.876896846432;8346.12365701617;8431.704890328809;8606.78483037929;8657.740267791976;8149.144339844231;8255.174482551936;8320.500769953822;8333.916660833314;8589.226969572519;8560.400771945904;8573.485590864568;8613.65545378178;8614.78463038429;8624.65501379963;7143.356538588367;7258.854822903407;7261.056062711814;7282.927170728225;7342.596157211405;7368.631568421664;7404.740834070782;7482.850342993219;7484.251574842472;7520.8495830082 23:04:30 INFO - 2015-10-22 23:04:30,387 DEBUG : BROWSER_OUTPUT: |4;prototype.html;324.99999999999994;328.53020181140965;345.03734580520336;347.7287715581847;357.8765325962542;5743.310802703671;5797.797077102302;5814.215080964069;5820.476157145856;5821.214136091627;5582.692951887645;5658.321001479828;5679.943200567993;5744.913826292609;5761.798337058204;4409.96365854024;4411;4421.535738747433;4422.601965823075;4424.557544245566;4268.743875367488;4420.756858372788;4421.602055814976;4429.557044295577;4430.911381772363;4260.403543503897;4340.8697739067875;4390.4511936008;4396.824127034914;4398.736075835444;4372.519022907478;4383.3863259143745;4383.715058521202;4383.9342409863875;4383.978080109611;3888.1251718363424;3891.9610803891883;3895.2599006188734;3895.5520115186832;3897.3569361055393;5448.91826622601;5543.722813859331;5547.472990065938;5551.472610102034;5554.111342185231;5065.670731402447;5082.923756143661;5092.490750924915;5140.922886156711;5141.228815677641;5995.910061349084;6006.429389208062;6006.639601623873;6009.699515024275;6011.75952961881;4255.851045213433;4292.377605247253;4296.462942132233;4302.548232435612;4310.051788606502;6118.112873633343;6289.276733175698;6294.937050629481;6296.496280297565;6299.338569450233;4285.3786201000685;4310.956890431118;4311.267084595626;4316.266234740039;4321.978390108092;4348.499922508952;4372.912541749147;4373.934390984138;4377.321515165155;4388.802503887333;4983.9252411213865;4997.275395067695;5010.273510341053;5010.774515146827;5015.22264049073;5954.583179177416;5974.044152935509;6017;6053.727582258809;6063.181470501426;1578.5974576482924;1594.936202551885;1594.9601259968595;1595.0828273742645;1595.3618552579062;1065.211743309945;1065.5684447798653;1077.6659235636978;1087.5595383869545;1089.455272363818;5790.218320526759;5867.589268751231;5979.372165922516;5988.790392336247;6018.398160183947;6245.094461303176;6261.029540421242;6265.216847894013;6270.592411493239;6272.717727702265;5855.912161317584;5924.526037917042;5968.1644569760265;6022.036474164112;6033.969830150909;3416.1288871338147;3422.9731080675697;3436.1753179236803;3448.706859916885;3448.9655103449145;6509.479241660656;6553.934460655428;6563.540552161398;6563.901541476882;6567.277599464055;6262.15460912771;6343.27052388972;6352.587081839758;6358.872822543523;6361.332060133712;6547;6566.67166641666;6568;6574.572652777554;6583.473322134218;6197.845053873689;6246.531510136667;6248.750049997949;6252.781152659635;6256.217972753406;5967.671778052249;6081.179040829608;6108.17539632144;6108.35862234459;6124.448799608057;6655.500837437308;6656.5340426169705;6660.666966651745;6661.966690166712;6666.600003999775;4399.2521271384585;4434.490033646172;4435.489918659267;4436.4010858534975;4436.600705936481;6217.906731398942;6298.71655775482;6320.304766475592;6325.810225693236;6330.52521060913;6194.349593292639;6196.442320191204;6199.56603037783;6200.441960223601;6204;6026.186464827368;6138.140660307471;6156.076588511759;6160.568760186744;6166.784162554289;5890.705464726832;5954.047352423591;6015.729292181775;6064.241969753781;6069.302030266399;6097.115918191926;6101.9389806103145;6109.572329936862;6112.541559383153;6126.08108783686;5944.524438045032;5992.5205983520345;5999.40005999414;6007.609505382136;6024.698765061642 23:04:30 INFO - 2015-10-22 23:04:30,388 DEBUG : BROWSER_OUTPUT: |5;yui.html;749.662651806687;776.9028871391076;826.7189155687065;826.9338452923766;843.1821133500503;12868.356582170889;12997.415116319764;13018.218906865572;13066.477340906364;13101.934490327547;11695.941520292386;11773.058155347573;11832;11852.051835853132;11877.524899004029;613.300837045767;621.8911690454175;714.6283932355173;729.2233771033858;731.1810771935413;739.5488751861387;740.1377395334429;744.713285385128;746.1046743907306;748.3788455581854;654.3619970528744;661.556756972827;775.6509570693182;780.8008957715774;782.338923609549;754.8490301939634;760.4524742185617;760.680514184044;761.1209053543166;762.1654288554024;557.7685260616839;557.8131326005753;558.0596694569663;558.4666643355586;558.6340946679932;12191.45138468771;12357.073219508573;12373.072019598567;12373.814392784116;12517.37413129349;11014.944925275322;11069.391183484906;11302.886971130265;11399.316041037564;11400.144989125849;9912.207023438108;10019;10045.14616257619;10053.447060411674;10093.192544596415;3659.8170091495585;3670.119171398872;3673.1000904778234;3677.3380791457525;3687.8524859005606;39445.21109577821;40427.59572404268;40487;43144;43164.35253471169;7210.206877243499;7234.24040475753;7340.339369456775;7346.081739782528;7392.815179620445;11195.2723073;11241.71895702614;11246;11266.605668801552;11287.604933827277;6578.638174900427;6630.336966303427;6653;6700.899486507707;6789.728410863609;4110.527289361702;4217.219814334358;4238.554951730086;4239.894002649959;4412.051408947092;2703.8643769616806;2752.4907892039637;2752.944941101167;2760.4203117345182;2764.626775385297;454.8862784303924;461.28040257198876;469.1133757198897;470.4260801821772;471.0884438611298;699.5243234600418;701.9543729657556;727.2218725963274;729.4310437858479;752.1801236651966;8282.917170828214;8436.873446898302;8504.107068757816;8548.102449242864;8596.742097737078;601.7081715368043;745.8993035940078;749.2133260076897;785.0422484568817;805.2229598437419;4107.774072425986;4276.102018576134;4304.139172165579;4337.110892267077;4813.422389313305;49481.50518494841;53563.19655205175;53707;53817.73091134519;53972.73013634907;43213.78393108014;43269.78365108154;43353.56646433495;43785.78107109444;45607.543924560996;39458.408123878165;39631.20737585232;39633;39742.80128599396;41388.58611413908;8941.910580894108;9010.32422568323;9025.368224224241;9034.593443294936;9138.90333160025;27986.58020129659;28103.297417564725;28225.43549128965;28678;28963.048868271886;35753.82123089368;35899;36072.098197545274;37913.810430948775;37965.24069518649;8177.182281772013;8376.664933402595;8454.1123182065;8631.611577478903;8665.393422460367;12607.936960315506;12636.55772047974;12824.935875320563;12897.677558061123;12973.54592589255;12124;12622;12631.211509213003;12634.052446066398;12749.617511474671;11770.293782373084;11967.042636589225;12039.277643341427;12045.698857528632;12435.316057616918;10545.73635659084;11005.944970275097;12058.457369418222;12246.265224086583;12576.245425274512;10743.623973160902;11011.669649910515;11254.324740515594;11342;11393.430328483708;9518.524073796145;10099.69700908974;10148.847767283349;10559.894401055892;10693.9465302673 23:04:30 INFO - 2015-10-22 23:04:30,388 DEBUG : BROWSER_OUTPUT: __end_tp_report 23:04:30 INFO - 2015-10-22 23:04:30,388 DEBUG : BROWSER_OUTPUT: __start_cc_report 23:04:30 INFO - 2015-10-22 23:04:30,388 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,850 23:04:30 INFO - 2015-10-22 23:04:30,388 DEBUG : BROWSER_OUTPUT: __end_cc_report 23:04:30 INFO - 2015-10-22 23:04:30,388 DEBUG : BROWSER_OUTPUT: __startTimestamp1445580270283__endTimestamp 23:04:30 INFO - 2015-10-22 23:04:30,388 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,390 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 23:04:30 INFO - 2015-10-22 23:04:30,390 DEBUG : BROWSER_OUTPUT: Number of tests: 6 23:04:30 INFO - 2015-10-22 23:04:30,390 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,390 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5390.51 Median:5695.72 stddev:748.29 (13.1%) stddev-sans-first:725.95 23:04:30 INFO - 2015-10-22 23:04:30,390 DEBUG : BROWSER_OUTPUT: Values: 2963.1 2965.4 3011.0 3032.0 3040.8 5466.3 5518.1 5540.8 5546.8 5574.3 5594.7 5598.4 5607.4 5615.6 5616.6 4789.7 4880.8 4930.0 4969.8 4997.5 4900.6 4921.9 4927.4 4936.6 4949.3 4923.1 4930.2 4933.2 4937.7 4941.2 4909.6 4924.0 4928.9 4935.8 4937.9 4873.1 4876.7 4901.6 4914.0 4918.0 5396.1 5414.1 5416.5 5429.0 5437.8 5705.8 5791.8 5802.8 5858.5 5868.6 5832.8 5885.9 5902.7 5915.9 5920.5 5197.5 5396.5 5448.2 5448.3 5455.7 5755.8 5930.9 5940.1 6036.0 6107.8 5724.4 5771.1 5771.2 5814.8 5843.3 5642.1 5840.8 5883.3 5885.6 5898.8 5663.7 5722.4 5744.9 5757.4 5841.5 5700.1 5758.8 5807.2 5817.8 5839.8 3627.1 3689.1 3715.9 3738.1 3778.6 3333.7 3374.4 3378.9 3385.7 3417.6 5689.3 5695.7 5695.7 5697.7 5759.9 5718.5 5883.5 5900.7 5904.6 5910.9 5579.2 5583.9 5591.4 5634.5 5663.8 4737.9 4769.1 4772.2 4780.2 4797.9 5932.1 6197.6 6201.3 6202.3 6220.7 5779.6 5795.6 5836.2 5841.8 5867.7 6115.9 6151.0 6152.3 6157.9 6171.6 5664.0 5799.2 5801.7 5805.4 5824.2 5796.9 5825.9 5883.2 5904.8 5907.5 5804.1 5823.7 5850.7 5860.8 5875.8 5587.9 5795.8 5822.7 5823.5 5834.2 5563.6 5625.7 5659.8 5682.2 5682.5 5755.7 5760.3 5792.4 5805.7 5829.5 5502.1 5722.3 5737.9 5739.9 5748.0 23:04:30 INFO - 2015-10-22 23:04:30,390 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,390 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48406.04 Median:32382.43 stddev:85511.39 (264.1%) stddev-sans-first:85675.80 23:04:30 INFO - 2015-10-22 23:04:30,390 DEBUG : BROWSER_OUTPUT: Values: 558.0 596.4 596.7 607.8 614.4 31967.4 32367.5 32457.8 32553.3 38842.3 41024.8 41491.4 41539.0 41540.4 41613.4 8778.0 8925.3 8938.1 8947.5 8949.6 8122.9 8266.3 8671.1 8801.2 10552.6 10602.7 10684.5 10753.9 10756.8 10758.1 10583.2 10707.1 10740.7 10742.5 10749.8 8023.8 8207.9 10491.4 10572.5 10899.8 38976.8 39763.4 39830.2 39856.0 39873.0 3399.9 3426.9 3489.2 3725.6 3747.1 30922.4 31108.7 31323.8 31616.0 32006.5 3213.8 3218.9 3229.0 3265.1 3314.9 288437.0 288566.0 289152.0 289163.0 289185.0 51657.2 52255.3 54071.2 54314.5 54354.7 55682.4 56353.4 56514.7 56627.2 56865.4 14667.3 14729.3 14799.7 14809.1 14842.5 11669.5 11736.4 11752.7 11754.4 11799.9 16846.6 17193.2 17261.4 17551.2 17592.0 8084.5 8285.6 8305.6 8342.3 8388.3 964.4 1024.6 1031.7 1038.8 1084.1 29074.0 29107.3 29306.3 29385.7 29678.1 1039.9 1047.2 1093.1 1102.6 1109.5 14982.6 15102.6 15351.2 15682.5 15824.9 387649.0 391004.0 392716.0 393348.0 393384.0 52645.7 52917.0 53250.5 53360.7 53467.0 1272.0 1278.6 1281.9 1288.3 1298.6 29594.3 29681.6 29796.1 29963.9 30333.5 45705.8 45712.8 45738.8 45864.5 45914.8 271723.0 272870.0 275031.0 275528.0 328841.0 27504.9 32049.4 32290.0 32397.4 32533.2 40165.4 40337.2 40393.6 41262.6 41464.6 36887.4 37391.3 37474.8 37941.6 38108.2 29320.7 33815.5 35878.5 36317.8 36805.9 33779.7 34660.6 34760.8 34797.7 35234.0 30021.8 30177.7 31166.5 31749.7 31779.0 25753.5 25775.3 26630.9 27203.2 27249.9 23:04:30 INFO - 2015-10-22 23:04:30,391 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,391 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27917.19 Median:2407.49 stddev:91900.29 (3817.3%) stddev-sans-first:92134.81 23:04:30 INFO - 2015-10-22 23:04:30,391 DEBUG : BROWSER_OUTPUT: Values: 365.0 373.9 374.2 374.6 378.4 10869.3 11145.1 12276.3 12592.6 16751.2 10625.9 11480.4 12077.0 12193.8 12217.9 4538.9 4887.4 4985.9 4987.7 5003.8 3731.0 3799.2 4897.7 5044.4 5146.9 5079.0 5096.1 5096.4 5097.7 5098.9 4974.6 5068.3 5069.2 5072.0 5079.6 4352.4 4352.5 4352.6 4358.0 4369.5 13009.3 13151.3 13738.6 13749.1 13763.3 2018.3 2195.0 2196.1 2229.4 2297.6 10178.7 10292.9 10360.3 10386.5 10428.7 1690.2 1732.5 1737.3 1748.9 1794.6 62407.7 63553.4 65273.3 67441.7 67829.3 6969.2 7059.1 7110.3 7121.0 7176.7 8218.9 8333.3 8337.4 8338.0 8340.2 5591.0 5621.2 5676.5 5714.3 5729.5 5904.1 5906.2 5940.3 5947.3 5956.9 3225.7 3253.1 3261.8 3276.8 3279.6 1155.8 1176.5 1203.2 1223.5 1223.9 445.5 463.3 476.5 477.8 487.6 10151.8 11441.2 11872.3 11999.4 12001.9 468.7 476.2 478.5 481.7 488.3 6324.2 6496.0 6549.4 6567.4 6575.9 450216.0 513752.8 535672.0 634477.0 635365.0 89711.6 90985.5 91363.5 92921.5 93721.0 504.6 520.9 536.6 537.1 537.3 10352.8 10416.5 10558.7 10834.8 11098.1 25780.6 35798.5 35978.5 36596.1 39731.6 49963.0 50938.7 51044.7 51160.5 51668.5 8244.4 8410.5 8414.6 8417.9 8452.4 10449.5 12143.8 12467.3 12484.1 12509.5 9551.3 10611.8 10687.8 10903.1 10920.6 10388.5 10479.9 10656.9 10721.0 10779.8 3655.9 10348.1 11062.9 11187.1 11209.8 8244.1 8284.2 8372.2 8454.9 8461.9 10475.4 10868.7 10908.6 11128.9 11159.3 23:04:30 INFO - 2015-10-22 23:04:30,392 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,394 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6498.92 Median:4984.51 stddev:3693.04 (74.1%) stddev-sans-first:3682.99 23:04:30 INFO - 2015-10-22 23:04:30,394 DEBUG : BROWSER_OUTPUT: Values: 1332.6 1350.6 1527.7 1536.7 1764.6 8261.7 9104.6 9441.0 9445.1 9460.4 8864.5 8900.4 8964.4 8971.0 9004.7 2042.7 2068.2 2321.4 2337.3 2344.1 2370.2 2375.9 2380.9 2382.0 2385.0 2283.4 2294.3 2325.8 2329.4 2330.1 2089.1 2125.8 2328.4 2372.2 2380.6 2368.5 2388.9 2404.5 2405.6 2411.0 9402.6 9446.7 9463.7 9489.0 9530.0 5428.0 5451.1 5477.6 5528.2 5530.7 3513.1 3542.7 3556.0 3571.8 3698.7 1201.7 1204.2 1226.2 1252.9 1261.9 13953.4 14156.9 14160.6 14175.2 14183.7 4726.6 4734.0 4765.5 4834.6 4954.7 11433.1 11441.9 11442.0 11449.9 11466.5 5463.4 5468.6 5468.6 5475.1 5479.6 4916.1 4940.1 4983.4 4985.6 4988.4 4064.4 4073.2 4096.7 4096.8 4131.3 1270.5 1271.5 1284.5 1294.0 1307.7 967.5 989.5 1028.5 1047.3 1172.7 8655.4 8774.3 8869.8 8876.9 8900.4 669.4 677.7 678.9 683.5 689.6 6409.2 6519.7 6560.3 6565.5 6569.4 11680.3 11751.5 11855.9 12045.5 12089.3 11959.9 11972.5 11972.8 11979.2 11992.8 8294.6 8499.4 8507.2 8512.6 8513.9 8280.0 8534.2 8534.7 8582.0 8618.2 10348.8 10406.6 10522.9 10563.0 10600.5 10551.5 10751.1 10807.8 10864.3 10915.2 8323.3 8356.8 8375.2 8378.2 8387.3 9212.0 9245.4 9256.4 9260.0 9272.4 8206.9 8346.1 8431.7 8606.8 8657.7 8149.1 8255.2 8320.5 8333.9 8589.2 8560.4 8573.5 8613.7 8614.8 8624.7 7143.4 7258.9 7261.1 7282.9 7342.6 7368.6 7404.7 7482.9 7484.3 7520.8 23:04:30 INFO - 2015-10-22 23:04:30,394 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,394 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5111.30 Median:5838.56 stddev:1525.58 (26.1%) stddev-sans-first:1486.96 23:04:30 INFO - 2015-10-22 23:04:30,394 DEBUG : BROWSER_OUTPUT: Values: 325.0 328.5 345.0 347.7 357.9 5743.3 5797.8 5814.2 5820.5 5821.2 5582.7 5658.3 5679.9 5744.9 5761.8 4410.0 4411.0 4421.5 4422.6 4424.6 4268.7 4420.8 4421.6 4429.6 4430.9 4260.4 4340.9 4390.5 4396.8 4398.7 4372.5 4383.4 4383.7 4383.9 4384.0 3888.1 3892.0 3895.3 3895.6 3897.4 5448.9 5543.7 5547.5 5551.5 5554.1 5065.7 5082.9 5092.5 5140.9 5141.2 5995.9 6006.4 6006.6 6009.7 6011.8 4255.9 4292.4 4296.5 4302.5 4310.1 6118.1 6289.3 6294.9 6296.5 6299.3 4285.4 4311.0 4311.3 4316.3 4322.0 4348.5 4372.9 4373.9 4377.3 4388.8 4983.9 4997.3 5010.3 5010.8 5015.2 5954.6 5974.0 6017.0 6053.7 6063.2 1578.6 1594.9 1595.0 1595.1 1595.4 1065.2 1065.6 1077.7 1087.6 1089.5 5790.2 5867.6 5979.4 5988.8 6018.4 6245.1 6261.0 6265.2 6270.6 6272.7 5855.9 5924.5 5968.2 6022.0 6034.0 3416.1 3423.0 3436.2 3448.7 3449.0 6509.5 6553.9 6563.5 6563.9 6567.3 6262.2 6343.3 6352.6 6358.9 6361.3 6547.0 6566.7 6568.0 6574.6 6583.5 6197.8 6246.5 6248.8 6252.8 6256.2 5967.7 6081.2 6108.2 6108.4 6124.4 6655.5 6656.5 6660.7 6662.0 6666.6 4399.3 4434.5 4435.5 4436.4 4436.6 6217.9 6298.7 6320.3 6325.8 6330.5 6194.3 6196.4 6199.6 6200.4 6204.0 6026.2 6138.1 6156.1 6160.6 6166.8 5890.7 5954.0 6015.7 6064.2 6069.3 6097.1 6101.9 6109.6 6112.5 6126.1 5944.5 5992.5 5999.4 6007.6 6024.7 23:04:30 INFO - 2015-10-22 23:04:30,395 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,395 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12346.32 Median:4258.00 stddev:13716.14 (322.1%) stddev-sans-first:13726.97 23:04:30 INFO - 2015-10-22 23:04:30,395 DEBUG : BROWSER_OUTPUT: Values: 749.7 776.9 826.7 826.9 843.2 12868.4 12997.4 13018.2 13066.5 13101.9 11695.9 11773.1 11832.0 11852.1 11877.5 613.3 621.9 714.6 729.2 731.2 739.5 740.1 744.7 746.1 748.4 654.4 661.6 775.7 780.8 782.3 754.8 760.5 760.7 761.1 762.2 557.8 557.8 558.1 558.5 558.6 12191.5 12357.1 12373.1 12373.8 12517.4 11014.9 11069.4 11302.9 11399.3 11400.1 9912.2 10019.0 10045.1 10053.4 10093.2 3659.8 3670.1 3673.1 3677.3 3687.9 39445.2 40427.6 40487.0 43144.0 43164.4 7210.2 7234.2 7340.3 7346.1 7392.8 11195.3 11241.7 11246.0 11266.6 11287.6 6578.6 6630.3 6653.0 6700.9 6789.7 4110.5 4217.2 4238.6 4239.9 4412.1 2703.9 2752.5 2752.9 2760.4 2764.6 454.9 461.3 469.1 470.4 471.1 699.5 702.0 727.2 729.4 752.2 8282.9 8436.9 8504.1 8548.1 8596.7 601.7 745.9 749.2 785.0 805.2 4107.8 4276.1 4304.1 4337.1 4813.4 49481.5 53563.2 53707.0 53817.7 53972.7 43213.8 43269.8 43353.6 43785.8 45607.5 39458.4 39631.2 39633.0 39742.8 41388.6 8941.9 9010.3 9025.4 9034.6 9138.9 27986.6 28103.3 28225.4 28678.0 28963.0 35753.8 35899.0 36072.1 37913.8 37965.2 8177.2 8376.7 8454.1 8631.6 8665.4 12607.9 12636.6 12824.9 12897.7 12973.5 12124.0 12622.0 12631.2 12634.1 12749.6 11770.3 11967.0 12039.3 12045.7 12435.3 10545.7 11005.9 12058.5 12246.3 12576.2 10743.6 11011.7 11254.3 11342.0 11393.4 9518.5 10099.7 10148.8 10559.9 10693.9 23:04:30 INFO - 2015-10-22 23:04:30,397 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 23:04:30 INFO - 2015-10-22 23:04:30,397 DEBUG : BROWSER_OUTPUT: 23:04:30 INFO - 2015-10-22 23:04:30,976 INFO : Browser exited with error code: 0 23:04:31 INFO - 2015-10-22 23:04:31,736 INFO : Completed test dromaeo_css (00:18:39) 23:04:31 INFO - 2015-10-22 23:04:31,736 INFO : Starting test kraken 23:04:31 INFO - 2015-10-22 23:04:31,736 DEBUG : operating with platform_type : w8_ 23:04:31 INFO - 2015-10-22 23:04:31,736 INFO : Initialising browser for kraken test... 23:04:31 INFO - 2015-10-22 23:04:31,757 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpc168ln\profile http://localhost:49237/getInfo.html 23:04:37 INFO - 2015-10-22 23:04:37,582 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 23:04:37 INFO - 2015-10-22 23:04:37,582 DEBUG : BROWSER_OUTPUT: colorDepth:24 23:04:37 INFO - 2015-10-22 23:04:37,582 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 23:04:37 INFO - 2015-10-22 23:04:37,582 DEBUG : BROWSER_OUTPUT: __metrics 23:04:37 INFO - 2015-10-22 23:04:37,727 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:04:38 INFO - 2015-10-22 23:04:38,131 DEBUG : BROWSER_OUTPUT: console.error: 23:04:38 INFO - 2015-10-22 23:04:38,131 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] 23:04:38 INFO - 2015-10-22 23:04:38,131 DEBUG : BROWSER_OUTPUT: Stack: 23:04:38 INFO - 2015-10-22 23:04:38,131 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 23:04:38 INFO - 2015-10-22 23:04:38,131 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 23:04:38 INFO - 2015-10-22 23:04:38,131 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 23:04:38 INFO - 2015-10-22 23:04:38,131 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 23:04:38 INFO - 2015-10-22 23:04:38,132 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 23:04:38 INFO - 2015-10-22 23:04:38,132 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 23:04:38 INFO - 2015-10-22 23:04:38,132 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 23:04:38 INFO - 2015-10-22 23:04:38,132 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 23:04:38 INFO - 2015-10-22 23:04:38,132 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 23:04:38 INFO - 2015-10-22 23:04:38,132 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 23:04:38 INFO - 2015-10-22 23:04:38,134 DEBUG : BROWSER_OUTPUT: 23:04:38 INFO - 2015-10-22 23:04:38,792 INFO : Browser initialized. 23:04:38 INFO - 2015-10-22 23:04:38,792 INFO : Running cycle 1/1 for kraken test... 23:04:38 INFO - 2015-10-22 23:04:38,792 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpc168ln\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 23:04:50 INFO - 2015-10-22 23:04:50,157 DEBUG : BROWSER_OUTPUT: RSS: Main: 191135744 23:04:50 INFO - 2015-10-22 23:04:50,157 DEBUG : BROWSER_OUTPUT: 23:04:52 INFO - 2015-10-22 23:04:52,736 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 23:04:53 INFO - 2015-10-22 23:04:53,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 228573184 23:04:53 INFO - 2015-10-22 23:04:53,322 DEBUG : BROWSER_OUTPUT: 23:04:55 INFO - 2015-10-22 23:04:55,838 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 23:04:56 INFO - 2015-10-22 23:04:56,421 DEBUG : BROWSER_OUTPUT: RSS: Main: 228773888 23:04:56 INFO - 2015-10-22 23:04:56,421 DEBUG : BROWSER_OUTPUT: 23:04:59 INFO - 2015-10-22 23:04:59,007 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 23:04:59 INFO - 2015-10-22 23:04:59,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 214867968 23:04:59 INFO - 2015-10-22 23:04:59,582 DEBUG : BROWSER_OUTPUT: 23:05:01 INFO - 2015-10-22 23:05:01,516 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 23:05:02 INFO - 2015-10-22 23:05:02,089 DEBUG : BROWSER_OUTPUT: RSS: Main: 216977408 23:05:02 INFO - 2015-10-22 23:05:02,089 DEBUG : BROWSER_OUTPUT: 23:05:04 INFO - 2015-10-22 23:05:04,151 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:05:04 INFO - 2015-10-22 23:05:04,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 214159360 23:05:04 INFO - 2015-10-22 23:05:04,713 DEBUG : BROWSER_OUTPUT: 23:05:07 INFO - 2015-10-22 23:05:07,670 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 23:05:08 INFO - 2015-10-22 23:05:08,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 206426112 23:05:08 INFO - 2015-10-22 23:05:08,253 DEBUG : BROWSER_OUTPUT: 23:05:11 INFO - 2015-10-22 23:05:11,523 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 23:05:12 INFO - 2015-10-22 23:05:12,105 DEBUG : BROWSER_OUTPUT: RSS: Main: 206594048 23:05:12 INFO - 2015-10-22 23:05:12,105 DEBUG : BROWSER_OUTPUT: 23:05:14 INFO - 2015-10-22 23:05:14,901 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 23:05:15 INFO - 2015-10-22 23:05:15,486 DEBUG : BROWSER_OUTPUT: RSS: Main: 203853824 23:05:15 INFO - 2015-10-22 23:05:15,486 DEBUG : BROWSER_OUTPUT: 23:05:17 INFO - 2015-10-22 23:05:17,207 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:05:17 INFO - 2015-10-22 23:05:17,769 DEBUG : BROWSER_OUTPUT: RSS: Main: 233136128 23:05:17 INFO - 2015-10-22 23:05:17,769 DEBUG : BROWSER_OUTPUT: 23:05:19 INFO - 2015-10-22 23:05:19,551 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:05:20 INFO - 2015-10-22 23:05:20,125 DEBUG : BROWSER_OUTPUT: RSS: Main: 237899776 23:05:20 INFO - 2015-10-22 23:05:20,125 DEBUG : BROWSER_OUTPUT: 23:05:22 INFO - 2015-10-22 23:05:22,267 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:05:22 INFO - 2015-10-22 23:05:22,851 DEBUG : BROWSER_OUTPUT: RSS: Main: 223330304 23:05:22 INFO - 2015-10-22 23:05:22,851 DEBUG : BROWSER_OUTPUT: 23:05:25 INFO - 2015-10-22 23:05:25,207 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:05:25 INFO - 2015-10-22 23:05:25,792 DEBUG : BROWSER_OUTPUT: RSS: Main: 227205120 23:05:25 INFO - 2015-10-22 23:05:25,792 DEBUG : BROWSER_OUTPUT: 23:05:28 INFO - 2015-10-22 23:05:28,345 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:05:28 INFO - 2015-10-22 23:05:28,914 DEBUG : BROWSER_OUTPUT: RSS: Main: 227024896 23:05:28 INFO - 2015-10-22 23:05:28,914 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,562 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: RSS: Main: 282271744 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: __start_tp_report 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;108;103;108;108;109;108;108;101;109 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;131;123;136;149;126;131;132;121;145;122 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: |2;audio-dft;140;140;140;140;141;139;139;142;140;139 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: |3;audio-fft;68;69;70;91;68;68;69;69;99;67 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;86;85;107;86;85;85;115;85;85;118 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;111;112;110;110;110;111;110;110;110 23:05:30 INFO - 2015-10-22 23:05:30,811 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;131;133;132;132;132;131;131;131;131;131 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;96;96;96;98;96;97;96;96;96 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;72;73;72;72;72;72;73;94;72;71 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;64;64;63;64;64;63;64 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;88;88;89;87;92;86;88;87;89;90 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;114;115;118;117;114;114;113;114;116 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;150;149;148;148;148;140;149;148;153;141 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;66;67;66;66;67;66;65;66;68 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: __end_tp_report 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: __start_cc_report 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,944 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: __end_cc_report 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: __startTimestamp1445580330809__endTimestamp 23:05:30 INFO - 2015-10-22 23:05:30,812 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,813 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 23:05:30 INFO - 2015-10-22 23:05:30,813 DEBUG : BROWSER_OUTPUT: Number of tests: 14 23:05:30 INFO - 2015-10-22 23:05:30,813 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,813 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.00 Median:108.00 stddev:2.71 (2.5%) stddev-sans-first:2.85 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: Values: 108.0 108.0 103.0 108.0 108.0 109.0 108.0 108.0 101.0 109.0 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:131.60 Median:131.50 stddev:9.50 (7.2%) stddev-sans-first:10.07 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: Values: 131.0 123.0 136.0 149.0 126.0 131.0 132.0 121.0 145.0 122.0 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:140.00 Median:140.00 stddev:0.94 (0.7%) stddev-sans-first:1.00 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: Values: 140.0 140.0 140.0 140.0 141.0 139.0 139.0 142.0 140.0 139.0 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:73.80 Median:69.00 stddev:11.36 (16.5%) stddev-sans-first:11.85 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 70.0 91.0 68.0 68.0 69.0 69.0 99.0 67.0 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:93.70 Median:85.00 stddev:13.82 (16.3%) stddev-sans-first:14.37 23:05:30 INFO - 2015-10-22 23:05:30,815 DEBUG : BROWSER_OUTPUT: Values: 86.0 85.0 107.0 86.0 85.0 85.0 115.0 85.0 85.0 118.0 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.50 Median:110.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: Values: 111.0 111.0 112.0 110.0 110.0 110.0 111.0 110.0 110.0 110.0 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:131.50 Median:131.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: Values: 131.0 133.0 132.0 132.0 132.0 131.0 131.0 131.0 131.0 131.0 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: Values: 97.0 96.0 96.0 96.0 98.0 96.0 97.0 96.0 96.0 96.0 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.30 Median:72.00 stddev:6.95 (9.6%) stddev-sans-first:7.32 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 72.0 72.0 72.0 72.0 73.0 94.0 72.0 71.0 23:05:30 INFO - 2015-10-22 23:05:30,816 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,818 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:64.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 23:05:30 INFO - 2015-10-22 23:05:30,818 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 64.0 64.0 63.0 64.0 64.0 63.0 64.0 23:05:30 INFO - 2015-10-22 23:05:30,818 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,818 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:88.50 stddev:1.71 (1.9%) stddev-sans-first:1.81 23:05:30 INFO - 2015-10-22 23:05:30,818 DEBUG : BROWSER_OUTPUT: Values: 88.0 88.0 89.0 87.0 92.0 86.0 88.0 87.0 89.0 90.0 23:05:30 INFO - 2015-10-22 23:05:30,818 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,818 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.60 Median:115.50 stddev:2.46 (2.1%) stddev-sans-first:1.66 23:05:30 INFO - 2015-10-22 23:05:30,819 DEBUG : BROWSER_OUTPUT: Values: 121.0 114.0 115.0 118.0 117.0 114.0 114.0 113.0 114.0 116.0 23:05:30 INFO - 2015-10-22 23:05:30,819 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,819 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.40 Median:148.50 stddev:3.95 (2.7%) stddev-sans-first:4.08 23:05:30 INFO - 2015-10-22 23:05:30,819 DEBUG : BROWSER_OUTPUT: Values: 150.0 149.0 148.0 148.0 148.0 140.0 149.0 148.0 153.0 141.0 23:05:30 INFO - 2015-10-22 23:05:30,819 DEBUG : BROWSER_OUTPUT: 23:05:30 INFO - 2015-10-22 23:05:30,819 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 23:05:30 INFO - 2015-10-22 23:05:30,819 DEBUG : BROWSER_OUTPUT: Values: 66.0 66.0 67.0 66.0 66.0 67.0 66.0 65.0 66.0 68.0 23:05:30 INFO - 2015-10-22 23:05:30,821 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 23:05:30 INFO - 2015-10-22 23:05:30,821 DEBUG : BROWSER_OUTPUT: 23:05:31 INFO - 2015-10-22 23:05:31,493 INFO : Browser exited with error code: 0 23:05:32 INFO - 2015-10-22 23:05:32,618 INFO : Completed test kraken (00:01:00) 23:05:32 INFO - 2015-10-22 23:05:32,618 INFO : Starting test v8_7 23:05:32 INFO - 2015-10-22 23:05:32,618 DEBUG : operating with platform_type : w8_ 23:05:32 INFO - 2015-10-22 23:05:32,618 INFO : Initialising browser for v8_7 test... 23:05:32 INFO - 2015-10-22 23:05:32,640 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmp6uaek7\profile http://localhost:49237/getInfo.html 23:05:37 INFO - 2015-10-22 23:05:37,875 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 23:05:37 INFO - 2015-10-22 23:05:37,875 DEBUG : BROWSER_OUTPUT: colorDepth:24 23:05:37 INFO - 2015-10-22 23:05:37,875 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 23:05:37 INFO - 2015-10-22 23:05:37,875 DEBUG : BROWSER_OUTPUT: __metrics 23:05:38 INFO - 2015-10-22 23:05:38,127 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: console.error: 23:05:38 INFO - 2015-10-22 23:05:38,845 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] 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: Stack: 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 23:05:38 INFO - 2015-10-22 23:05:38,845 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 23:05:38 INFO - 2015-10-22 23:05:38,846 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 23:05:38 INFO - 2015-10-22 23:05:38,846 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 23:05:38 INFO - 2015-10-22 23:05:38,846 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6768.46 23:06:15 INFO - RETURN: kraken: 1440.30 23:06:15 INFO - RETURN: v8_7: 20003.29 23:06:15 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,31]]", "result": "20003.29"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,31]]", "result": "1440.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,31]]", "result": "6768.46"}}} 23:06:15 INFO - Return code: 0 23:06:15 INFO - # TBPL SUCCESS # 23:06:15 INFO - Running post-action listener: _resource_record_post_action 23:06:15 INFO - Running post-run listener: _resource_record_post_run 23:06:16 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 378984960; Write bytes: 392840192; Read time: 4483790; Write time: 4731310 23:06:16 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 295424; Read time: 0; Write time: 630 23:06:16 INFO - run-tests - Wall time: 1227s; CPU: 13.0%; Read bytes: 378939392; Write bytes: 352659456; Read time: 4476130; Write time: 4667350 23:06:16 INFO - Running post-run listener: _upload_blobber_files 23:06:16 INFO - Blob upload gear active. 23:06:16 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 23:06:16 INFO - Copying logs to upload dir... 23:06:16 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1416.389000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 44 secs) (at 2015-10-22 23:06:23.908916) ========= ========= Started set props: build_url (results: 0, elapsed: 2 secs) (at 2015-10-22 23:06:23.916693) ========= '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-140\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-140 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-140 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-140\AppData\Local LOGONSERVER=\\T-W864-IX-140 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-140 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-140 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-140 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False build_url:https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/yhpLXO58QiSNq1ajpLUctg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip' ========= Finished set props: build_url (results: 0, elapsed: 2 secs) (at 2015-10-22 23:06:26.118100) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 27 secs) (at 2015-10-22 23:06:26.118499) ========= '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-140\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-140 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-140 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-140\AppData\Local LOGONSERVER=\\T-W864-IX-140 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-140 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-140 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-140 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= Finished 'rm -f ...' (results: 0, elapsed: 27 secs) (at 2015-10-22 23:06:53.293630) ========= ========= Started reboot slave lost (results: 0, elapsed: 17 secs) (at 2015-10-22 23:06:53.294065) ========= ========= Finished reboot slave lost (results: 0, elapsed: 17 secs) (at 2015-10-22 23:07:10.469202) =========