builder: fx-team_win8_64_test-dromaeojs slave: t-w864-ix-187 starttime: 1445872755.3 results: success (0) buildid: 20151026063032 builduid: cfd6af1e106241c9a216f37f4a7fdb53 revision: 076830f89d148b20422c6aa4d239fd787ec9c4b6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 08:19:15.304395) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 08:19:15.305231) ========= ========= Started set props: basedir (results: 0, elapsed: 1 secs) (at 2015-10-26 08:19:15.305829) ========= 'cd' in dir C:\\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['cd'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-187\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-187 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-187 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-187\AppData\Local LOGONSERVER=\\T-W864-IX-187 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 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-187 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-187 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-187 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False C:\slave\test program finished with exit code 0 elapsedTime=0.638000 basedir: 'C:\\slave\\test' ========= Finished set props: basedir (results: 0, elapsed: 1 secs) (at 2015-10-26 08:19:17.303793) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 19 secs) (at 2015-10-26 08:19:17.307280) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 19 secs) (at 2015-10-26 08:19:36.939314) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 08:19:36.939648) ========= 'rm' '-rf' 'properties' in dir C:\\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-187\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-187 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-187 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-187\AppData\Local LOGONSERVER=\\T-W864-IX-187 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 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-187 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-187 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-187 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.102000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 08:19:39.869033) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 08:19:39.869380) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 08:19:39.869815) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 08:19:39.870179) ========= 'bash' '-c' 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir C:\\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-187\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-187 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-187 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-187\AppData\Local LOGONSERVER=\\T-W864-IX-187 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 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-187 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-187 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-187 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False --08:19:40-- 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% 19.59 MB/s 08:19:41 (19.59 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.477000 ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 08:19:42.236531) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 08:19:42.236913) ========= 'rm' '-rf' 'scripts' in dir C:\\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-187\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-187 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-187 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-187\AppData\Local LOGONSERVER=\\T-W864-IX-187 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 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-187 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-187 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-187 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=2.106000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 08:19:44.714944) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2015-10-26 08:19:44.715371) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 076830f89d148b20422c6aa4d239fd787ec9c4b6 --destination scripts --debug' in dir C:\\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 076830f89d148b20422c6aa4d239fd787ec9c4b6 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-187\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-187 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-187 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-187\AppData\Local LOGONSERVER=\\T-W864-IX-187 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 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-187 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-187 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-187 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-26 08:19:45,232 truncating revision to first 12 chars 2015-10-26 08:19:45,232 Setting DEBUG logging. 2015-10-26 08:19:45,233 attempt 1/10 2015-10-26 08:19:45,233 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/076830f89d14?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-26 08:19:47,845 unpacking tar archive at: fx-team-076830f89d14/testing/mozharness/ program finished with exit code 0 elapsedTime=5.200000 ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2015-10-26 08:19:51.225609) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 18 secs) (at 2015-10-26 08:19:51.225923) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 18 secs) (at 2015-10-26 08:20:10.132911) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 08:20:10.133212) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 08:20:10.133653) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 7 secs) (at 2015-10-26 08:20:10.134007) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--system-bits' '64' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-Non-PGO' in dir C:\\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-187\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-187 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-187 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-187\AppData\Local LOGONSERVER=\\T-W864-IX-187 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 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PROPERTIES_FILE=C:\slave\test/buildprops.json PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-187 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-187 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-187 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 08:20:10 INFO - MultiFileLogger online at 20151026 08:20:10 in C:\slave\test 08:20:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 08:20:10 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 08:20:10 INFO - {'append_to_log': False, 08:20:10 INFO - 'base_work_dir': 'C:\\slave\\test', 08:20:10 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 08:20:10 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 08:20:10 INFO - 'branch': 'Fx-Team-Non-PGO', 08:20:10 INFO - 'buildbot_json_path': 'buildprops.json', 08:20:10 INFO - 'config_files': ('talos/windows_config.py',), 08:20:10 INFO - 'default_actions': ('clobber', 08:20:10 INFO - 'read-buildbot-config', 08:20:10 INFO - 'download-and-extract', 08:20:10 INFO - 'populate-webroot', 08:20:10 INFO - 'create-virtualenv', 08:20:10 INFO - 'install', 08:20:10 INFO - 'run-tests'), 08:20:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:20:10 INFO - 'download_minidump_stackwalk': True, 08:20:10 INFO - 'download_symbols': 'ondemand', 08:20:10 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 08:20:10 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 08:20:10 INFO - 'hg': 'c:/mozilla-build/hg/hg', 08:20:10 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 08:20:10 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 08:20:10 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 08:20:10 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 08:20:10 INFO - 'C:/mozilla-build/tooltool.py'), 08:20:10 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 08:20:10 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 08:20:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:20:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:20:10 INFO - 'installer_path': 'installer.exe', 08:20:10 INFO - 'log_level': 'info', 08:20:10 INFO - 'log_name': 'talos', 08:20:10 INFO - 'log_to_console': True, 08:20:10 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 08:20:10 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 08:20:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 08:20:10 INFO - 'opt_config_files': (), 08:20:10 INFO - 'pip_index': False, 08:20:10 INFO - 'sps_profile': False, 08:20:10 INFO - 'sps_profile_interval': 0, 08:20:10 INFO - 'suite': 'dromaeojs', 08:20:10 INFO - 'system_bits': '64', 08:20:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:20:10 INFO - 'title': 'T-W864-IX-187', 08:20:10 INFO - 'use_talos_json': True, 08:20:10 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 08:20:10 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 08:20:10 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 08:20:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:20:10 INFO - 'work_dir': 'build'} 08:20:10 INFO - ##### 08:20:10 INFO - ##### Running clobber step. 08:20:10 INFO - ##### 08:20:10 INFO - Running pre-action listener: _resource_record_pre_action 08:20:10 INFO - Running main action method: clobber 08:20:10 INFO - rmtree: C:\slave\test\build 08:20:10 INFO - Using _rmtree_windows ... 08:20:10 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 08:20:58 INFO - Running post-action listener: _resource_record_post_action 08:20:58 INFO - ##### 08:20:58 INFO - ##### Running read-buildbot-config step. 08:20:58 INFO - ##### 08:20:58 INFO - Running pre-action listener: _resource_record_pre_action 08:20:58 INFO - Running main action method: read_buildbot_config 08:20:58 INFO - Using buildbot properties: 08:20:58 INFO - { 08:20:58 INFO - "properties": { 08:20:58 INFO - "buildnumber": 693, 08:20:58 INFO - "product": "firefox", 08:20:58 INFO - "script_repo_revision": "production", 08:20:58 INFO - "builddir": "fx-team_win8_64_test-dromaeojs", 08:20:58 INFO - "repository": "", 08:20:58 INFO - "buildername": "Windows 8 64-bit fx-team talos dromaeojs", 08:20:58 INFO - "buildid": "20151026063032", 08:20:58 INFO - "slavename": "t-w864-ix-187", 08:20:58 INFO - "pgo_build": "False", 08:20:58 INFO - "basedir": "C:\\slave\\test", 08:20:58 INFO - "project": "", 08:20:58 INFO - "platform": "win8_64", 08:20:58 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 08:20:58 INFO - "slavebuilddir": "test", 08:20:58 INFO - "scheduler": "tests-fx-team-win64-talos", 08:20:58 INFO - "branch": "fx-team", 08:20:58 INFO - "repo_path": "integration/fx-team", 08:20:58 INFO - "stage_platform": "win64", 08:20:58 INFO - "builduid": "cfd6af1e106241c9a216f37f4a7fdb53", 08:20:58 INFO - "revision": "076830f89d148b20422c6aa4d239fd787ec9c4b6" 08:20:58 INFO - }, 08:20:58 INFO - "sourcestamp": { 08:20:58 INFO - "repository": "", 08:20:58 INFO - "hasPatch": false, 08:20:58 INFO - "project": "", 08:20:58 INFO - "branch": "fx-team-win64-talos", 08:20:58 INFO - "changes": [ 08:20:58 INFO - { 08:20:58 INFO - "category": null, 08:20:58 INFO - "files": [ 08:20:58 INFO - { 08:20:58 INFO - "url": null, 08:20:58 INFO - "name": "https://queue.taskcluster.net/v1/task/IgSif9T6QqObFl2bbt8lQg/artifacts/public/build/firefox-44.0a1.en-US.win64.zip" 08:20:58 INFO - } 08:20:58 INFO - ], 08:20:58 INFO - "repository": "", 08:20:58 INFO - "rev": "60d33952d4cad718c786bea4d229cb137d5b2317", 08:20:58 INFO - "who": "cbook@mozilla.com", 08:20:58 INFO - "when": 1445871172, 08:20:58 INFO - "number": 6573866, 08:20:58 INFO - "comments": "Bug 1217232 - Hide placeholder svg path in context menu when unblocking image r=mfinkle", 08:20:58 INFO - "project": "", 08:20:58 INFO - "at": "Mon 26 Oct 2015 07:52:52", 08:20:58 INFO - "branch": "fx-team-win64-talos", 08:20:58 INFO - "revlink": "", 08:20:58 INFO - "properties": [ 08:20:58 INFO - [ 08:20:58 INFO - "buildid", 08:20:58 INFO - "20151026030933", 08:20:58 INFO - "Change" 08:20:58 INFO - ], 08:20:58 INFO - [ 08:20:58 INFO - "builduid", 08:20:58 INFO - "3123e42579314a7e94b13ee96afbf98a", 08:20:58 INFO - "Change" 08:20:58 INFO - ], 08:20:58 INFO - [ 08:20:58 INFO - "pgo_build", 08:20:58 INFO - "False", 08:20:58 INFO - "Change" 08:20:58 INFO - ] 08:20:58 INFO - ], 08:20:58 INFO - "revision": "60d33952d4cad718c786bea4d229cb137d5b2317" 08:20:58 INFO - }, 08:20:58 INFO - { 08:20:58 INFO - "category": null, 08:20:58 INFO - "files": [ 08:20:58 INFO - { 08:20:58 INFO - "url": null, 08:20:58 INFO - "name": "https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip" 08:20:58 INFO - } 08:20:58 INFO - ], 08:20:58 INFO - "repository": "", 08:20:58 INFO - "rev": "076830f89d148b20422c6aa4d239fd787ec9c4b6", 08:20:58 INFO - "who": "alessio.placitelli@gmail.com", 08:20:58 INFO - "when": 1445871211, 08:20:58 INFO - "number": 6573868, 08:20:58 INFO - "comments": "Bug 1213780 - Fix Telemetry reporting repeated hang annotations for Chrome hangs. r=aklotz", 08:20:58 INFO - "project": "", 08:20:58 INFO - "at": "Mon 26 Oct 2015 07:53:31", 08:20:58 INFO - "branch": "fx-team-win64-talos", 08:20:58 INFO - "revlink": "", 08:20:58 INFO - "properties": [ 08:20:58 INFO - [ 08:20:58 INFO - "buildid", 08:20:58 INFO - "20151026063032", 08:20:58 INFO - "Change" 08:20:58 INFO - ], 08:20:58 INFO - [ 08:20:58 INFO - "builduid", 08:20:58 INFO - "cfd6af1e106241c9a216f37f4a7fdb53", 08:20:58 INFO - "Change" 08:20:58 INFO - ], 08:20:58 INFO - [ 08:20:58 INFO - "pgo_build", 08:20:58 INFO - "False", 08:20:58 INFO - "Change" 08:20:58 INFO - ] 08:20:58 INFO - ], 08:20:58 INFO - "revision": "076830f89d148b20422c6aa4d239fd787ec9c4b6" 08:20:58 INFO - } 08:20:58 INFO - ], 08:20:58 INFO - "revision": "076830f89d148b20422c6aa4d239fd787ec9c4b6" 08:20:58 INFO - } 08:20:58 INFO - } 08:20:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip. 08:20:58 INFO - Running post-action listener: _resource_record_post_action 08:20:58 INFO - ##### 08:20:58 INFO - ##### Running download-and-extract step. 08:20:58 INFO - ##### 08:20:58 INFO - Running pre-action listener: _resource_record_pre_action 08:20:58 INFO - Running main action method: download_and_extract 08:20:58 INFO - mkdir: C:\slave\test\build\tests 08:20: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')]} 08:20:58 INFO - https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:20:58 INFO - trying https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/test_packages.json 08:20:58 INFO - Downloading https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 08:20:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 08:21:01 INFO - Downloaded 1221 bytes. 08:21:01 INFO - Reading from file C:\slave\test\build\test_packages.json 08:21:01 INFO - Using the following test package requirements: 08:21:01 INFO - {u'common': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 08:21:01 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 08:21:01 INFO - u'firefox-44.0a1.en-US.win64.cppunittest.tests.zip'], 08:21:01 INFO - u'jittest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 08:21:01 INFO - u'jsshell-win64.zip'], 08:21:01 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 08:21:01 INFO - u'firefox-44.0a1.en-US.win64.mochitest.tests.zip'], 08:21:01 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 08:21:01 INFO - u'reftest': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 08:21:01 INFO - u'firefox-44.0a1.en-US.win64.reftest.tests.zip'], 08:21:01 INFO - u'talos': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 08:21:01 INFO - u'firefox-44.0a1.en-US.win64.talos.tests.zip'], 08:21:01 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 08:21:01 INFO - u'firefox-44.0a1.en-US.win64.web-platform.tests.zip'], 08:21:01 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win64.common.tests.zip'], 08:21:01 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win64.common.tests.zip', 08:21:01 INFO - u'firefox-44.0a1.en-US.win64.xpcshell.tests.zip']} 08:21:01 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win64.common.tests.zip'] for test suite category: common 08:21:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:01 INFO - https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 08:21:01 INFO - trying https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip 08:21:01 INFO - Downloading https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win64.common.tests.zip 08:21:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'}, attempt #1 ProcessManager NOT managing child processes 08:21:20 INFO - Downloaded 18108007 bytes. 08:21:20 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] in C:\slave\test\build\tests 08:21:20 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win64.common.tests.zip 08:21:20 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] with output_timeout 1760 ProcessManager UNABLE to use job objects to manage child processes 08:21:46 INFO - Return code: 0 08:21:46 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 08:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:46 INFO - https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 08:21:46 INFO - trying https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip 08:21:46 INFO - Downloading https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win64.common.tests.zip 08:21:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'}, attempt #1 ProcessManager NOT managing child processes 08:21:48 INFO - Downloaded 18108007 bytes. 08:21:48 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] in C:\slave\test\build\tests 08:21:48 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win64.common.tests.zip 08:21:48 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.common.tests.zip'] with output_timeout 1760 ProcessManager UNABLE to use job objects to manage child processes 08:22:07 INFO - Return code: 0 08:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:07 INFO - https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip matches https://queue.taskcluster.net 08:22:07 INFO - trying https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip 08:22:07 INFO - Downloading https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win64.talos.tests.zip 08:22:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.talos.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.talos.tests.zip'}, attempt #1 ProcessManager NOT managing child processes 08:22:08 INFO - Downloaded 11131307 bytes. 08:22:08 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.talos.tests.zip'] in C:\slave\test\build\tests 08:22:08 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win64.talos.tests.zip 08:22:08 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.talos.tests.zip'] with output_timeout 1760 ProcessManager UNABLE to use job objects to manage child processes 08:22:09 INFO - Return code: 0 08:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:09 INFO - https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip matches https://queue.taskcluster.net 08:22:09 INFO - trying https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 08:22:09 INFO - Downloading https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip to C:\slave\test\build\firefox-44.0a1.en-US.win64.zip 08:22:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.zip'}, attempt #1 08:22:31 INFO - Downloaded 54774108 bytes. 08:22:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 08:22:31 INFO - mkdir: C:\slave\test\properties 08:22:31 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 08:22:31 INFO - Writing to file C:\slave\test\properties\build_url 08:22:31 INFO - Contents: 08:22:31 INFO - build_url:https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip 08:22:31 INFO - Running post-action listener: _resource_record_post_action 08:22:31 INFO - Running post-action listener: set_extra_try_arguments 08:22:31 INFO - ##### 08:22:31 INFO - ##### Running populate-webroot step. 08:22:31 INFO - ##### 08:22:31 INFO - Running pre-action listener: _resource_record_pre_action 08:22:31 INFO - Running main action method: populate_webroot 08:22:31 INFO - Guessing talos json url... 08:22:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:31 INFO - https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.txt matches https://queue.taskcluster.net 08:22:31 INFO - trying https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.txt 08:22:31 INFO - Downloading https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.txt to C:\slave\test\build\firefox-44.0a1.en-US.win64.txt 08:22:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.txt', 'file_name': 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.txt'}, attempt #1 08:22:34 INFO - Downloaded 103 bytes. 08:22:34 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win64.txt 08:22:34 INFO - Contents: 08:22:34 INFO - 20151026063032 08:22:34 INFO - https://hg.mozilla.org/integration/fx-team/rev/076830f89d148b20422c6aa4d239fd787ec9c4b6 08:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:34 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/076830f89d/testing/talos/talos.json 08:22:34 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/076830f89d/testing/talos/talos.json to C:\slave\test\build\talos.json 08:22:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/076830f89d/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1 08:22:34 INFO - Downloaded 4264 bytes. 08:22:34 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 08:22:34 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 08:22:34 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 08:22:34 INFO - u'../fennec_ids.txt'], 08:22:34 INFO - u'tests': [u'tcheck2']}, 08:22:34 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 08:22:34 INFO - u'--tppagecycles', 08:22:34 INFO - u'7'], 08:22:34 INFO - u'tests': [u'tsvgm']}}, 08:22:34 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 08:22:35 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 08:22:35 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 08:22:35 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 08:22:35 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 08:22:35 INFO - u'tests': [u'dromaeo_css', 08:22:35 INFO - u'kraken', 08:22:35 INFO - u'v8_7']}, 08:22:35 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:35 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 08:22:35 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:35 INFO - u'talos_options': [u'--e10s'], 08:22:35 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 08:22:35 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:35 INFO - u'tests': [u'damp', u'tps']}, 08:22:35 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:35 INFO - u'talos_options': [u'--e10s'], 08:22:35 INFO - u'tests': [u'damp', u'tps']}, 08:22:35 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 08:22:35 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 08:22:35 INFO - u'other': {u'tests': [u'a11yr', 08:22:35 INFO - u'ts_paint', 08:22:35 INFO - u'tpaint', 08:22:35 INFO - u'sessionrestore', 08:22:35 INFO - u'sessionrestore_no_auto_restore']}, 08:22:35 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 08:22:35 INFO - u'tests': [u'a11yr', 08:22:35 INFO - u'ts_paint', 08:22:35 INFO - u'tpaint', 08:22:35 INFO - u'sessionrestore', 08:22:35 INFO - u'sessionrestore_no_auto_restore']}, 08:22:35 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 08:22:35 INFO - u'tests': [u'a11yr', 08:22:35 INFO - u'ts_paint', 08:22:35 INFO - u'tpaint', 08:22:35 INFO - u'sessionrestore', 08:22:35 INFO - u'sessionrestore_no_auto_restore']}, 08:22:35 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 08:22:35 INFO - u'tests': [u'a11yr', 08:22:35 INFO - u'ts_paint', 08:22:35 INFO - u'tpaint', 08:22:35 INFO - u'sessionrestore', 08:22:35 INFO - u'sessionrestore_no_auto_restore']}, 08:22:35 INFO - u'other_l64': {u'tests': [u'a11yr', 08:22:35 INFO - u'ts_paint', 08:22:35 INFO - u'tpaint', 08:22:35 INFO - u'sessionrestore', 08:22:35 INFO - u'sessionrestore_no_auto_restore']}, 08:22:35 INFO - u'other_nol64': {u'tests': [u'a11yr', 08:22:35 INFO - u'ts_paint', 08:22:35 INFO - u'tpaint', 08:22:35 INFO - u'sessionrestore', 08:22:35 INFO - u'sessionrestore_no_auto_restore']}, 08:22:35 INFO - u'svgr': {u'tests': [u'tsvgx', 08:22:35 INFO - u'tsvgr_opacity', 08:22:35 INFO - u'tart', 08:22:35 INFO - u'tscrollx', 08:22:35 INFO - u'cart']}, 08:22:35 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 08:22:35 INFO - u'tests': [u'tsvgx', 08:22:35 INFO - u'tsvgr_opacity', 08:22:35 INFO - u'tart', 08:22:35 INFO - u'tscrollx', 08:22:35 INFO - u'cart']}, 08:22:35 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:35 INFO - u'tests': [u'tp5o']}, 08:22:35 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:35 INFO - u'talos_options': [u'--e10s'], 08:22:35 INFO - u'tests': [u'tp5o']}, 08:22:35 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:35 INFO - u'talos_options': [u'--xperf_path', 08:22:35 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:35 INFO - u'tests': [u'tp5n']}, 08:22:35 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:35 INFO - u'talos_options': [u'--e10s', 08:22:35 INFO - u'--xperf_path', 08:22:35 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 08:22:35 INFO - u'C:/slave/talos-data/talos/xperf.config'], 08:22:35 INFO - u'tests': [u'tp5n']}}, 08:22:35 INFO - u'talos.zip': {u'path': u'', 08:22:35 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:22:35 INFO - Running post-action listener: _resource_record_post_action 08:22:35 INFO - ##### 08:22:35 INFO - ##### Running create-virtualenv step. 08:22:35 INFO - ##### 08:22:35 INFO - Running pre-action listener: _resource_record_pre_action 08:22:35 INFO - Running main action method: create_virtualenv 08:22:35 INFO - Creating virtualenv C:\slave\test\build/venv 08:22:35 INFO - mkdir: C:\slave\test\build\venv\Scripts 08:22:35 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 08:22:35 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 08:22:35 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build 08:22:35 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 08:22:36 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 08:22:39 INFO - Installing distribute......................................................................................................................................................................................done. 08:22:40 INFO - Return code: 0 08:22:40 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 08:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:40 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02342B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0239BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024585E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D38B48>, '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 0x01D5A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02829660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-187', 'USERDOMAIN': 'T-W864-IX-187', '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-187', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-187', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', '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-187\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:22:40 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 08:22:40 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:22:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:22:40 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Roaming', 08:22:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:22:40 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:22:40 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:22:40 INFO - 'COMPUTERNAME': 'T-W864-IX-187', 08:22:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:22:40 INFO - 'DCLOCATION': 'SCL3', 08:22:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:22:40 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:22:40 INFO - 'HOMEDRIVE': 'C:', 08:22:40 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 08:22:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:22:40 INFO - 'KTS_VERSION': '1.19c', 08:22:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 08:22:40 INFO - 'LOGONSERVER': '\\\\T-W864-IX-187', 08:22:40 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:22:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:40 INFO - 'MOZ_NO_REMOTE': '1', 08:22:40 INFO - 'NO_EM_RESTART': '1', 08:22:40 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:22:40 INFO - 'OS': 'Windows_NT', 08:22:40 INFO - 'OURDRIVE': 'C:', 08:22:40 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', 08:22:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:22:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:22:40 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:22:40 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:22:40 INFO - 'PROCESSOR_LEVEL': '6', 08:22:40 INFO - 'PROCESSOR_REVISION': '1e05', 08:22:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:22:40 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:22:40 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:22:40 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:22:40 INFO - 'PROMPT': '$P$G', 08:22:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:22:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:22:40 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:22:40 INFO - 'PWD': 'C:\\slave\\test', 08:22:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:22:40 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:22:40 INFO - 'SYSTEMDRIVE': 'C:', 08:22:40 INFO - 'SYSTEMROOT': 'C:\\windows', 08:22:40 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:22:40 INFO - 'TEST1': 'testie', 08:22:40 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:22:40 INFO - 'USERDOMAIN': 'T-W864-IX-187', 08:22:40 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 08:22:40 INFO - 'USERNAME': 'cltbld', 08:22:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-187', 08:22:40 INFO - 'WINDIR': 'C:\\windows', 08:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:22:44 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:22:44 INFO - Downloading/unpacking pip>=1.5 08:22:44 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 08:22:48 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 08:22:48 INFO - Running setup.py egg_info for package pip 08:22:48 INFO - warning: no files found matching 'pip\cacert.pem' 08:22:48 INFO - warning: no files found matching '*.html' under directory 'docs' 08:22:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:22:48 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:22:48 INFO - Installing collected packages: pip 08:22:48 INFO - Found existing installation: pip 0.8.2 08:22:48 INFO - Uninstalling pip: 08:22:48 INFO - Successfully uninstalled pip 08:22:48 INFO - Running setup.py install for pip 08:22:48 INFO - warning: no files found matching 'pip\cacert.pem' 08:22:48 INFO - warning: no files found matching '*.html' under directory 'docs' 08:22:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:22:48 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:22:48 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 08:22:48 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 08:22:48 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 08:22:48 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 08:22:48 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 08:22:48 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 08:22:48 INFO - Successfully installed pip 08:22:48 INFO - Cleaning up... 08:22:48 INFO - Return code: 0 08:22:48 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 08:22: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')]} 08:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22: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')]} 08:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:48 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02342B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0239BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024585E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D38B48>, '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 0x01D5A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02829660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-187', 'USERDOMAIN': 'T-W864-IX-187', '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-187', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-187', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', '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-187\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:22:48 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test\build 08:22:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:22:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:22:48 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Roaming', 08:22:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:22:48 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:22:48 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:22:48 INFO - 'COMPUTERNAME': 'T-W864-IX-187', 08:22:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:22:48 INFO - 'DCLOCATION': 'SCL3', 08:22:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:22:48 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:22:48 INFO - 'HOMEDRIVE': 'C:', 08:22:48 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 08:22:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:22:48 INFO - 'KTS_VERSION': '1.19c', 08:22:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 08:22:48 INFO - 'LOGONSERVER': '\\\\T-W864-IX-187', 08:22:48 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:22:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:48 INFO - 'MOZ_NO_REMOTE': '1', 08:22:48 INFO - 'NO_EM_RESTART': '1', 08:22:48 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:22:48 INFO - 'OS': 'Windows_NT', 08:22:48 INFO - 'OURDRIVE': 'C:', 08:22:48 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', 08:22:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:22:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:22:48 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:22:48 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:22:48 INFO - 'PROCESSOR_LEVEL': '6', 08:22:48 INFO - 'PROCESSOR_REVISION': '1e05', 08:22:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:22:48 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:22:48 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:22:48 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:22:48 INFO - 'PROMPT': '$P$G', 08:22:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:22:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:22:48 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:22:48 INFO - 'PWD': 'C:\\slave\\test', 08:22:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:22:48 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:22:48 INFO - 'SYSTEMDRIVE': 'C:', 08:22:48 INFO - 'SYSTEMROOT': 'C:\\windows', 08:22:48 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:22:48 INFO - 'TEST1': 'testie', 08:22:48 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:22:48 INFO - 'USERDOMAIN': 'T-W864-IX-187', 08:22:48 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 08:22:48 INFO - 'USERNAME': 'cltbld', 08:22:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-187', 08:22:48 INFO - 'WINDIR': 'C:\\windows', 08:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:50 INFO - Downloading/unpacking psutil>=0.7.1 08:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:51 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl 08:22:51 INFO - Installing collected packages: psutil 08:22:51 INFO - Successfully installed psutil 08:22:51 INFO - Cleaning up... 08:22:51 INFO - Return code: 0 08:22:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 08:22: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')]} 08:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22: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')]} 08:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02342B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0239BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024585E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D38B48>, '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 0x01D5A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02829660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-187', 'USERDOMAIN': 'T-W864-IX-187', '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-187', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-187', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', '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-187\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:22:51 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test\build 08:22:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:22:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:22:51 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Roaming', 08:22:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:22:51 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:22:51 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:22:51 INFO - 'COMPUTERNAME': 'T-W864-IX-187', 08:22:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:22:51 INFO - 'DCLOCATION': 'SCL3', 08:22:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:22:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:22:51 INFO - 'HOMEDRIVE': 'C:', 08:22:51 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 08:22:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:22:51 INFO - 'KTS_VERSION': '1.19c', 08:22:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 08:22:51 INFO - 'LOGONSERVER': '\\\\T-W864-IX-187', 08:22:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:22:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:51 INFO - 'MOZ_NO_REMOTE': '1', 08:22:51 INFO - 'NO_EM_RESTART': '1', 08:22:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:22:51 INFO - 'OS': 'Windows_NT', 08:22:51 INFO - 'OURDRIVE': 'C:', 08:22:51 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', 08:22:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:22:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:22:51 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:22:51 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:22:51 INFO - 'PROCESSOR_LEVEL': '6', 08:22:51 INFO - 'PROCESSOR_REVISION': '1e05', 08:22:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:22:51 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:22:51 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:22:51 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:22:51 INFO - 'PROMPT': '$P$G', 08:22:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:22:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:22:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:22:51 INFO - 'PWD': 'C:\\slave\\test', 08:22:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:22:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:22:51 INFO - 'SYSTEMDRIVE': 'C:', 08:22:51 INFO - 'SYSTEMROOT': 'C:\\windows', 08:22:51 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:22:51 INFO - 'TEST1': 'testie', 08:22:51 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:22:51 INFO - 'USERDOMAIN': 'T-W864-IX-187', 08:22:51 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 08:22:51 INFO - 'USERNAME': 'cltbld', 08:22:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-187', 08:22:51 INFO - 'WINDIR': 'C:\\windows', 08:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:22:56 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 08:22:56 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 08:22:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 08:22:56 INFO - Installing collected packages: mozsystemmonitor 08:22:56 INFO - Running setup.py install for mozsystemmonitor 08:22:56 INFO - Successfully installed mozsystemmonitor 08:22:56 INFO - Cleaning up... 08:22:56 INFO - Return code: 0 08:22:56 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 08:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02342B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0239BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024585E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D38B48>, '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 0x01D5A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02829660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-187', 'USERDOMAIN': 'T-W864-IX-187', '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-187', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-187', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', '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-187\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:22:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 08:22:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:22:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:22:56 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Roaming', 08:22:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:22:56 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:22:56 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:22:56 INFO - 'COMPUTERNAME': 'T-W864-IX-187', 08:22:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:22:56 INFO - 'DCLOCATION': 'SCL3', 08:22:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:22:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:22:56 INFO - 'HOMEDRIVE': 'C:', 08:22:56 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 08:22:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:22:56 INFO - 'KTS_VERSION': '1.19c', 08:22:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 08:22:56 INFO - 'LOGONSERVER': '\\\\T-W864-IX-187', 08:22:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:22:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:56 INFO - 'MOZ_NO_REMOTE': '1', 08:22:56 INFO - 'NO_EM_RESTART': '1', 08:22:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:22:56 INFO - 'OS': 'Windows_NT', 08:22:56 INFO - 'OURDRIVE': 'C:', 08:22:56 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', 08:22:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:22:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:22:56 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:22:56 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:22:56 INFO - 'PROCESSOR_LEVEL': '6', 08:22:56 INFO - 'PROCESSOR_REVISION': '1e05', 08:22:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:22:56 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:22:56 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:22:56 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:22:56 INFO - 'PROMPT': '$P$G', 08:22:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:22:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:22:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:22:56 INFO - 'PWD': 'C:\\slave\\test', 08:22:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:22:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:22:56 INFO - 'SYSTEMDRIVE': 'C:', 08:22:56 INFO - 'SYSTEMROOT': 'C:\\windows', 08:22:56 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:22:56 INFO - 'TEST1': 'testie', 08:22:56 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:22:56 INFO - 'USERDOMAIN': 'T-W864-IX-187', 08:22:56 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 08:22:56 INFO - 'USERNAME': 'cltbld', 08:22:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-187', 08:22:56 INFO - 'WINDIR': 'C:\\windows', 08:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:57 INFO - Downloading/unpacking blobuploader==1.2.4 08:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:58 INFO - Downloading blobuploader-1.2.4.tar.gz 08:22:58 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 08:22:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 08:22:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:58 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 08:22:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 08:22:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:58 INFO - Downloading docopt-0.6.1.tar.gz 08:22:58 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 08:22:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 08:22:58 INFO - Installing collected packages: blobuploader, requests, docopt 08:22:58 INFO - Running setup.py install for blobuploader 08:22:58 INFO - Running setup.py install for requests 08:22:59 INFO - Running setup.py install for docopt 08:22:59 INFO - Successfully installed blobuploader requests docopt 08:22:59 INFO - Cleaning up... 08:23:00 INFO - Return code: 0 08:23:00 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:00 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02342B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0239BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024585E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D38B48>, '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 0x01D5A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02829660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-187', 'USERDOMAIN': 'T-W864-IX-187', '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-187', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-187', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', '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-187\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:23:00 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 08:23:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:23:00 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Roaming', 08:23:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:23:00 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:23:00 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:23:00 INFO - 'COMPUTERNAME': 'T-W864-IX-187', 08:23:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:23:00 INFO - 'DCLOCATION': 'SCL3', 08:23:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:23:00 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:23:00 INFO - 'HOMEDRIVE': 'C:', 08:23:00 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 08:23:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:23:00 INFO - 'KTS_VERSION': '1.19c', 08:23:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 08:23:00 INFO - 'LOGONSERVER': '\\\\T-W864-IX-187', 08:23:00 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:23:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:00 INFO - 'MOZ_NO_REMOTE': '1', 08:23:00 INFO - 'NO_EM_RESTART': '1', 08:23:00 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:23:00 INFO - 'OS': 'Windows_NT', 08:23:00 INFO - 'OURDRIVE': 'C:', 08:23:00 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', 08:23:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:23:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:23:00 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:23:00 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:23:00 INFO - 'PROCESSOR_LEVEL': '6', 08:23:00 INFO - 'PROCESSOR_REVISION': '1e05', 08:23:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:23:00 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:23:00 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:23:00 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:23:00 INFO - 'PROMPT': '$P$G', 08:23:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:23:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:23:00 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:23:00 INFO - 'PWD': 'C:\\slave\\test', 08:23:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:23:00 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:23:00 INFO - 'SYSTEMDRIVE': 'C:', 08:23:00 INFO - 'SYSTEMROOT': 'C:\\windows', 08:23:00 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:23:00 INFO - 'TEST1': 'testie', 08:23:00 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:23:00 INFO - 'USERDOMAIN': 'T-W864-IX-187', 08:23:00 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 08:23:00 INFO - 'USERNAME': 'cltbld', 08:23:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-187', 08:23:00 INFO - 'WINDIR': 'C:\\windows', 08:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 08:23:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-gi3yf1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 08:23:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 08:23:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-xe1h8h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 08:23:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 08:23:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-klcohm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 08:23:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 08:23:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-0pu30w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 08:23:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 08:23:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-g1t3vl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 08:23:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 08:23:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-p9b1wx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 08:23:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 08:23:01 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-z9msw8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 08:23:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-lpxhrb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 08:23:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-z1cmaw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 08:23:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-3ybuzm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 08:23:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-pf7a0h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 08:23:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-3cxmii-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 08:23:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-e7olwp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 08:23:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-aodkfa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 08:23:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-bl2fxs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 08:23:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ocvcpj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 08:23:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 08:23:02 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ijl1or-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 08:23:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:23:03 INFO - Running setup.py install for manifestparser 08:23:03 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 08:23:03 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 08:23:03 INFO - Running setup.py install for mozcrash 08:23:04 INFO - Running setup.py install for mozdebug 08:23:04 INFO - Running setup.py install for mozdevice 08:23:04 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 08:23:04 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 08:23:04 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 08:23:04 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 08:23:05 INFO - Running setup.py install for mozfile 08:23:05 INFO - Running setup.py install for mozhttpd 08:23:05 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 08:23:05 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Running setup.py install for mozinfo 08:23:06 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Running setup.py install for mozInstall 08:23:06 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 08:23:06 INFO - Running setup.py install for mozleak 08:23:07 INFO - Running setup.py install for mozlog 08:23:07 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 08:23:07 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 08:23:07 INFO - Running setup.py install for moznetwork 08:23:08 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 08:23:08 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 08:23:08 INFO - Running setup.py install for mozprocess 08:23:08 INFO - Running setup.py install for mozprofile 08:23:09 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 08:23:09 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 08:23:09 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 08:23:09 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 08:23:09 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 08:23:09 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 08:23:09 INFO - Running setup.py install for mozrunner 08:23:09 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 08:23:09 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 08:23:10 INFO - Running setup.py install for mozscreenshot 08:23:10 INFO - Running setup.py install for moztest 08:23:10 INFO - Running setup.py install for mozversion 08:23:10 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 08:23:10 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 08:23:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:23:11 INFO - Cleaning up... 08:23:11 INFO - Return code: 0 08:23:11 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:11 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02342B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0239BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024585E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D38B48>, '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 0x01D5A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02829660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-187', 'USERDOMAIN': 'T-W864-IX-187', '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-187', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-187', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', '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-187\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:23:11 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 08:23:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:23:11 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Roaming', 08:23:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:23:11 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:23:11 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:23:11 INFO - 'COMPUTERNAME': 'T-W864-IX-187', 08:23:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:23:11 INFO - 'DCLOCATION': 'SCL3', 08:23:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:23:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:23:11 INFO - 'HOMEDRIVE': 'C:', 08:23:11 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 08:23:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:23:11 INFO - 'KTS_VERSION': '1.19c', 08:23:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 08:23:11 INFO - 'LOGONSERVER': '\\\\T-W864-IX-187', 08:23:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:23:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:11 INFO - 'MOZ_NO_REMOTE': '1', 08:23:11 INFO - 'NO_EM_RESTART': '1', 08:23:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:23:11 INFO - 'OS': 'Windows_NT', 08:23:11 INFO - 'OURDRIVE': 'C:', 08:23:11 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', 08:23:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:23:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:23:11 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:23:11 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:23:11 INFO - 'PROCESSOR_LEVEL': '6', 08:23:11 INFO - 'PROCESSOR_REVISION': '1e05', 08:23:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:23:11 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:23:11 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:23:11 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:23:11 INFO - 'PROMPT': '$P$G', 08:23:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:23:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:23:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:23:11 INFO - 'PWD': 'C:\\slave\\test', 08:23:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:23:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:23:11 INFO - 'SYSTEMDRIVE': 'C:', 08:23:11 INFO - 'SYSTEMROOT': 'C:\\windows', 08:23:11 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:23:11 INFO - 'TEST1': 'testie', 08:23:11 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:23:11 INFO - 'USERDOMAIN': 'T-W864-IX-187', 08:23:11 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 08:23:11 INFO - 'USERNAME': 'cltbld', 08:23:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-187', 08:23:11 INFO - 'WINDIR': 'C:\\windows', 08:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 08:23:11 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-yuycp1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 08:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1)) 08:23:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 08:23:11 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-utgipp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 08:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 08:23:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 08:23:11 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-nvw2av-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 08:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 08:23:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 08:23:11 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-gcf49_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 08:23:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 08:23:12 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-nojipv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5)) 08:23:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 08:23:12 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-4tqpyo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6)) 08:23:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 08:23:12 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-_v1h9z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7)) 08:23:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 08:23:12 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-1qossa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8)) 08:23:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 08:23:12 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-3qd8s4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 08:23:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 08:23:12 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-dryk7p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 08:23:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 08:23:12 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-yapohx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11)) 08:23:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 08:23:12 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-l9hxak-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 08:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12)) 08:23:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 08:23:13 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-x0xxc4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13)) 08:23:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 08:23:13 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-gmtau0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 08:23:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 08:23:13 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-laibpn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 08:23:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 08:23:13 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-xa1pvc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16)) 08:23:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 08:23:13 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-sztrgu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17)) 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 08:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 08:23:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 08:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:17 INFO - Downloading blessings-1.5.1.tar.gz 08:23:17 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 08:23:17 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 08:23:17 INFO - Installing collected packages: blessings 08:23:17 INFO - Running setup.py install for blessings 08:23:17 INFO - Successfully installed blessings 08:23:17 INFO - Cleaning up... 08:23:20 INFO - Return code: 0 08:23:20 INFO - Done creating virtualenv C:\slave\test\build/venv. 08:23:20 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 08:23:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 08:23:21 INFO - Reading from file tmpfile_stdout 08:23:21 INFO - Using _rmtree_windows ... 08:23:21 INFO - Using _rmtree_windows ... 08:23:21 INFO - Current package versions: 08:23:21 INFO - blessings == 1.5.1 08:23:21 INFO - blobuploader == 1.2.4 08:23:21 INFO - docopt == 0.6.1 08:23:21 INFO - manifestparser == 1.1 08:23:21 INFO - mozInstall == 1.12 08:23:21 INFO - mozcrash == 0.16 08:23:21 INFO - mozdebug == 0.1 08:23:21 INFO - mozdevice == 0.46 08:23:21 INFO - mozfile == 1.2 08:23:21 INFO - mozhttpd == 0.7 08:23:21 INFO - mozinfo == 0.8 08:23:21 INFO - mozleak == 0.1 08:23:21 INFO - mozlog == 3.0 08:23:21 INFO - moznetwork == 0.27 08:23:21 INFO - mozprocess == 0.22 08:23:21 INFO - mozprofile == 0.27 08:23:21 INFO - mozrunner == 6.11 08:23:21 INFO - mozscreenshot == 0.1 08:23:21 INFO - mozsystemmonitor == 0.0 08:23:21 INFO - moztest == 0.7 08:23:21 INFO - mozversion == 1.4 08:23:21 INFO - psutil == 3.1.1 08:23:21 INFO - requests == 1.2.3 08:23:21 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02342B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0239BEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024585E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D38B48>, '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 0x01D5A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02829660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-187', 'USERDOMAIN': 'T-W864-IX-187', '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-187', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-187', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', '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-187\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:23:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos 08:23:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:23:21 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Roaming', 08:23:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:23:21 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:23:21 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:23:21 INFO - 'COMPUTERNAME': 'T-W864-IX-187', 08:23:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:23:21 INFO - 'DCLOCATION': 'SCL3', 08:23:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:23:21 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:23:21 INFO - 'HOMEDRIVE': 'C:', 08:23:21 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 08:23:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:23:21 INFO - 'KTS_VERSION': '1.19c', 08:23:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 08:23:21 INFO - 'LOGONSERVER': '\\\\T-W864-IX-187', 08:23:21 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:23:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:21 INFO - 'MOZ_NO_REMOTE': '1', 08:23:21 INFO - 'NO_EM_RESTART': '1', 08:23:21 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:23:21 INFO - 'OS': 'Windows_NT', 08:23:21 INFO - 'OURDRIVE': 'C:', 08:23:21 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', 08:23:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:23:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:23:21 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:23:21 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:23:21 INFO - 'PROCESSOR_LEVEL': '6', 08:23:21 INFO - 'PROCESSOR_REVISION': '1e05', 08:23:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:23:21 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:23:21 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:23:21 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:23:21 INFO - 'PROMPT': '$P$G', 08:23:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:23:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:23:21 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:23:21 INFO - 'PWD': 'C:\\slave\\test', 08:23:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:23:21 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:23:21 INFO - 'SYSTEMDRIVE': 'C:', 08:23:21 INFO - 'SYSTEMROOT': 'C:\\windows', 08:23:21 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:23:21 INFO - 'TEST1': 'testie', 08:23:21 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:23:21 INFO - 'USERDOMAIN': 'T-W864-IX-187', 08:23:21 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 08:23:21 INFO - 'USERNAME': 'cltbld', 08:23:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-187', 08:23:21 INFO - 'WINDIR': 'C:\\windows', 08:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 08:23:21 INFO - Cleaning up... 08:23:21 INFO - Return code: 0 08:23:21 INFO - Running post-action listener: _resource_record_post_action 08:23:21 INFO - Running post-action listener: _start_resource_monitoring 08:23:21 INFO - Starting resource monitoring. 08:23:21 INFO - ##### 08:23:21 INFO - ##### Running install step. 08:23:21 INFO - ##### 08:23:21 INFO - Running pre-action listener: _resource_record_pre_action 08:23:21 INFO - Running main action method: install 08:23:21 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 08:23:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 08:23:21 INFO - Reading from file tmpfile_stdout 08:23:21 INFO - Using _rmtree_windows ... 08:23:21 INFO - Using _rmtree_windows ... 08:23:21 INFO - Detecting whether we're running mozinstall >=1.0... 08:23:21 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 08:23:21 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 08:23:22 INFO - Reading from file tmpfile_stdout 08:23:22 INFO - Output received: 08:23:22 INFO - Usage: mozinstall-script.py [options] installer 08:23:22 INFO - Options: 08:23:22 INFO - -h, --help show this help message and exit 08:23:22 INFO - -d DEST, --destination=DEST 08:23:22 INFO - Directory to install application into. [default: 08:23:22 INFO - "C:\slave\test"] 08:23:22 INFO - --app=APP Application being installed. [default: firefox] 08:23:22 INFO - Using _rmtree_windows ... 08:23:22 INFO - Using _rmtree_windows ... 08:23:22 INFO - mkdir: C:\slave\test\build\application 08:23:22 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win64.zip', '--destination', 'C:\\slave\\test\\build\\application'] 08:23:22 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-44.0a1.en-US.win64.zip --destination C:\slave\test\build\application 08:23:23 INFO - Reading from file tmpfile_stdout 08:23:23 INFO - Output received: 08:23:23 INFO - C:\slave\test\build\application\firefox\firefox.exe 08:23:23 INFO - Using _rmtree_windows ... 08:23:23 INFO - Using _rmtree_windows ... 08:23:23 INFO - Running post-action listener: _resource_record_post_action 08:23:23 INFO - ##### 08:23:23 INFO - ##### Running run-tests step. 08:23:23 INFO - ##### 08:23:23 INFO - Running pre-action listener: _resource_record_pre_action 08:23:23 INFO - Running main action method: run_tests 08:23:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 08:23:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 08:23:23 INFO - Python 2.7.3 08:23:23 INFO - Return code: 0 08:23:23 INFO - grabbing minidump binary from tooltool 08:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:23 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01D38B48>, '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 0x01D5A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02829660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 08:23:23 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 08:23:23 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 08:23:23 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 08:23:34 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpxq66w9 08:23:34 INFO - INFO - File integrity verified, renaming tmpxq66w9 to win32-minidump_stackwalk.exe 08:23:34 INFO - Return code: 0 08:23:34 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 08:23:34 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 08:23:34 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 08:23:34 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 08:23:34 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 08:23:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-187', '--webServer', 'localhost'] in C:\slave\test\build 08:23:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip --title T-W864-IX-187 --webServer localhost 08:23:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:23:34 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Roaming', 08:23:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 08:23:34 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 08:23:34 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 08:23:34 INFO - 'COMPUTERNAME': 'T-W864-IX-187', 08:23:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:23:34 INFO - 'DCLOCATION': 'SCL3', 08:23:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:23:34 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:23:34 INFO - 'HOMEDRIVE': 'C:', 08:23:34 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-187', 08:23:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:23:34 INFO - 'KTS_VERSION': '1.19c', 08:23:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-187\\AppData\\Local', 08:23:34 INFO - 'LOGONSERVER': '\\\\T-W864-IX-187', 08:23:34 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 08:23:34 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 08:23:34 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:23:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:34 INFO - 'MOZ_NO_REMOTE': '1', 08:23:34 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 08:23:34 INFO - 'NO_EM_RESTART': '1', 08:23:34 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:23:34 INFO - 'OS': 'Windows_NT', 08:23:34 INFO - 'OURDRIVE': 'C:', 08:23:34 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts', 08:23:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:23:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:23:34 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 08:23:34 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 08:23:34 INFO - 'PROCESSOR_LEVEL': '6', 08:23:34 INFO - 'PROCESSOR_REVISION': '1e05', 08:23:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:23:34 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 08:23:34 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 08:23:34 INFO - 'PROGRAMW6432': 'C:\\Program Files', 08:23:34 INFO - 'PROMPT': '$P$G', 08:23:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:23:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:23:34 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:23:34 INFO - 'PWD': 'C:\\slave\\test', 08:23:34 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 08:23:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:23:34 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:23:34 INFO - 'SYSTEMDRIVE': 'C:', 08:23:34 INFO - 'SYSTEMROOT': 'C:\\windows', 08:23:34 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:23:34 INFO - 'TEST1': 'testie', 08:23:34 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 08:23:34 INFO - 'USERDOMAIN': 'T-W864-IX-187', 08:23:34 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-187', 08:23:34 INFO - 'USERNAME': 'cltbld', 08:23:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-187', 08:23:34 INFO - 'WINDIR': 'C:\\windows', 08:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:23:34 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-187', '--webServer', 'localhost'] with output_timeout 3600 ProcessManager UNABLE to use job objects to manage child processes ProcessManager NOT managing child processes 08:23:35 INFO - mozversion INFO | application_buildid: 20151026063032 08:23:35 INFO - mozversion INFO | application_changeset: 076830f89d148b20422c6aa4d239fd787ec9c4b6 08:23:35 INFO - mozversion INFO | application_display_name: Nightly 08:23:35 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:23:35 INFO - mozversion INFO | application_name: Firefox 08:23:35 INFO - mozversion INFO | application_remotingname: firefox 08:23:35 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 08:23:35 INFO - mozversion INFO | application_vendor: Mozilla 08:23:35 INFO - mozversion INFO | application_version: 44.0a1 08:23:35 INFO - mozversion INFO | platform_buildid: 20151026063032 08:23:35 INFO - mozversion INFO | platform_changeset: 076830f89d148b20422c6aa4d239fd787ec9c4b6 08:23:35 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 08:23:35 INFO - mozversion INFO | platform_version: 44.0a1 08:23:35 INFO - 2015-10-26 08:23:35,615 DEBUG : using testdate: 1445873015 08:23:35 INFO - 2015-10-26 08:23:35,615 DEBUG : actual date: 1445873015 08:23:36 INFO - 2015-10-26 08:23:36,763 INFO : starting webserver on 'localhost:49276' 08:23:36 INFO - 2015-10-26 08:23:36,766 INFO : Starting test suite T-W864-IX-187 08:23:36 INFO - 2015-10-26 08:23:36,766 INFO : Starting test dromaeo_css 08:23:36 INFO - 2015-10-26 08:23:36,766 DEBUG : operating with platform_type : w8_ 08:23:36 INFO - 2015-10-26 08:23:36,766 INFO : Initialising browser for dromaeo_css test... 08:23:36 INFO - 2015-10-26 08:23:36,848 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmptcykf0\profile http://localhost:49276/getInfo.html 08:23:44 INFO - 2015-10-26 08:23:44,279 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:23:44 INFO - 2015-10-26 08:23:44,279 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:23:44 INFO - 2015-10-26 08:23:44,279 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 08:23:44 INFO - 2015-10-26 08:23:44,279 DEBUG : BROWSER_OUTPUT: __metrics 08:23:44 INFO - 2015-10-26 08:23:44,466 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:23:45 INFO - 2015-10-26 08:23:45,293 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: console.error: 08:23:45 INFO - 2015-10-26 08:23:45,523 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] 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: Stack: 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 08:23:45 INFO - 2015-10-26 08:23:45,523 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 08:23:45 INFO - 2015-10-26 08:23:45,525 DEBUG : BROWSER_OUTPUT: 08:23:46 INFO - 2015-10-26 08:23:46,285 INFO : Browser initialized. 08:23:46 INFO - 2015-10-26 08:23:46,285 INFO : Running cycle 1/1 for dromaeo_css test... 08:23:46 INFO - 2015-10-26 08:23:46,285 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmptcykf0\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:23:57 INFO - 2015-10-26 08:23:57,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 179650560 08:23:57 INFO - 2015-10-26 08:23:57,857 DEBUG : BROWSER_OUTPUT: 08:24:49 INFO - 2015-10-26 08:24:49,296 DEBUG : BROWSER_OUTPUT: 1445873089295 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] 08:26:58 INFO - 2015-10-26 08:26:58,494 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 08:26:59 INFO - 2015-10-26 08:26:59,526 DEBUG : BROWSER_OUTPUT: RSS: Main: 240062464 08:26:59 INFO - 2015-10-26 08:26:59,526 DEBUG : BROWSER_OUTPUT: 08:30:02 INFO - 2015-10-26 08:30:02,638 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 08:30:03 INFO - 2015-10-26 08:30:03,203 DEBUG : BROWSER_OUTPUT: RSS: Main: 230412288 08:30:03 INFO - 2015-10-26 08:30:03,203 DEBUG : BROWSER_OUTPUT: 08:33:05 INFO - 2015-10-26 08:33:05,513 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 08:33:06 INFO - 2015-10-26 08:33:06,086 DEBUG : BROWSER_OUTPUT: RSS: Main: 192303104 08:33:06 INFO - 2015-10-26 08:33:06,086 DEBUG : BROWSER_OUTPUT: 08:36:08 INFO - 2015-10-26 08:36:08,380 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 08:36:08 INFO - 2015-10-26 08:36:08,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 165093376 08:36:08 INFO - 2015-10-26 08:36:08,944 DEBUG : BROWSER_OUTPUT: 08:39:11 INFO - 2015-10-26 08:39:11,848 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 08:39:12 INFO - 2015-10-26 08:39:12,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 169521152 08:39:12 INFO - 2015-10-26 08:39:12,414 DEBUG : BROWSER_OUTPUT: 08:42:15 INFO - 2015-10-26 08:42:15,437 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 08:42:15 INFO - 2015-10-26 08:42:15,680 DEBUG : BROWSER_OUTPUT: RSS: Main: 196972544 08:42:15 INFO - 2015-10-26 08:42:15,680 DEBUG : BROWSER_OUTPUT: 08:42:15 INFO - 2015-10-26 08:42:15,681 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:42:15 INFO - 2015-10-26 08:42:15,681 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:42:15 INFO - 2015-10-26 08:42:15,681 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:42:15 INFO - 2015-10-26 08:42:15,681 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:42:15 INFO - 2015-10-26 08:42:15,683 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2551.4769472258185;2607;2632.9868350658244;2661.6939052009025;2664.174106027131;4732.219183834663;4736.218523943554;4737.8815529611775;4751.857444276673;4805.0389922015565;4747.76261186941;4754.310624959378;4761.095391875541;4806.591439727627;4817.975910120454;4203.600657937491;4313.698041137121;4320.071184695305;4321.438213032301;4328.740275583459;4326.632236259906;4327.805248763798;4328.4156638853665;4330.56694330566;4333.2416827055295;4328.935065974013;4334.371516130159;4337.978310108445;4342.804573794171;4346.413234213388;4313.956860431403;4317.676174286909;4335.891602709926;4339.284018137004;4343.087951530182;4284.592963668447;4294.957050429487;4313.09425020746;4313.762743049131;4319.632831209319;4570;4614.400127983375;4674.555917187861;4688.859334219979;4709.128811169944;4932.975335123338;4979.327790748239;4980.327655766461;4980.402351717781;5018.197088465828;4804;4954.628402869763;4996.550310472038;5001.699898006132;5005.974970125162;4432.822687092513;4540.818367265339;4541.341505481687;4547.522510136421;4669.112868554964;5047.646664733506;5067.721275329893;5078.771455284521;5080.77136528857;5140.306058682105;4790.233562630032;4871.074495845778;4874.5856602189215;4879.829205977774;4891.559759621651;4786.425628924481;4832.613390928717;4915.36100306965;4918.729469879119;4937.654364194472;4869.2939523768855;4894.975525122351;4905.607551395879;4928.260760885824;4992.375953005874;4866.31871537985;4906.239532872411;4910.263460480956;4916.164252077156;4964.851054468371;3196.408664397094;3253.6258330291844;3288.7369010479106;3293.2754793945774;3294.670532946734;2983.731464168235;3016.4117996990817;3045.74111200546;3048.512238041947;3051.2371907023244;4578.908421831545;4586.770661466913;4625.259958406706;4648.162665846363;4709.434867815816;4644.117617652635;4818.397700287464;4823.734694591831;4929.950700493021;4985.476524964899;4544.295634176708;4562.315652652129;4600.72395656255;4620.445546534437;4658.767061646904;4124.6906482013965;4143.4199212110325;4146.3365861462025;4177.853775117917;4180.352045432963;4746.857594272177;4800.423949126127;4874.073925954058;5015.122353588107;5023.547880690755;4787.952120478751;4894.070126675921;4904.681195722266;4905.852824415273;4950.6782059166035;4933.679310844784;4959.553640172402;5005.7997680092385;5024.0956627807345;5067.898642027212;4714.080754252887;4832.1543729846435;4835.057163853015;4893.73084480357;4965.577925876197;4834.564889159992;4881.8535443936735;4898.804047838189;4900.019996000743;4954.355933728592;4818.759062046814;4865.9756701216265;4874.634402419903;4877.87805304856;4940.110780059624;4647.16351056813;4756.785944632569;4780.067886762187;4877.51334630533;4951.554360107608;4676.3686902267755;4686.6016388605985;4704.717716936994;4753.239481682914;4782.306565548046;4782.115308667908;4790.640701947298;4826.1795494766675;4842.322074909445;4844.854654360375;4609.354690343422;4728.456227533878;4730.2431610942085;4782.187028205283;4856.125897338513 08:42:15 INFO - 2015-10-26 08:42:15,683 DEBUG : BROWSER_OUTPUT: |1;ext.html;563.1946316767023;565.0958466453675;566.4448840136666;571.5856004396816;580.5529742098581;29953.101406957798;30073.24816879579;37195.442068369;37712.81143594285;37873.43189852154;39050;39366;39712.60287397125;39883;39917.00207494814;7487.288707572772;8342.91657083429;8490.78773030673;8501;8534.274586660142;7559;10196.643117490888;10356;10390.688279351632;10421.27051106423;9857.260705447085;9949.95025024874;9951.950240248789;9952.303338766289;9989.400635961829;7621.161672216052;7786.922130778677;9996.750081247954;10261.333013354146;10305.227107966932;10127.898721012769;10162.59349626014;10277.383356998604;10282.280240383176;10288.537015834305;37488.062798429986;37988;37991.43012854809;38001;38013.42979855304;2917.547780094089;2940.7206315400217;2993.491106511898;3013.7095807186697;3589.587197472298;28021.57967630487;28401.431971360664;28416.31130863405;28851;29111.272218194503;2672;2682.8926842926257;2700.878460469264;2728.7544121029205;2745.8215216010894;234481;234777.99999999828;235101;235998;312457;45109.323360149625;51735.74132129315;52514.47485525172;52789.47210527922;52877.73561132247;55664.44335556593;55738.44261557414;55871.72064139653;55905;56101.719491402284;11316.20786544934;11329.886701133048;11348.829767553494;11391.202615816814;11391.544338226544;9049.230815380766;9073.909260907438;9118.680846170351;9119.544022798833;9120.81758364829;16886.74669879953;17047.829521704873;17057.31770729182;17138.228779704943;17187.828121718874;7256.455765817479;7452.403807695371;7453.627318634155;7489.176190619028;7497.587632680255;840.0801122770556;852.7100785732756;893.2764460786783;953.580424613168;960.2366118935463;24081.397965051015;25676.3580910475;25721.871390642926;25723.74276257251;25862.482750344887;905.959231834569;953.4875602786568;979.9412035277907;1002.981973297104;1070.0957690751302;13433.39549720265;13515.864841351657;13572.93213533926;13679.589612311647;13710.971677124056;352800;354548;357484;358178.90048142284;360365;46340.768296158305;46860;46949.5305046952;46985.295220571716;46991.76504117458;1134.121056181466;1168.3282112785353;1225.5342969671467;1241.3544956622507;1250.593557093948;21313.89343053275;24608.507829842943;24681.259562213163;24696.13563525268;24703.62944555797;39034.6096539031;39335.60664393319;39340.2131957365;39406.802965984985;39451.21097577975;205689;209342;219865;221834;233371;26175.20459533319;26254;26508.602370964065;26581.867090664422;26692.93228270925;35860.462093068105;36313;36354.636453635125;36397.090072747334;36641;32326.515102273956;33268.20151219183;33492.66507334895;33696.663033370336;33829.32341353208;29989.700102999563;31772.854010180563;32136.357272853944;32489.35021299608;32647.673523265414;28755.5686664696;29075.56386654244;32896;32942;33775.32449351048;24511;25980.480390392466;26105.347366315993;26526.86736566305;26661;23272.883635581715;23295;23655.408614784767;23669.28992130239;23950.760492394853 08:42:15 INFO - 2015-10-26 08:42:15,688 DEBUG : BROWSER_OUTPUT: |2;jquery.html;340.4467739922625;345.8448781071222;345.89968909016386;346.21063974138957;350.5320397269645;13240.602781916534;13391;14919.925400372997;15212.695746085072;16428.91785541072;14893;15034.398624055024;15043.323050462728;15372.538823835275;15433.996790208628;5034.320366750497;5058.140116180249;5152.072626927152;5152.252923326116;5152.278680984665;3853.9036524086982;3937.0551067743822;5037.49625037497;5589.329280486348;5758.971205143968;5679.375268720458;5704.65772053676;5711.343195532525;5711.885762284773;5719.14212868069;5553.944460555404;5691.260136182291;5692.57305702074;5701.657900525982;5714.200011998323;4905.558499735005;4926.261060840867;4983.551480366748;4989.151844186497;5000.67495612784;17542.21060052301;17756.644867102714;18050.90974545119;18079.638407231912;18088.366907158317;1806.115003648216;1876.099472253326;1941.7766956799867;2004.188303736989;2104.063691657213;12011.939940300243;12064.853838885292;12181.025517958631;12247;12429.773876881813;1761.4539492757285;1779.217144456435;1791.7222830461299;1796.3712700554702;1829.140304057091;51237;51789.482105178846;52187;52795.73602132003;52821;8718.564071796385;8930.687425940061;9097.681581144629;9178.219851312713;9234.399764015317;11771.352575608424;11792.23350482216;11792.587259445878;11801.173917825841;11803.940980295216;6551.443127334134;6559.737610495622;6571.868562628721;6586.209654841449;6667.033280174444;6182.752689892444;6184.721687524072;6213.596116252429;6239.251289845248;6251.156093927352;5444.265024221759;5480.232767412565;5508.091164957737;5515.090010148361;5544.77820887168;1588.8728901688069;1665.5586269638557;1689.9691188375082;1723.8017627972943;1816.273490603769;385.4218671992012;391.40506430226463;397.4435789894171;401.64655103509307;956.2827879090682;8641.783955401166;10116.190704743603;10557.260991730505;10632.25574209798;10668.14654827612;393.1664870474555;399.03831765445716;417.507341337225;437.42478640587524;460.7557994263046;5496.532794712414;5597.3003374578175;5683.2895888014;5780.104083867007;5917.585768996215;316086;422765;442794;443474;443579;57963;60328;61022.38977610256;61116.694416527636;61639.38360616426;436.511107559529;440.2537698600905;443.3040127000578;443.7160217460828;444.1117764471058;9552.312884653618;10210;10308.690739277834;10310.381377117397;10380.636677716244;28173.71826281711;28950;29286.853565732035;29346.706532934397;30185.396292073598;37528;37694;38010.61989380181;38045.624753153745;38356.616433835305;6022.247219097613;6048.637081775108;6127.908081378872;6128.417800308964;6161.291451483136;8986.775330616787;10959.890401095887;11321;11362.886371136514;11464;7289.5261807982315;8971.910280897107;9184.49485278316;9187.908120918706;9440;9435.150836424755;9445.52772361393;9659.082387173208;9706.126448619376;9827.361221520578;9536.523173841419;9542.99798521173;9578.808423831346;9756.317057805885;9876.604935802487;5796.594238403271;5799.101139323411;5848.2689663792025;5853.151293062397;5877.089051197071;9359.5788189533;9439.197668198005;9454.574544145393;9673.467959262309;9783.385741352597 08:42:15 INFO - 2015-10-26 08:42:15,690 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1305.5104335874048;1434.6556826361677;1569.364407414997;1592.673501932104;1766.9734953975692;7624.237576242373;8149.633266503007;8318.37612179086;8404.579771011455;8433.662653493853;8133.633986470608;8227.91772082279;8234.91765082349;8239.958800206006;8246.876296855551;1851.4445666300123;1892.7444794952644;2114.598226336999;2123.3205374280237;2148.194427089841;2110.4618322327783;2112.8943552822375;2134.349023547817;2144.806967372936;2144.8713077215416;2067.1421360135528;2105.210546045233;2114.186038375229;2115.894205289737;2116.5555233401005;1836.6602178596936;1863.67385707502;2096.4549217203635;2157.7518585362727;2158.417227348623;2125.6917746926765;2160.2115227941845;2161.9351419457444;2178.553396553703;2182.2689399051337;8540.658373665046;8647.351448641377;8651.913480865174;8653.826923461558;8684.13158684133;4661.836835710767;4786.090642777861;4837.661363704542;4842.975785121052;4843;2703.6079768433665;2751.3259251483514;2774.153883065664;2779.4441111777523;2805.8597070146616;809.3161278719532;814.2752949874616;820.4052062254817;834.5076404921126;836.4395854777314;12356.752864942651;12393.132480726354;12407.317597532132;12594.43325050375;12600.74798504034;4007.358822588372;4037.798110094542;4044.292248856438;4087.89780255496;4274.658027357867;10286.691399258034;10397.58409663605;10409.895901040893;10430.68707938763;10449.634262800766;4715.74063426508;4781;4782.856514304576;4786.8085276589245;4810.687305325143;4238.639715624145;4256.446661933929;4293.635041021485;4299.484061912591;4300.462442194726;3732.1789206374556;3754.981225093857;3763.8118094095184;3772.679322257584;3785.6782173515007;1144.8282757586428;1158.3976332307152;1163.5345861655237;1170.6722117807028;1170.9238899471507;833.3458235285271;859.4671303791689;1024.6823484719762;1175.5474142455216;1179.8466199394024;7922.445428819928;8522.829543409098;8563.529005904611;8577.614007369559;8587.27008204297;657.2080642825384;662.7282814046218;663.1279866974912;670.815525730428;675.392147067643;5915.40845915405;5940.554458415636;5943.078822782476;5946;5954.821355359346;10140;10224.539895704711;10316;10546.209034322457;10571.15430765537;10469.68590942273;10482.161427085815;10487.685369438928;10506.212034097472;10515.57937682484;7501;7718.961405192938;7791.376689864797;7820.374370050382;7823.452358334862;7492.363149132167;7671.808204794925;7695.192004839635;7698.884516732365;7721.4594978353225;8833.646654133763;8890.1554352339;8897;8905.73282801517;8928.241099506355;9028.954855225682;9144.817103657757;9185.77035574116;9216.539173041456;9311.068893110634;7316.378107860892;7325.706971721072;7336.96331518339;7358.22738612438;7379.188289288281;7917.168697286932;7965.084015338311;8016.198380161937;8168.428210025479;8188.385871059813;7368.521046131984;7399.48203625741;7616.923830761843;7651.234876512304;7669.923300766921;7119.038929744418;7123.572585644878;7273;7324.7070117196845;7381.372583330477;6837.35045170708;6957.478189135733;7386.482946193715;7579.54522728638;7631.809204769703;6826.726930922906;6854.2117656470145;6916.792496225121;6957.686904089227;6964.129483814523;6899.103116594811;6956.40870526011;6984.3364880336285;7049.400800931979;7189.460790440842 08:42:15 INFO - 2015-10-26 08:42:15,759 DEBUG : BROWSER_OUTPUT: |4;prototype.html;297.0760933496824;304.82472578267505;308.551058210304;311.17228173059664;311.2110799124219;4921.384826896638;4999.375078115236;5035;5039.59683225342;5041.647084704072;4927.408710954682;4955.9008819823675;4977.104121258181;4985;4985.925211121836;3727.235916637083;3785.9432108518395;3855.4602355670227;3862.748921320105;3872.4384964180176;3785.6403641653997;3814.6757525610356;3854.1906199698096;3857.5534174684494;3857.749246298996;3790.6967442604664;3811.7712937223855;3812.9809350953205;3815.4467602197665;3815.503984482027;3664.8900532984053;3711.1278849470355;3738.327101121797;3806.5812760596314;3833.5399752029657;3326.550915626408;3350.2294472271515;3352.329534093167;3356.7146792522667;3359.1266270769534;4780.426348838127;4796.928046079312;4799;4799.880002999918;4808.61531077513;4258.893527661802;4300;4304.7632380219075;4306.74159550428;4309.095090031097;4889.266610008492;5061.417936937262;5062.392512898439;5063.240513922941;5075.974620126875;3625.583057948343;3626.1478552540134;3629.038304849217;3629.963700362989;3631.4371272452813;5308.469153084699;5380.946190538084;5383.838484845422;5385.973070134663;5399.865003374908;3686.170611612366;3701.722370822199;3739.719521035933;3743.12036671382;3746.119661879456;3778.886633401002;3784.8864534064023;3793.9241215175543;3795.4876091727265;3798.1454172810895;4193.958060419418;4245.80893859777;4284.335927931175;4292.871213863589;4325.091730736581;5133.640645154804;5135.435102138762;5135.666181698178;5137.974310128426;5147.742612869341;1337.525178561616;1353.235421986594;1356.5455572383362;1358.2936872826076;1361.4009835672273;914.8902131744234;917.1791246834047;928.5960607135918;936.3024546712743;942.4675058591873;4900.656954013256;4920.704757714549;4930.556249937523;4935.506449355035;4938.975305123523;5288.206768984683;5314.1497360422145;5315.946840531623;5319.202119682079;5329.440408757102;4888.120138375056;4922.507749225048;4927.5811556017425;4928.408590969035;4928.852134435972;2858.814177078483;2896.0732565578815;2900.274931267183;2918.1683220282544;2919.7664186865;5336.62643614951;5338.837770702871;5340.8664783379945;5342.412334643186;5355.384130825005;5434.239206511092;5438.564914806806;5439.102548079522;5441.945580544223;5448.482394172547;5336.332958380202;5338.279332290169;5339.626226164209;5344.198370244494;5345.65253258537;5199.48005199477;5240.161574148118;5240.318758561362;5312.28284181646;5323.680579165108;5116;5161.638685292144;5177.430482646831;5186.118359878754;5190.870228244324;5319.867003324948;5322.09524380864;5324.494173053554;5326.8934621308135;5329.360476742816;3711.406175011985;3727.422249551324;3734.738568300193;3738.8878333648945;3742.7567208131386;5153.072446959583;5276.287701160448;5279.920801188062;5280.762365693477;5301.655392399482;5100.897982040264;5131.769070391767;5134.922976155286;5140.30605868203;5142.948570514247;5125.769340379767;5134.332536770196;5137.434882163034;5145.07388670043;5147.433782284021;4997.125503036881;5078.5175408336145;5087.3386459759995;5089.643724939218;5108.9782043590685;5032.723200224023;5158.174692049254;5165.9483405165465;5170.560502357192;5178.689278643294;5015.9968006399595;5079.390473143247;5125.692458452505;5138.588912886886;5143.8456846294675 08:42:15 INFO - 2015-10-26 08:42:15,762 DEBUG : BROWSER_OUTPUT: |5;yui.html;759.6961215513794;759.7037155509352;786.3512602103262;789.7472808701215;799.8480288745135;10495.790084198312;10839.512221950012;10855.945720271407;10856.945715271422;10944.45277736112;10542.683719488408;10568.524416401262;10643.893561064388;10650.62722804702;10668.093212076963;609.3632154398653;633.7053270229343;636.39542434687;748.8839229919344;776.4138075752799;753.9924600754005;757.2389748302948;762.3824701991377;762.6301243896705;762.7673559564331;806.0810675829559;814.2346194577108;814.8044469327349;815.1725998111921;815.4454970619976;652.0545209446298;663.6416335179022;787.5235482533043;797.8603744344774;802.8233788566506;587.3539106982328;591.6745789815584;593.7209511529618;594.9553783466214;595.0449528506771;11062.723431914186;11183.273087249303;11225.887741122648;11239;11240.156988225834;8577.871331930026;9810.362326448758;9943.950280248624;10181.03280188381;10227;8676.479411235347;8722.08418116095;8891.777705557348;8950;9059;2786.4845003674386;2958.1421387797513;2995.086498617921;3011.864466099031;3054.4501989641853;30086.84956575225;30726.385472290876;30876.38247235065;31124.68875311263;31212.531812022837;6410.8076757697345;6521.47828173745;6561.114249576246;6634.966825165843;6684.231313399021;10157.136643385247;10167.034131757471;10200.132988695896;10313;10335.534900929477;5552.361478429952;5555.694436805934;5643.238162848045;5722.656640601494;5723.8569035774435;3402.336544373873;3411.5906091269208;3459.552152499488;3520.119970007498;3681.5398075240596;2282.988585057064;2336.9883150584137;2338.216697406388;2338.3686404670643;2339.9649005264932;394.56597742483615;396.5439744294085;397.4793021142313;397.80308747170335;402.57729384147115;442.40717438632385;444.3334997503744;448.67022738287335;453.88879724467716;456.6346922462084;7837.960810195913;7857.724979625686;8040.597970101471;8122.350211983027;8294.460860044077;429.5017779375971;431.47791172681406;448.54396872100386;449.6627529352985;462.1657907476969;3471.3057388522398;3482.460218666111;3498.9825050874583;3534.5228394166975;3567.982160089183;39961;40000.199995999916;40216;40234;40261.39607905884;33313.3337333252;34480.13799654978;34716.826415867756;35081.64918350835;35151.4727279091;29483.557746633818;29875;30064.849675751917;30128.397432051235;30302.545461818092;7118.3237592430705;7420.183779784112;7474.327310542078;7492.51298665585;7723.034620672416;21541.892290539075;21865.781342186376;22022;22039.559208816056;22085.33743987683;27606.171814845588;27853.86073069622;28070;28073.298167545974;28089.297767555974;6595.571287866273;6606.900896486461;6765.763198288037;6803.1836179656775;6901.206361268318;10270.640527581798;10442.582296708046;10486;10533.36799792015;10676.359418434919;9404.670836520689;9909.801803964025;9926.205903527556;10002.849957250794;10280.023397777199;9786.381577642496;9881.752956176153;9960.601575936882;9975.900240997787;10483.52824122901;9377.671781487616;9430.434173949585;9585.712428627152;9593.13661770444;9831.85252221203;8150.7147249845975;8213.466124701876;8252.05101413345;8440.704575339834;8451.8732219018;7626.9618651906385;7774.6890124394395;7783.221677832172;7783.416243781626;7991.200879911962 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,783 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: __startTimestamp1445874135680__endTimestamp 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4561.52 Median:4756.79 stddev:563.99 (11.9%) stddev-sans-first:543.22 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: Values: 2551.5 2607.0 2633.0 2661.7 2664.2 4732.2 4736.2 4737.9 4751.9 4805.0 4747.8 4754.3 4761.1 4806.6 4818.0 4203.6 4313.7 4320.1 4321.4 4328.7 4326.6 4327.8 4328.4 4330.6 4333.2 4328.9 4334.4 4338.0 4342.8 4346.4 4314.0 4317.7 4335.9 4339.3 4343.1 4284.6 4295.0 4313.1 4313.8 4319.6 4570.0 4614.4 4674.6 4688.9 4709.1 4933.0 4979.3 4980.3 4980.4 5018.2 4804.0 4954.6 4996.6 5001.7 5006.0 4432.8 4540.8 4541.3 4547.5 4669.1 5047.6 5067.7 5078.8 5080.8 5140.3 4790.2 4871.1 4874.6 4879.8 4891.6 4786.4 4832.6 4915.4 4918.7 4937.7 4869.3 4895.0 4905.6 4928.3 4992.4 4866.3 4906.2 4910.3 4916.2 4964.9 3196.4 3253.6 3288.7 3293.3 3294.7 2983.7 3016.4 3045.7 3048.5 3051.2 4578.9 4586.8 4625.3 4648.2 4709.4 4644.1 4818.4 4823.7 4930.0 4985.5 4544.3 4562.3 4600.7 4620.4 4658.8 4124.7 4143.4 4146.3 4177.9 4180.4 4746.9 4800.4 4874.1 5015.1 5023.5 4788.0 4894.1 4904.7 4905.9 4950.7 4933.7 4959.6 5005.8 5024.1 5067.9 4714.1 4832.2 4835.1 4893.7 4965.6 4834.6 4881.9 4898.8 4900.0 4954.4 4818.8 4866.0 4874.6 4877.9 4940.1 4647.2 4756.8 4780.1 4877.5 4951.6 4676.4 4686.6 4704.7 4753.2 4782.3 4782.1 4790.6 4826.2 4842.3 4844.9 4609.4 4728.5 4730.2 4782.2 4856.1 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:42571.56 Median:32568.51 stddev:74115.75 (227.6%) stddev-sans-first:74256.55 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: Values: 563.2 565.1 566.4 571.6 580.6 29953.1 30073.2 37195.4 37712.8 37873.4 39050.0 39366.0 39712.6 39883.0 39917.0 7487.3 8342.9 8490.8 8501.0 8534.3 7559.0 10196.6 10356.0 10390.7 10421.3 9857.3 9950.0 9952.0 9952.3 9989.4 7621.2 7786.9 9996.8 10261.3 10305.2 10127.9 10162.6 10277.4 10282.3 10288.5 37488.1 37988.0 37991.4 38001.0 38013.4 2917.5 2940.7 2993.5 3013.7 3589.6 28021.6 28401.4 28416.3 28851.0 29111.3 2672.0 2682.9 2700.9 2728.8 2745.8 234481.0 234778.0 235101.0 235998.0 312457.0 45109.3 51735.7 52514.5 52789.5 52877.7 55664.4 55738.4 55871.7 55905.0 56101.7 11316.2 11329.9 11348.8 11391.2 11391.5 9049.2 9073.9 9118.7 9119.5 9120.8 16886.7 17047.8 17057.3 17138.2 17187.8 7256.5 7452.4 7453.6 7489.2 7497.6 840.1 852.7 893.3 953.6 960.2 24081.4 25676.4 25721.9 25723.7 25862.5 906.0 953.5 979.9 1003.0 1070.1 13433.4 13515.9 13572.9 13679.6 13711.0 352800.0 354548.0 357484.0 358178.9 360365.0 46340.8 46860.0 46949.5 46985.3 46991.8 1134.1 1168.3 1225.5 1241.4 1250.6 21313.9 24608.5 24681.3 24696.1 24703.6 39034.6 39335.6 39340.2 39406.8 39451.2 205689.0 209342.0 219865.0 221834.0 233371.0 26175.2 26254.0 26508.6 26581.9 26692.9 35860.5 36313.0 36354.6 36397.1 36641.0 32326.5 33268.2 33492.7 33696.7 33829.3 29989.7 31772.9 32136.4 32489.4 32647.7 28755.6 29075.6 32896.0 32942.0 33775.3 24511.0 25980.5 26105.3 26526.9 26661.0 23272.9 23295.0 23655.4 23669.3 23950.8 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: 08:42:15 INFO - 2015-10-26 08:42:15,763 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:22561.89 Median:4986.35 stddev:68124.73 (1366.2%) stddev-sans-first:68295.41 08:42:15 INFO - 2015-10-26 08:42:15,765 DEBUG : BROWSER_OUTPUT: Values: 340.4 345.8 345.9 346.2 350.5 13240.6 13391.0 14919.9 15212.7 16428.9 14893.0 15034.4 15043.3 15372.5 15434.0 5034.3 5058.1 5152.1 5152.3 5152.3 3853.9 3937.1 5037.5 5589.3 5759.0 5679.4 5704.7 5711.3 5711.9 5719.1 5553.9 5691.3 5692.6 5701.7 5714.2 4905.6 4926.3 4983.6 4989.2 5000.7 17542.2 17756.6 18050.9 18079.6 18088.4 1806.1 1876.1 1941.8 2004.2 2104.1 12011.9 12064.9 12181.0 12247.0 12429.8 1761.5 1779.2 1791.7 1796.4 1829.1 51237.0 51789.5 52187.0 52795.7 52821.0 8718.6 8930.7 9097.7 9178.2 9234.4 11771.4 11792.2 11792.6 11801.2 11803.9 6551.4 6559.7 6571.9 6586.2 6667.0 6182.8 6184.7 6213.6 6239.3 6251.2 5444.3 5480.2 5508.1 5515.1 5544.8 1588.9 1665.6 1690.0 1723.8 1816.3 385.4 391.4 397.4 401.6 956.3 8641.8 10116.2 10557.3 10632.3 10668.1 393.2 399.0 417.5 437.4 460.8 5496.5 5597.3 5683.3 5780.1 5917.6 316086.0 422765.0 442794.0 443474.0 443579.0 57963.0 60328.0 61022.4 61116.7 61639.4 436.5 440.3 443.3 443.7 444.1 9552.3 10210.0 10308.7 10310.4 10380.6 28173.7 28950.0 29286.9 29346.7 30185.4 37528.0 37694.0 38010.6 38045.6 38356.6 6022.2 6048.6 6127.9 6128.4 6161.3 8986.8 10959.9 11321.0 11362.9 11464.0 7289.5 8971.9 9184.5 9187.9 9440.0 9435.2 9445.5 9659.1 9706.1 9827.4 9536.5 9543.0 9578.8 9756.3 9876.6 5796.6 5799.1 5848.3 5853.2 5877.1 9359.6 9439.2 9454.6 9673.5 9783.4 08:42:15 INFO - 2015-10-26 08:42:15,766 DEBUG : BROWSER_OUTPUT: 08:42:15 INFO - 2015-10-26 08:42:15,766 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5779.69 Median:5944.54 stddev:3276.47 (55.1%) stddev-sans-first:3268.40 08:42:15 INFO - 2015-10-26 08:42:15,766 DEBUG : BROWSER_OUTPUT: Values: 1305.5 1434.7 1569.4 1592.7 1767.0 7624.2 8149.6 8318.4 8404.6 8433.7 8133.6 8227.9 8234.9 8240.0 8246.9 1851.4 1892.7 2114.6 2123.3 2148.2 2110.5 2112.9 2134.3 2144.8 2144.9 2067.1 2105.2 2114.2 2115.9 2116.6 1836.7 1863.7 2096.5 2157.8 2158.4 2125.7 2160.2 2161.9 2178.6 2182.3 8540.7 8647.4 8651.9 8653.8 8684.1 4661.8 4786.1 4837.7 4843.0 4843.0 2703.6 2751.3 2774.2 2779.4 2805.9 809.3 814.3 820.4 834.5 836.4 12356.8 12393.1 12407.3 12594.4 12600.7 4007.4 4037.8 4044.3 4087.9 4274.7 10286.7 10397.6 10409.9 10430.7 10449.6 4715.7 4781.0 4782.9 4786.8 4810.7 4238.6 4256.4 4293.6 4299.5 4300.5 3732.2 3755.0 3763.8 3772.7 3785.7 1144.8 1158.4 1163.5 1170.7 1170.9 833.3 859.5 1024.7 1175.5 1179.8 7922.4 8522.8 8563.5 8577.6 8587.3 657.2 662.7 663.1 670.8 675.4 5915.4 5940.6 5943.1 5946.0 5954.8 10140.0 10224.5 10316.0 10546.2 10571.2 10469.7 10482.2 10487.7 10506.2 10515.6 7501.0 7719.0 7791.4 7820.4 7823.5 7492.4 7671.8 7695.2 7698.9 7721.5 8833.6 8890.2 8897.0 8905.7 8928.2 9029.0 9144.8 9185.8 9216.5 9311.1 7316.4 7325.7 7337.0 7358.2 7379.2 7917.2 7965.1 8016.2 8168.4 8188.4 7368.5 7399.5 7616.9 7651.2 7669.9 7119.0 7123.6 7273.0 7324.7 7381.4 6837.4 6957.5 7386.5 7579.5 7631.8 6826.7 6854.2 6916.8 6957.7 6964.1 6899.1 6956.4 6984.3 7049.4 7189.5 08:42:15 INFO - 2015-10-26 08:42:15,767 DEBUG : BROWSER_OUTPUT: 08:42:15 INFO - 2015-10-26 08:42:15,767 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4324.32 Median:4985.46 stddev:1262.61 (25.3%) stddev-sans-first:1229.43 08:42:15 INFO - 2015-10-26 08:42:15,767 DEBUG : BROWSER_OUTPUT: Values: 297.1 304.8 308.6 311.2 311.2 4921.4 4999.4 5035.0 5039.6 5041.6 4927.4 4955.9 4977.1 4985.0 4985.9 3727.2 3785.9 3855.5 3862.7 3872.4 3785.6 3814.7 3854.2 3857.6 3857.7 3790.7 3811.8 3813.0 3815.4 3815.5 3664.9 3711.1 3738.3 3806.6 3833.5 3326.6 3350.2 3352.3 3356.7 3359.1 4780.4 4796.9 4799.0 4799.9 4808.6 4258.9 4300.0 4304.8 4306.7 4309.1 4889.3 5061.4 5062.4 5063.2 5076.0 3625.6 3626.1 3629.0 3630.0 3631.4 5308.5 5380.9 5383.8 5386.0 5399.9 3686.2 3701.7 3739.7 3743.1 3746.1 3778.9 3784.9 3793.9 3795.5 3798.1 4194.0 4245.8 4284.3 4292.9 4325.1 5133.6 5135.4 5135.7 5138.0 5147.7 1337.5 1353.2 1356.5 1358.3 1361.4 914.9 917.2 928.6 936.3 942.5 4900.7 4920.7 4930.6 4935.5 4939.0 5288.2 5314.1 5315.9 5319.2 5329.4 4888.1 4922.5 4927.6 4928.4 4928.9 2858.8 2896.1 2900.3 2918.2 2919.8 5336.6 5338.8 5340.9 5342.4 5355.4 5434.2 5438.6 5439.1 5441.9 5448.5 5336.3 5338.3 5339.6 5344.2 5345.7 5199.5 5240.2 5240.3 5312.3 5323.7 5116.0 5161.6 5177.4 5186.1 5190.9 5319.9 5322.1 5324.5 5326.9 5329.4 3711.4 3727.4 3734.7 3738.9 3742.8 5153.1 5276.3 5279.9 5280.8 5301.7 5100.9 5131.8 5134.9 5140.3 5142.9 5125.8 5134.3 5137.4 5145.1 5147.4 4997.1 5078.5 5087.3 5089.6 5109.0 5032.7 5158.2 5165.9 5170.6 5178.7 5016.0 5079.4 5125.7 5138.6 5143.8 08:42:15 INFO - 2015-10-26 08:42:15,769 DEBUG : BROWSER_OUTPUT: 08:42:15 INFO - 2015-10-26 08:42:15,769 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:9866.15 Median:449.17 stddev:10376.11 (2310.1%) stddev-sans-first:10382.68 08:42:15 INFO - 2015-10-26 08:42:15,769 DEBUG : BROWSER_OUTPUT: Values: 759.7 759.7 786.4 789.7 799.8 10495.8 10839.5 10855.9 10856.9 10944.5 10542.7 10568.5 10643.9 10650.6 10668.1 609.4 633.7 636.4 748.9 776.4 754.0 757.2 762.4 762.6 762.8 806.1 814.2 814.8 815.2 815.4 652.1 663.6 787.5 797.9 802.8 587.4 591.7 593.7 595.0 595.0 11062.7 11183.3 11225.9 11239.0 11240.2 8577.9 9810.4 9944.0 10181.0 10227.0 8676.5 8722.1 8891.8 8950.0 9059.0 2786.5 2958.1 2995.1 3011.9 3054.5 30086.8 30726.4 30876.4 31124.7 31212.5 6410.8 6521.5 6561.1 6635.0 6684.2 10157.1 10167.0 10200.1 10313.0 10335.5 5552.4 5555.7 5643.2 5722.7 5723.9 3402.3 3411.6 3459.6 3520.1 3681.5 2283.0 2337.0 2338.2 2338.4 2340.0 394.6 396.5 397.5 397.8 402.6 442.4 444.3 448.7 453.9 456.6 7838.0 7857.7 8040.6 8122.4 8294.5 429.5 431.5 448.5 449.7 462.2 3471.3 3482.5 3499.0 3534.5 3568.0 39961.0 40000.2 40216.0 40234.0 40261.4 33313.3 34480.1 34716.8 35081.6 35151.5 29483.6 29875.0 30064.8 30128.4 30302.5 7118.3 7420.2 7474.3 7492.5 7723.0 21541.9 21865.8 22022.0 22039.6 22085.3 27606.2 27853.9 28070.0 28073.3 28089.3 6595.6 6606.9 6765.8 6803.2 6901.2 10270.6 10442.6 10486.0 10533.4 10676.4 9404.7 9909.8 9926.2 10002.8 10280.0 9786.4 9881.8 9960.6 9975.9 10483.5 9377.7 9430.4 9585.7 9593.1 9831.9 8150.7 8213.5 8252.1 8440.7 8451.9 7627.0 7774.7 7783.2 7783.4 7991.2 08:42:15 INFO - 2015-10-26 08:42:15,770 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:42:15 INFO - 2015-10-26 08:42:15,770 DEBUG : BROWSER_OUTPUT: 08:42:16 INFO - 2015-10-26 08:42:16,384 INFO : Browser exited with error code: 0 08:42:17 INFO - 2015-10-26 08:42:17,157 INFO : Completed test dromaeo_css (00:18:40) 08:42:17 INFO - 2015-10-26 08:42:17,157 INFO : Starting test kraken 08:42:17 INFO - 2015-10-26 08:42:17,157 DEBUG : operating with platform_type : w8_ 08:42:17 INFO - 2015-10-26 08:42:17,157 INFO : Initialising browser for kraken test... 08:42:17 INFO - 2015-10-26 08:42:17,226 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpkckck1\profile http://localhost:49276/getInfo.html 08:42:22 INFO - 2015-10-26 08:42:22,552 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:42:22 INFO - 2015-10-26 08:42:22,552 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:42:22 INFO - 2015-10-26 08:42:22,552 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 08:42:22 INFO - 2015-10-26 08:42:22,552 DEBUG : BROWSER_OUTPUT: __metrics 08:42:22 INFO - 2015-10-26 08:42:22,714 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: console.error: 08:42:23 INFO - 2015-10-26 08:42:23,674 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] 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: Stack: 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 08:42:23 INFO - 2015-10-26 08:42:23,674 DEBUG : BROWSER_OUTPUT: 08:42:24 INFO - 2015-10-26 08:42:24,397 INFO : Browser initialized. 08:42:24 INFO - 2015-10-26 08:42:24,397 INFO : Running cycle 1/1 for kraken test... 08:42:24 INFO - 2015-10-26 08:42:24,397 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpkckck1\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:42:35 INFO - 2015-10-26 08:42:35,967 DEBUG : BROWSER_OUTPUT: RSS: Main: 201908224 08:42:35 INFO - 2015-10-26 08:42:35,967 DEBUG : BROWSER_OUTPUT: 08:42:38 INFO - 2015-10-26 08:42:38,719 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 08:42:39 INFO - 2015-10-26 08:42:39,305 DEBUG : BROWSER_OUTPUT: RSS: Main: 237756416 08:42:39 INFO - 2015-10-26 08:42:39,305 DEBUG : BROWSER_OUTPUT: 08:42:42 INFO - 2015-10-26 08:42:42,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 08:42:42 INFO - 2015-10-26 08:42:42,588 DEBUG : BROWSER_OUTPUT: RSS: Main: 239886336 08:42:42 INFO - 2015-10-26 08:42:42,588 DEBUG : BROWSER_OUTPUT: 08:42:45 INFO - 2015-10-26 08:42:45,289 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 08:42:45 INFO - 2015-10-26 08:42:45,871 DEBUG : BROWSER_OUTPUT: RSS: Main: 225415168 08:42:45 INFO - 2015-10-26 08:42:45,871 DEBUG : BROWSER_OUTPUT: 08:42:47 INFO - 2015-10-26 08:42:47,908 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 08:42:48 INFO - 2015-10-26 08:42:48,480 DEBUG : BROWSER_OUTPUT: RSS: Main: 225497088 08:42:48 INFO - 2015-10-26 08:42:48,480 DEBUG : BROWSER_OUTPUT: 08:42:50 INFO - 2015-10-26 08:42:50,690 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:42:51 INFO - 2015-10-26 08:42:51,252 DEBUG : BROWSER_OUTPUT: RSS: Main: 223297536 08:42:51 INFO - 2015-10-26 08:42:51,253 DEBUG : BROWSER_OUTPUT: 08:42:54 INFO - 2015-10-26 08:42:54,342 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 08:42:54 INFO - 2015-10-26 08:42:54,924 DEBUG : BROWSER_OUTPUT: RSS: Main: 215117824 08:42:54 INFO - 2015-10-26 08:42:54,924 DEBUG : BROWSER_OUTPUT: 08:42:58 INFO - 2015-10-26 08:42:58,255 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 08:42:58 INFO - 2015-10-26 08:42:58,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 210800640 08:42:58 INFO - 2015-10-26 08:42:58,838 DEBUG : BROWSER_OUTPUT: 08:43:01 INFO - 2015-10-26 08:43:01,710 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 08:43:02 INFO - 2015-10-26 08:43:02,282 DEBUG : BROWSER_OUTPUT: RSS: Main: 211443712 08:43:02 INFO - 2015-10-26 08:43:02,282 DEBUG : BROWSER_OUTPUT: 08:43:04 INFO - 2015-10-26 08:43:04,065 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:43:04 INFO - 2015-10-26 08:43:04,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 241025024 08:43:04 INFO - 2015-10-26 08:43:04,631 DEBUG : BROWSER_OUTPUT: 08:43:06 INFO - 2015-10-26 08:43:06,523 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:43:07 INFO - 2015-10-26 08:43:07,091 DEBUG : BROWSER_OUTPUT: RSS: Main: 246214656 08:43:07 INFO - 2015-10-26 08:43:07,091 DEBUG : BROWSER_OUTPUT: 08:43:09 INFO - 2015-10-26 08:43:09,408 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:43:09 INFO - 2015-10-26 08:43:09,990 DEBUG : BROWSER_OUTPUT: RSS: Main: 233009152 08:43:09 INFO - 2015-10-26 08:43:09,990 DEBUG : BROWSER_OUTPUT: 08:43:12 INFO - 2015-10-26 08:43:12,546 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:43:13 INFO - 2015-10-26 08:43:13,128 DEBUG : BROWSER_OUTPUT: RSS: Main: 235196416 08:43:13 INFO - 2015-10-26 08:43:13,128 DEBUG : BROWSER_OUTPUT: 08:43:15 INFO - 2015-10-26 08:43:15,875 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:43:16 INFO - 2015-10-26 08:43:16,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 235982848 08:43:16 INFO - 2015-10-26 08:43:16,451 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,276 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 08:43:18 INFO - 2015-10-26 08:43:18,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 290541568 08:43:18 INFO - 2015-10-26 08:43:18,528 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,528 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;113;107;111;114;114;113;113;107;112 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;135;138;151;159;132;140;145;130;160;130 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |2;audio-dft;146;154;140;140;140;139;140;143;141;139 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;72;96;73;70;70;71;99;69 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;112;90;90;92;117;94;90;118 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;113;110;110;111;111;111;110;112;111;112 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;133;132;132;131;132;133;131;131;132;133 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;98;100;97;97;97 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;72;73;71;71;72;72;72;86;73;69 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;69;69;69;69;69;69;70;69 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;89;91;89;91;93;91;92;93;92;92 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;126;127;123;133;125;127;132;125;124 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;149;158;158;160;161;149;161;159;158;161 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;74;74;74;74;75;74;75;75;75;74 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,966 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: __startTimestamp1445874198527__endTimestamp 08:43:18 INFO - 2015-10-26 08:43:18,529 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,532 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:43:18 INFO - 2015-10-26 08:43:18,532 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:43:18 INFO - 2015-10-26 08:43:18,532 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,532 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:2.70 (2.4%) stddev-sans-first:2.74 08:43:18 INFO - 2015-10-26 08:43:18,532 DEBUG : BROWSER_OUTPUT: Values: 114.0 113.0 107.0 111.0 114.0 114.0 113.0 113.0 107.0 112.0 08:43:18 INFO - 2015-10-26 08:43:18,532 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,532 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.00 Median:142.50 stddev:11.35 (8.0%) stddev-sans-first:11.76 08:43:18 INFO - 2015-10-26 08:43:18,532 DEBUG : BROWSER_OUTPUT: Values: 135.0 138.0 151.0 159.0 132.0 140.0 145.0 130.0 160.0 130.0 08:43:18 INFO - 2015-10-26 08:43:18,532 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,533 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:142.20 Median:140.50 stddev:4.66 (3.3%) stddev-sans-first:4.74 08:43:18 INFO - 2015-10-26 08:43:18,533 DEBUG : BROWSER_OUTPUT: Values: 146.0 154.0 140.0 140.0 140.0 139.0 140.0 143.0 141.0 139.0 08:43:18 INFO - 2015-10-26 08:43:18,533 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,533 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:76.20 Median:71.50 stddev:11.30 (15.8%) stddev-sans-first:11.83 08:43:18 INFO - 2015-10-26 08:43:18,535 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 72.0 96.0 73.0 70.0 70.0 71.0 99.0 69.0 08:43:18 INFO - 2015-10-26 08:43:18,535 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,535 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:98.30 Median:90.00 stddev:12.15 (13.5%) stddev-sans-first:12.51 08:43:18 INFO - 2015-10-26 08:43:18,535 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 112.0 90.0 90.0 92.0 117.0 94.0 90.0 118.0 08:43:18 INFO - 2015-10-26 08:43:18,535 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,535 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:111.10 Median:111.00 stddev:0.99 (0.9%) stddev-sans-first:0.78 08:43:18 INFO - 2015-10-26 08:43:18,535 DEBUG : BROWSER_OUTPUT: Values: 113.0 110.0 110.0 111.0 111.0 111.0 110.0 112.0 111.0 112.0 08:43:18 INFO - 2015-10-26 08:43:18,535 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:132.00 Median:132.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: Values: 133.0 132.0 132.0 131.0 132.0 133.0 131.0 131.0 132.0 133.0 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.90 Median:96.50 stddev:1.29 (1.3%) stddev-sans-first:1.32 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 98.0 100.0 97.0 97.0 97.0 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:73.10 Median:72.00 stddev:4.68 (6.5%) stddev-sans-first:4.94 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 71.0 71.0 72.0 72.0 72.0 86.0 73.0 69.0 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 69.0 69.0 69.0 69.0 69.0 69.0 70.0 69.0 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:92.00 stddev:1.42 (1.5%) stddev-sans-first:1.24 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: Values: 89.0 91.0 89.0 91.0 93.0 91.0 92.0 93.0 92.0 92.0 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:126.50 stddev:3.26 (2.6%) stddev-sans-first:3.44 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: Values: 126.0 126.0 127.0 123.0 133.0 125.0 127.0 132.0 125.0 124.0 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.40 Median:159.50 stddev:4.60 (2.9%) stddev-sans-first:3.74 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: Values: 149.0 158.0 158.0 160.0 161.0 149.0 161.0 159.0 158.0 161.0 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:74.50 stddev:0.52 (0.7%) stddev-sans-first:0.53 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: Values: 74.0 74.0 74.0 74.0 75.0 74.0 75.0 75.0 75.0 74.0 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:43:18 INFO - 2015-10-26 08:43:18,536 DEBUG : BROWSER_OUTPUT: 08:43:19 INFO - 2015-10-26 08:43:19,305 INFO : Browser exited with error code: 0 08:43:20 INFO - 2015-10-26 08:43:20,121 INFO : Completed test kraken (00:01:02) 08:43:20 INFO - 2015-10-26 08:43:20,121 INFO : Starting test v8_7 08:43:20 INFO - 2015-10-26 08:43:20,121 DEBUG : operating with platform_type : w8_ 08:43:20 INFO - 2015-10-26 08:43:20,121 INFO : Initialising browser for v8_7 test... 08:43:20 INFO - 2015-10-26 08:43:20,142 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmppmmlh0\profile http://localhost:49276/getInfo.html 08:43:25 INFO - 2015-10-26 08:43:25,489 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:43:25 INFO - 2015-10-26 08:43:25,489 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:43:25 INFO - 2015-10-26 08:43:25,489 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 08:43:25 INFO - 2015-10-26 08:43:25,489 DEBUG : BROWSER_OUTPUT: __metrics 08:43:25 INFO - 2015-10-26 08:43:25,680 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:43:26 INFO - 2015-10-26 08:43:26,630 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 08:43:26 INFO - 2015-10-26 08:43:26,976 DEBUG : BROWSER_OUTPUT: console.error: 08:43:26 INFO - 2015-10-26 08:43:26,979 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] 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: Stack: 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:43:26 INFO - 2015-10-26 08:43:26,979 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 08:43:26 INFO - 2015-10-26 08:43:26,980 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5988.93 08:44:04 INFO - RETURN: kraken: 1502.60 08:44:04 INFO - RETURN: v8_7: 20026.45 08:44:04 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,31]]", "result": "20026.45"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,31]]", "result": "1502.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,31]]", "result": "5988.93"}}} 08:44:04 INFO - Return code: 0 08:44:04 INFO - # TBPL SUCCESS # 08:44:04 INFO - Running post-action listener: _resource_record_post_action 08:44:04 INFO - Running post-run listener: _resource_record_post_run 08:44:05 INFO - Total resource usage - Wall time: 1242s; CPU: 13.0%; Read bytes: 56354816; Write bytes: 382853632; Read time: 598400; Write time: 4090630 08:44:05 INFO - install - Wall time: 2s; CPU: 14.0%; Read bytes: 0; Write bytes: 616960; Read time: 0; Write time: 740 08:44:05 INFO - run-tests - Wall time: 1241s; CPU: 13.0%; Read bytes: 56354816; Write bytes: 343779840; Read time: 598400; Write time: 4022410 08:44:05 INFO - Running post-run listener: _upload_blobber_files 08:44:05 INFO - Blob upload gear active. 08:44:05 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:44:05 INFO - Copying logs to upload dir... 08:44:05 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=1435.036000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 7 secs) (at 2015-10-26 08:44:17.303339) ========= ========= Started set props: build_url (results: 0, elapsed: 13 secs) (at 2015-10-26 08:44:17.304787) ========= 'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f' in dir C:\\slave\test\properties (timeout 1200 secs) watching logfiles {} argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-187\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-187 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-187 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-187\AppData\Local LOGONSERVER=\\T-W864-IX-187 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 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test\properties RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-187 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-187 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-187 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False build_url:https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/dTPSG486SjiuZPo2TywYmA/artifacts/public/build/firefox-44.0a1.en-US.win64.zip' ========= Finished set props: build_url (results: 0, elapsed: 13 secs) (at 2015-10-26 08:44:30.473421) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 15 secs) (at 2015-10-26 08:44:30.473775) ========= 'rm' '-f' 'oauth.txt' in dir C:\\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-187\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-187 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-187 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-187\AppData\Local LOGONSERVER=\\T-W864-IX-187 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 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-187 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-187 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-187 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.102000 ========= Finished 'rm -f ...' (results: 0, elapsed: 15 secs) (at 2015-10-26 08:44:45.958841) ========= ========= Started reboot slave lost (results: 0, elapsed: 23 secs) (at 2015-10-26 08:44:45.959171) ========= ========= Finished reboot slave lost (results: 0, elapsed: 23 secs) (at 2015-10-26 08:45:09.506268) =========