builder: mozilla-beta_win7-ix_test-svgr-pgo slave: t-w732-ix-269 starttime: 1457995608.08 results: success (0) buildid: 20160314120416 builduid: 68fbc588cd194569a71c51b6d1cd9758 revision: b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-14 15:46:48.079652) ========= master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-14 15:46:48.080120) ========= ========= Started set props: basedir (results: 0, elapsed: 1 secs) (at 2016-03-14 15:46:48.080414) ========= 'cd' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['cd'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-269 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-269 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:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-269 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False C:\slave\test-pgo program finished with exit code 0 elapsedTime=1.110000 basedir: 'C:\\slave\\test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 1 secs) (at 2016-03-14 15:46:49.208700) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-14 15:46:49.209030) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-14 15:46:49.359890) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 15:46:49.360412) ========= 'rm' '-rf' 'properties' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-269 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-269 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:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-269 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.602000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 15:46:49.986087) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-14 15:46:49.986417) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-14 15:46:49.986785) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-14 15:46:49.987095) ========= 'bash' '-c' 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-269 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-269 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:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-269 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False --15:46:50-- 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% 18.57 MB/s 15:46:50 (18.57 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.199000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-14 15:46:51.207515) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 18 secs) (at 2016-03-14 15:46:51.207860) ========= 'rm' '-rf' 'scripts' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-269 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-269 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:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-269 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=18.143000 ========= master_lag: 0.00 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 18 secs) (at 2016-03-14 15:47:09.353232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-03-14 15:47:09.353611) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1 --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-269 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-269 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:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-269 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-03-14 15:47:12,361 truncating revision to first 12 chars 2016-03-14 15:47:12,361 Setting DEBUG logging. 2016-03-14 15:47:12,361 attempt 1/10 2016-03-14 15:47:12,361 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b31b7bd99b5f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-14 15:47:12,875 unpacking tar archive at: mozilla-beta-b31b7bd99b5f/testing/mozharness/ program finished with exit code 0 elapsedTime=5.286000 ========= master_lag: 1.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-03-14 15:47:16.075285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-14 15:47:16.075654) ========= 'echo' u'b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-269 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-269 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:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-269 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1 program finished with exit code 0 elapsedTime=0.101000 script_repo_revision: 'b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1' ========= master_lag: 0.32 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-14 15:47:16.498662) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-14 15:47:16.499280) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-14 15:47:16.550781) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 33 secs) (at 2016-03-14 15:47:16.551252) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'svgr' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Beta' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Beta' in dir C:\slave\test-pgo\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'svgr', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-269 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-269 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:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PROPERTIES_FILE=C:\slave\test-pgo/buildprops.json PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-269 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 15:47:17 INFO - MultiFileLogger online at 20160314 15:47:17 in C:\slave\test-pgo 15:47:17 INFO - Run as scripts/scripts/talos_script.py --suite svgr --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:47:17 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 15:47:17 INFO - {'append_to_log': False, 15:47:17 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 15:47:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:47:17 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 15:47:17 INFO - 'branch': 'Mozilla-Beta', 15:47:17 INFO - 'buildbot_json_path': 'buildprops.json', 15:47:17 INFO - 'config_files': ('talos/windows_config.py',), 15:47:17 INFO - 'default_actions': ('clobber', 15:47:17 INFO - 'read-buildbot-config', 15:47:17 INFO - 'download-and-extract', 15:47:17 INFO - 'populate-webroot', 15:47:17 INFO - 'create-virtualenv', 15:47:17 INFO - 'install', 15:47:17 INFO - 'run-tests'), 15:47:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:47:17 INFO - 'download_minidump_stackwalk': True, 15:47:17 INFO - 'download_symbols': 'ondemand', 15:47:17 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 15:47:17 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 15:47:17 INFO - 'hg': 'c:/mozilla-build/hg/hg', 15:47:17 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 15:47:17 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 15:47:17 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 15:47:17 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 15:47:17 INFO - 'C:/mozilla-build/tooltool.py'), 15:47:17 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 15:47:17 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 15:47:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:47:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:47:17 INFO - 'installer_path': 'installer.exe', 15:47:17 INFO - 'log_level': 'info', 15:47:17 INFO - 'log_name': 'talos', 15:47:17 INFO - 'log_to_console': True, 15:47:17 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 15:47:17 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 15:47:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 15:47:17 INFO - 'opt_config_files': (), 15:47:17 INFO - 'pip_index': False, 15:47:17 INFO - 'sps_profile': False, 15:47:17 INFO - 'sps_profile_interval': 0, 15:47:17 INFO - 'suite': 'svgr', 15:47:17 INFO - 'system_bits': '32', 15:47:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:47:17 INFO - 'title': 'T-W732-IX-269', 15:47:17 INFO - 'use_talos_json': True, 15:47:17 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 15:47:17 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 15:47:17 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 15:47:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:47:17 INFO - 'work_dir': 'build'} 15:47:17 INFO - ##### 15:47:17 INFO - ##### Running clobber step. 15:47:17 INFO - ##### 15:47:17 INFO - Running pre-action listener: _resource_record_pre_action 15:47:17 INFO - Running main action method: clobber 15:47:17 INFO - rmtree: C:\slave\test-pgo\build 15:47:17 INFO - Using _rmtree_windows ... 15:47:17 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 15:49:58 INFO - Running post-action listener: _resource_record_post_action 15:49:58 INFO - ##### 15:49:58 INFO - ##### Running read-buildbot-config step. 15:49:58 INFO - ##### 15:49:58 INFO - Running pre-action listener: _resource_record_pre_action 15:49:58 INFO - Running main action method: read_buildbot_config 15:49:58 INFO - Using buildbot properties: 15:49:58 INFO - { 15:49:58 INFO - "project": "", 15:49:58 INFO - "product": "firefox", 15:49:58 INFO - "script_repo_revision": "production", 15:49:58 INFO - "builddir": "mozilla-beta_win7-ix_test-svgr", 15:49:58 INFO - "repository": "", 15:49:58 INFO - "buildername": "Windows 7 32-bit mozilla-beta pgo talos svgr", 15:49:58 INFO - "buildid": "20160314120416", 15:49:58 INFO - "pgo_build": "True", 15:49:58 INFO - "basedir": "C:\\slave\\test-pgo", 15:49:58 INFO - "buildnumber": 2, 15:49:58 INFO - "slavename": "t-w732-ix-269", 15:49:58 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/", 15:49:58 INFO - "platform": "win7-ix", 15:49:58 INFO - "scheduler": "tests-mozilla-beta-win32-pgo-talos", 15:49:58 INFO - "branch": "mozilla-beta", 15:49:58 INFO - "repo_path": "releases/mozilla-beta", 15:49:58 INFO - "revision": "b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1", 15:49:58 INFO - "stage_platform": "win32-pgo", 15:49:58 INFO - "builduid": "68fbc588cd194569a71c51b6d1cd9758", 15:49:58 INFO - "slavebuilddir": "test" 15:49:58 INFO - } 15:49:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.zip. 15:49:58 INFO - Running post-action listener: _resource_record_post_action 15:49:58 INFO - ##### 15:49:58 INFO - ##### Running download-and-extract step. 15:49:58 INFO - ##### 15:49:58 INFO - Running pre-action listener: _resource_record_pre_action 15:49:58 INFO - Running main action method: download_and_extract 15:49:58 INFO - mkdir: C:\slave\test-pgo\build\tests 15:49: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')]} 15:49:58 INFO - https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 15:49:58 INFO - trying https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/test_packages.json 15:49:58 INFO - Downloading https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 15:49:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 15:49:59 INFO - Downloaded 1267 bytes. 15:49:59 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 15:49:59 INFO - Using the following test package requirements: 15:49:59 INFO - {u'common': [u'firefox-46.0.en-US.win32.common.tests.zip'], 15:49:59 INFO - u'cppunittest': [u'firefox-46.0.en-US.win32.common.tests.zip', 15:49:59 INFO - u'firefox-46.0.en-US.win32.cppunittest.tests.zip'], 15:49:59 INFO - u'gtest': [u'firefox-46.0.en-US.win32.common.tests.zip'], 15:49:59 INFO - u'jittest': [u'firefox-46.0.en-US.win32.common.tests.zip', 15:49:59 INFO - u'jsshell-win32.zip'], 15:49:59 INFO - u'mochitest': [u'firefox-46.0.en-US.win32.common.tests.zip', 15:49:59 INFO - u'firefox-46.0.en-US.win32.mochitest.tests.zip'], 15:49:59 INFO - u'mozbase': [u'firefox-46.0.en-US.win32.common.tests.zip'], 15:49:59 INFO - u'reftest': [u'firefox-46.0.en-US.win32.common.tests.zip', 15:49:59 INFO - u'firefox-46.0.en-US.win32.reftest.tests.zip'], 15:49:59 INFO - u'talos': [u'firefox-46.0.en-US.win32.common.tests.zip', 15:49:59 INFO - u'firefox-46.0.en-US.win32.talos.tests.zip'], 15:49:59 INFO - u'web-platform': [u'firefox-46.0.en-US.win32.common.tests.zip', 15:49:59 INFO - u'firefox-46.0.en-US.win32.web-platform.tests.zip'], 15:49:59 INFO - u'webapprt': [u'firefox-46.0.en-US.win32.common.tests.zip'], 15:49:59 INFO - u'xpcshell': [u'firefox-46.0.en-US.win32.common.tests.zip', 15:49:59 INFO - u'firefox-46.0.en-US.win32.xpcshell.tests.zip']} 15:49:59 INFO - Downloading packages: [u'firefox-46.0.en-US.win32.common.tests.zip'] for test suite category: common 15:49:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:59 INFO - https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 15:49:59 INFO - trying https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip 15:49:59 INFO - Downloading https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-46.0.en-US.win32.common.tests.zip 15:49:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-46.0.en-US.win32.common.tests.zip'}, attempt #1 15:50:01 INFO - Downloaded 17907091 bytes. 15:50:01 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-46.0.en-US.win32.common.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 15:50:01 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-46.0.en-US.win32.common.tests.zip -d C:\slave\test-pgo\build\tests 15:50:26 INFO - Return code: 0 15:50:26 INFO - Downloading packages: [u'firefox-46.0.en-US.win32.common.tests.zip', u'firefox-46.0.en-US.win32.talos.tests.zip'] for test suite category: talos 15:50:26 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')]} 15:50:26 INFO - https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 15:50:26 INFO - trying https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip 15:50:26 INFO - Downloading https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-46.0.en-US.win32.common.tests.zip 15:50:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-46.0.en-US.win32.common.tests.zip'}, attempt #1 15:50:28 INFO - Downloaded 17907091 bytes. 15:50:28 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-46.0.en-US.win32.common.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 15:50:28 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-46.0.en-US.win32.common.tests.zip -d C:\slave\test-pgo\build\tests 15:50:49 INFO - Return code: 0 15:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:49 INFO - https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 15:50:49 INFO - trying https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip 15:50:49 INFO - Downloading https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip to C:\slave\test-pgo\build\firefox-46.0.en-US.win32.talos.tests.zip 15:50:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-46.0.en-US.win32.talos.tests.zip'}, attempt #1 15:50:50 INFO - Downloaded 10801536 bytes. 15:50:50 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-46.0.en-US.win32.talos.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 15:50:50 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-46.0.en-US.win32.talos.tests.zip -d C:\slave\test-pgo\build\tests 15:50:51 INFO - Return code: 0 15:50: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')]} 15:50:51 INFO - https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.zip matches https://queue.taskcluster.net 15:50:51 INFO - trying https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.zip 15:50:51 INFO - Downloading https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.zip to C:\slave\test-pgo\build\firefox-46.0.en-US.win32.zip 15:50:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-46.0.en-US.win32.zip'}, attempt #1 15:50:54 INFO - Downloaded 55114267 bytes. 15:50:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.zip 15:50:54 INFO - mkdir: C:\slave\test-pgo\properties 15:50:54 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 15:50:54 INFO - Writing to file C:\slave\test-pgo\properties\build_url 15:50:54 INFO - Contents: 15:50:54 INFO - build_url:https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.zip 15:50:55 INFO - Running post-action listener: _resource_record_post_action 15:50:55 INFO - Running post-action listener: set_extra_try_arguments 15:50:55 INFO - ##### 15:50:55 INFO - ##### Running populate-webroot step. 15:50:55 INFO - ##### 15:50:55 INFO - Running pre-action listener: _resource_record_pre_action 15:50:55 INFO - Running main action method: populate_webroot 15:50:55 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 15:50:55 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 15:50:55 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 15:50:55 INFO - u'--tppagecycles', 15:50:55 INFO - u'7'], 15:50:55 INFO - u'tests': [u'tsvgm']}}, 15:50:55 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 15:50:55 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 15:50:55 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 15:50:55 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 15:50:55 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 15:50:55 INFO - u'tests': [u'dromaeo_css', 15:50:55 INFO - u'kraken', 15:50:55 INFO - u'v8_7']}, 15:50:55 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:55 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 15:50:55 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:55 INFO - u'talos_options': [u'--e10s'], 15:50:55 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 15:50:55 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:55 INFO - u'tests': [u'damp', u'tps']}, 15:50:55 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:55 INFO - u'talos_options': [u'--e10s'], 15:50:55 INFO - u'tests': [u'damp', u'tps']}, 15:50:55 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 15:50:55 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 15:50:55 INFO - u'other': {u'tests': [u'a11yr', 15:50:55 INFO - u'ts_paint', 15:50:55 INFO - u'tpaint', 15:50:55 INFO - u'sessionrestore', 15:50:55 INFO - u'sessionrestore_no_auto_restore']}, 15:50:55 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 15:50:55 INFO - u'tests': [u'a11yr', 15:50:55 INFO - u'ts_paint', 15:50:55 INFO - u'tpaint', 15:50:55 INFO - u'sessionrestore', 15:50:55 INFO - u'sessionrestore_no_auto_restore']}, 15:50:55 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 15:50:55 INFO - u'tests': [u'a11yr', 15:50:55 INFO - u'ts_paint', 15:50:55 INFO - u'tpaint', 15:50:55 INFO - u'sessionrestore', 15:50:55 INFO - u'sessionrestore_no_auto_restore']}, 15:50:55 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 15:50:55 INFO - u'tests': [u'a11yr', 15:50:55 INFO - u'ts_paint', 15:50:55 INFO - u'tpaint', 15:50:55 INFO - u'sessionrestore', 15:50:55 INFO - u'sessionrestore_no_auto_restore']}, 15:50:55 INFO - u'other_l64': {u'tests': [u'a11yr', 15:50:55 INFO - u'ts_paint', 15:50:55 INFO - u'tpaint', 15:50:55 INFO - u'sessionrestore', 15:50:55 INFO - u'sessionrestore_no_auto_restore']}, 15:50:55 INFO - u'other_nol64': {u'tests': [u'a11yr', 15:50:55 INFO - u'ts_paint', 15:50:55 INFO - u'tpaint', 15:50:55 INFO - u'sessionrestore', 15:50:55 INFO - u'sessionrestore_no_auto_restore']}, 15:50:55 INFO - u'svgr': {u'tests': [u'tsvgx', 15:50:55 INFO - u'tsvgr_opacity', 15:50:55 INFO - u'tart', 15:50:55 INFO - u'tscrollx', 15:50:55 INFO - u'cart']}, 15:50:55 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 15:50:55 INFO - u'tests': [u'tsvgx', 15:50:55 INFO - u'tsvgr_opacity', 15:50:55 INFO - u'tart', 15:50:55 INFO - u'tscrollx', 15:50:55 INFO - u'cart']}, 15:50:55 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:55 INFO - u'tests': [u'tp5o']}, 15:50:55 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:55 INFO - u'talos_options': [u'--e10s'], 15:50:55 INFO - u'tests': [u'tp5o']}, 15:50:55 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:55 INFO - u'talos_options': [u'--xperf_path', 15:50:55 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:55 INFO - u'tests': [u'tp5n']}, 15:50:55 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:55 INFO - u'talos_options': [u'--e10s', 15:50:55 INFO - u'--xperf_path', 15:50:55 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:55 INFO - u'tests': [u'tp5n']}}, 15:50:55 INFO - u'talos.zip': {u'path': u'', 15:50:55 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:50:55 INFO - Running post-action listener: _resource_record_post_action 15:50:55 INFO - ##### 15:50:55 INFO - ##### Running create-virtualenv step. 15:50:55 INFO - ##### 15:50:55 INFO - Running pre-action listener: _resource_record_pre_action 15:50:55 INFO - Running main action method: create_virtualenv 15:50:55 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 15:50:55 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 15:50:55 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 15:50:55 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 15:50:55 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build 15:50:55 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv 15:51:04 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 15:51:08 INFO - Installing distribute......................................................................................................................................................................................done. 15:51:09 INFO - Return code: 0 15:51:09 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 15:51: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')]} 15:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51: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')]} 15:51:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019756B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B3EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-269', 'USERDOMAIN': 'T-W732-IX-269', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-269', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 15:51:09 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 15:51:09 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:51:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:09 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:09 INFO - 'DCLOCATION': 'SCL3', 15:51:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:09 INFO - 'HOMEDRIVE': 'C:', 15:51:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:09 INFO - 'KTS_VERSION': '1.19c', 15:51:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:09 INFO - 'MOZ_NO_REMOTE': '1', 15:51:09 INFO - 'NO_EM_RESTART': '1', 15:51:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:09 INFO - 'OS': 'Windows_NT', 15:51:09 INFO - 'OURDRIVE': 'C:', 15:51:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:09 INFO - 'PROCESSOR_LEVEL': '6', 15:51:09 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:09 INFO - 'PROMPT': '$P$G', 15:51:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:09 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:09 INFO - 'SYSTEMDRIVE': 'C:', 15:51:09 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:09 INFO - 'TEST1': 'testie', 15:51:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:09 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:09 INFO - 'USERNAME': 'cltbld', 15:51:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:09 INFO - 'WINDIR': 'C:\\windows', 15:51:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:10 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:51:10 INFO - Downloading/unpacking pip>=1.5 15:51:15 INFO - Running setup.py egg_info for package pip 15:51:15 INFO - warning: no files found matching 'pip\cacert.pem' 15:51:15 INFO - warning: no files found matching '*.html' under directory 'docs' 15:51:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 15:51:15 INFO - no previously-included directories found matching 'docs\_build\_sources' 15:51:15 INFO - Installing collected packages: pip 15:51:15 INFO - Found existing installation: pip 0.8.2 15:51:15 INFO - Uninstalling pip: 15:51:15 INFO - Successfully uninstalled pip 15:51:15 INFO - Running setup.py install for pip 15:51:15 INFO - warning: no files found matching 'pip\cacert.pem' 15:51:15 INFO - warning: no files found matching '*.html' under directory 'docs' 15:51:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 15:51:15 INFO - no previously-included directories found matching 'docs\_build\_sources' 15:51:15 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:15 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:15 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:15 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:15 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:15 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:15 INFO - Successfully installed pip 15:51:15 INFO - Cleaning up... 15:51:15 INFO - Return code: 0 15:51:15 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 15:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:15 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019756B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B3EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-269', 'USERDOMAIN': 'T-W732-IX-269', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-269', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 15:51:15 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test-pgo\build 15:51:15 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:51:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:15 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:15 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:15 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:15 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:15 INFO - 'DCLOCATION': 'SCL3', 15:51:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:15 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:15 INFO - 'HOMEDRIVE': 'C:', 15:51:15 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:15 INFO - 'KTS_VERSION': '1.19c', 15:51:15 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:15 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:15 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:15 INFO - 'MOZ_NO_REMOTE': '1', 15:51:15 INFO - 'NO_EM_RESTART': '1', 15:51:15 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:15 INFO - 'OS': 'Windows_NT', 15:51:15 INFO - 'OURDRIVE': 'C:', 15:51:15 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:15 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:15 INFO - 'PROCESSOR_LEVEL': '6', 15:51:15 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:15 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:15 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:15 INFO - 'PROMPT': '$P$G', 15:51:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:15 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:15 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:15 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:15 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:15 INFO - 'SYSTEMDRIVE': 'C:', 15:51:15 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:15 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:15 INFO - 'TEST1': 'testie', 15:51:15 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:15 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:15 INFO - 'USERNAME': 'cltbld', 15:51:15 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:15 INFO - 'WINDIR': 'C:\\windows', 15:51:15 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:15 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:16 INFO - Downloading/unpacking psutil>=0.7.1 15:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:17 INFO - Installing collected packages: psutil 15:51:17 INFO - Successfully installed psutil 15:51:17 INFO - Cleaning up... 15:51:17 INFO - Return code: 0 15:51:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 15:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:17 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019756B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B3EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-269', 'USERDOMAIN': 'T-W732-IX-269', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-269', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 15:51:18 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build 15:51:18 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:51:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:18 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:18 INFO - 'DCLOCATION': 'SCL3', 15:51:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:18 INFO - 'HOMEDRIVE': 'C:', 15:51:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:18 INFO - 'KTS_VERSION': '1.19c', 15:51:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:18 INFO - 'MOZ_NO_REMOTE': '1', 15:51:18 INFO - 'NO_EM_RESTART': '1', 15:51:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:18 INFO - 'OS': 'Windows_NT', 15:51:18 INFO - 'OURDRIVE': 'C:', 15:51:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:18 INFO - 'PROCESSOR_LEVEL': '6', 15:51:18 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:18 INFO - 'PROMPT': '$P$G', 15:51:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:18 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:18 INFO - 'SYSTEMDRIVE': 'C:', 15:51:18 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:18 INFO - 'TEST1': 'testie', 15:51:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:18 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:18 INFO - 'USERNAME': 'cltbld', 15:51:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:18 INFO - 'WINDIR': 'C:\\windows', 15:51:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:51:19 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 15:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 15:51:19 INFO - Installing collected packages: mozsystemmonitor 15:51:19 INFO - Running setup.py install for mozsystemmonitor 15:51:19 INFO - Successfully installed mozsystemmonitor 15:51:19 INFO - Cleaning up... 15:51:19 INFO - Return code: 0 15:51:19 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 15:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:19 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019756B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B3EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-269', 'USERDOMAIN': 'T-W732-IX-269', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-269', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 15:51:19 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build 15:51:19 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:51:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:19 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:19 INFO - 'DCLOCATION': 'SCL3', 15:51:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:19 INFO - 'HOMEDRIVE': 'C:', 15:51:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:19 INFO - 'KTS_VERSION': '1.19c', 15:51:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:19 INFO - 'MOZ_NO_REMOTE': '1', 15:51:19 INFO - 'NO_EM_RESTART': '1', 15:51:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:19 INFO - 'OS': 'Windows_NT', 15:51:19 INFO - 'OURDRIVE': 'C:', 15:51:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:19 INFO - 'PROCESSOR_LEVEL': '6', 15:51:19 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:19 INFO - 'PROMPT': '$P$G', 15:51:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:19 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:19 INFO - 'SYSTEMDRIVE': 'C:', 15:51:19 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:19 INFO - 'TEST1': 'testie', 15:51:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:19 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:19 INFO - 'USERNAME': 'cltbld', 15:51:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:19 INFO - 'WINDIR': 'C:\\windows', 15:51:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:20 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:21 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:21 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 15:51:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:21 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 15:51:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:22 INFO - Downloading docopt-0.6.1.tar.gz 15:51:22 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 15:51:22 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:22 INFO - Running setup.py install for blobuploader 15:51:22 INFO - Running setup.py install for requests 15:51:22 INFO - Running setup.py install for docopt 15:51:23 INFO - Successfully installed blobuploader requests docopt 15:51:23 INFO - Cleaning up... 15:51:24 INFO - Return code: 0 15:51:24 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:24 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019756B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B3EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-269', 'USERDOMAIN': 'T-W732-IX-269', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-269', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 15:51:24 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 15:51:24 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:24 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:24 INFO - 'DCLOCATION': 'SCL3', 15:51:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:24 INFO - 'HOMEDRIVE': 'C:', 15:51:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:24 INFO - 'KTS_VERSION': '1.19c', 15:51:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:24 INFO - 'MOZ_NO_REMOTE': '1', 15:51:24 INFO - 'NO_EM_RESTART': '1', 15:51:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:24 INFO - 'OS': 'Windows_NT', 15:51:24 INFO - 'OURDRIVE': 'C:', 15:51:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:24 INFO - 'PROCESSOR_LEVEL': '6', 15:51:24 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:24 INFO - 'PROMPT': '$P$G', 15:51:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:24 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:24 INFO - 'SYSTEMDRIVE': 'C:', 15:51:24 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:24 INFO - 'TEST1': 'testie', 15:51:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:24 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:24 INFO - 'USERNAME': 'cltbld', 15:51:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:24 INFO - 'WINDIR': 'C:\\windows', 15:51:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 15:51:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2lptmj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 15:51:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 15:51:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xmpuvy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 15:51:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 15:51:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p5odqz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 15:51:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 15:51:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w4mzsr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 15:51:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 15:51:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lz19_m-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 15:51:25 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 15:51:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5oshhc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 15:51:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 15:51:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f00qg_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 15:51:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 15:51:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rfc8ug-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 15:51:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 15:51:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hhw0ja-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 15:51:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 15:51:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-njwrvl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 15:51:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 15:51:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pgmy4t-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 15:51:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 15:51:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vequte-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 15:51:26 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 15:51:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nkedv_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 15:51:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 15:51:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8e9bvw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 15:51:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 15:51:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fxavos-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:51:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 15:51:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ygkrqr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 15:51:27 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 15:51:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pitg2k-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 15:51:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:27 INFO - Running setup.py install for manifestparser 15:51:27 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:27 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:28 INFO - Running setup.py install for mozcrash 15:51:28 INFO - Running setup.py install for mozdebug 15:51:29 INFO - Running setup.py install for mozdevice 15:51:29 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:29 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:29 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:29 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:29 INFO - Running setup.py install for mozfile 15:51:30 INFO - Running setup.py install for mozhttpd 15:51:30 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:30 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:30 INFO - Running setup.py install for mozinfo 15:51:30 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:30 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:31 INFO - Running setup.py install for mozInstall 15:51:31 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:31 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:31 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:31 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:31 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:31 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:31 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:31 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:31 INFO - Running setup.py install for mozleak 15:51:31 INFO - Running setup.py install for mozlog 15:51:32 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:32 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:32 INFO - Running setup.py install for moznetwork 15:51:32 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:32 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:33 INFO - Running setup.py install for mozprocess 15:51:33 INFO - Running setup.py install for mozprofile 15:51:33 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:33 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:33 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:33 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:33 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:33 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:34 INFO - Running setup.py install for mozrunner 15:51:34 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:34 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:34 INFO - Running setup.py install for mozscreenshot 15:51:35 INFO - Running setup.py install for moztest 15:51:35 INFO - Running setup.py install for mozversion 15:51:35 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:35 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:36 INFO - Cleaning up... 15:51:36 INFO - Return code: 0 15:51:36 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 15:51:36 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')]} 15:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:36 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')]} 15:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:36 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019756B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B3EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-269', 'USERDOMAIN': 'T-W732-IX-269', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-269', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 15:51:36 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 15:51:36 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:36 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:36 INFO - 'DCLOCATION': 'SCL3', 15:51:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:36 INFO - 'HOMEDRIVE': 'C:', 15:51:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:36 INFO - 'KTS_VERSION': '1.19c', 15:51:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:36 INFO - 'MOZ_NO_REMOTE': '1', 15:51:36 INFO - 'NO_EM_RESTART': '1', 15:51:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:36 INFO - 'OS': 'Windows_NT', 15:51:36 INFO - 'OURDRIVE': 'C:', 15:51:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:36 INFO - 'PROCESSOR_LEVEL': '6', 15:51:36 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:36 INFO - 'PROMPT': '$P$G', 15:51:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:36 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:36 INFO - 'SYSTEMDRIVE': 'C:', 15:51:36 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:36 INFO - 'TEST1': 'testie', 15:51:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:36 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:36 INFO - 'USERNAME': 'cltbld', 15:51:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:36 INFO - 'WINDIR': 'C:\\windows', 15:51:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 15:51:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k1cyxl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 15:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1)) 15:51:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 15:51:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-plvsq_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 15:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 15:51:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 15:51:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9lv5wd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 15:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 15:51:36 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 15:51:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qvf_7y-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 15:51:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 15:51:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 15:51:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rheyuc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 15:51:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5)) 15:51:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 15:51:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4fru0s-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 15:51:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6)) 15:51:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 15:51:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ykd1a6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 15:51:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7)) 15:51:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 15:51:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-esb4eo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 15:51:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8)) 15:51:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 15:51:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i4ifrw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 15:51:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9)) 15:51:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 15:51:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8qt0uq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 15:51:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 15:51:37 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 15:51:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cksynp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11)) 15:51:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 15:51:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kkfddo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12)) 15:51:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 15:51:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nmpjwm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13)) 15:51:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 15:51:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ohm7x8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 15:51:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 15:51:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q47pik-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15)) 15:51:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 15:51:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gyejqv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16)) 15:51:38 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 15:51:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tfj5ud-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17)) 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 15:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 15:51:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 15:51:38 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 15:51:38 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 15:51:39 INFO - Downloading blessings-1.6.tar.gz 15:51:39 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 15:51:40 INFO - Installing collected packages: blessings 15:51:40 INFO - Running setup.py install for blessings 15:51:40 INFO - Successfully installed blessings 15:51:40 INFO - Cleaning up... 15:51:43 INFO - Return code: 0 15:51:43 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 15:51:43 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 15:51:43 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 15:51:43 INFO - Reading from file tmpfile_stdout 15:51:43 INFO - Using _rmtree_windows ... 15:51:43 INFO - Using _rmtree_windows ... 15:51:43 INFO - Current package versions: 15:51:43 INFO - blessings == 1.6 15:51:43 INFO - blobuploader == 1.2.4 15:51:43 INFO - docopt == 0.6.1 15:51:43 INFO - manifestparser == 1.1 15:51:43 INFO - mozInstall == 1.12 15:51:43 INFO - mozcrash == 0.16 15:51:43 INFO - mozdebug == 0.1 15:51:43 INFO - mozdevice == 0.48 15:51:43 INFO - mozfile == 1.2 15:51:43 INFO - mozhttpd == 0.7 15:51:43 INFO - mozinfo == 0.9 15:51:43 INFO - mozleak == 0.1 15:51:43 INFO - mozlog == 3.1 15:51:43 INFO - moznetwork == 0.27 15:51:43 INFO - mozprocess == 0.22 15:51:43 INFO - mozprofile == 0.28 15:51:43 INFO - mozrunner == 6.11 15:51:43 INFO - mozscreenshot == 0.1 15:51:43 INFO - mozsystemmonitor == 0.0 15:51:43 INFO - moztest == 0.7 15:51:43 INFO - mozversion == 1.4 15:51:43 INFO - psutil == 3.1.1 15:51:43 INFO - requests == 1.2.3 15:51:43 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 15:51:43 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')]} 15:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:43 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')]} 15:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:43 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019756B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B3EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-269', 'USERDOMAIN': 'T-W732-IX-269', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-269', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 15:51:43 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\talos 15:51:43 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test-pgo\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:43 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:43 INFO - 'DCLOCATION': 'SCL3', 15:51:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:43 INFO - 'HOMEDRIVE': 'C:', 15:51:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:43 INFO - 'KTS_VERSION': '1.19c', 15:51:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:43 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:43 INFO - 'MOZ_NO_REMOTE': '1', 15:51:43 INFO - 'NO_EM_RESTART': '1', 15:51:43 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:43 INFO - 'OS': 'Windows_NT', 15:51:43 INFO - 'OURDRIVE': 'C:', 15:51:43 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:43 INFO - 'PROCESSOR_LEVEL': '6', 15:51:43 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:43 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:43 INFO - 'PROMPT': '$P$G', 15:51:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:43 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:43 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:43 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:43 INFO - 'SYSTEMDRIVE': 'C:', 15:51:43 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:43 INFO - 'TEST1': 'testie', 15:51:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:43 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:43 INFO - 'USERNAME': 'cltbld', 15:51:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:43 INFO - 'WINDIR': 'C:\\windows', 15:51:43 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 9)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 15:51:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 15:51:43 INFO - Cleaning up... 15:51:43 INFO - Return code: 0 15:51:43 INFO - Installing jsonschema into virtualenv C:\slave\test-pgo\build/venv 15:51:43 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')]} 15:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:43 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')]} 15:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:43 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019756B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B3EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-269', 'USERDOMAIN': 'T-W732-IX-269', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-269', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 15:51:43 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test-pgo\build 15:51:43 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:51:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:43 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:43 INFO - 'DCLOCATION': 'SCL3', 15:51:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:43 INFO - 'HOMEDRIVE': 'C:', 15:51:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:43 INFO - 'KTS_VERSION': '1.19c', 15:51:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:44 INFO - 'MOZ_NO_REMOTE': '1', 15:51:44 INFO - 'NO_EM_RESTART': '1', 15:51:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:44 INFO - 'OS': 'Windows_NT', 15:51:44 INFO - 'OURDRIVE': 'C:', 15:51:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:44 INFO - 'PROCESSOR_LEVEL': '6', 15:51:44 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:44 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:44 INFO - 'PROMPT': '$P$G', 15:51:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:44 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:44 INFO - 'SYSTEMDRIVE': 'C:', 15:51:44 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:44 INFO - 'TEST1': 'testie', 15:51:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:44 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:44 INFO - 'USERNAME': 'cltbld', 15:51:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:44 INFO - 'WINDIR': 'C:\\windows', 15:51:44 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:44 INFO - Downloading/unpacking jsonschema 15:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:51:45 INFO - Downloading/unpacking functools32 (from jsonschema) 15:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:45 INFO - Downloading functools32-3.2.3-2.tar.gz 15:51:45 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\functools32\setup.py) egg_info for package functools32 15:51:45 INFO - warning: no files found matching '*.txt' 15:51:45 INFO - no previously-included directories found matching 'build' 15:51:45 INFO - no previously-included directories found matching 'dist' 15:51:45 INFO - no previously-included directories found matching '.git*' 15:51:45 INFO - Installing collected packages: jsonschema, functools32 15:51:45 INFO - Running setup.py install for functools32 15:51:45 INFO - warning: no files found matching '*.txt' 15:51:45 INFO - no previously-included directories found matching 'build' 15:51:45 INFO - no previously-included directories found matching 'dist' 15:51:45 INFO - no previously-included directories found matching '.git*' 15:51:45 INFO - Successfully installed jsonschema functools32 15:51:45 INFO - Cleaning up... 15:51:46 INFO - Return code: 0 15:51:46 INFO - Installing flake8 into virtualenv C:\slave\test-pgo\build/venv 15:51: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')]} 15:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51: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')]} 15:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:46 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019756B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B3EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-269', 'USERDOMAIN': 'T-W732-IX-269', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-269', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 15:51:46 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in C:\slave\test-pgo\build 15:51:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:51:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:46 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:46 INFO - 'DCLOCATION': 'SCL3', 15:51:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:46 INFO - 'HOMEDRIVE': 'C:', 15:51:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:46 INFO - 'KTS_VERSION': '1.19c', 15:51:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:46 INFO - 'MOZ_NO_REMOTE': '1', 15:51:46 INFO - 'NO_EM_RESTART': '1', 15:51:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:46 INFO - 'OS': 'Windows_NT', 15:51:46 INFO - 'OURDRIVE': 'C:', 15:51:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:46 INFO - 'PROCESSOR_LEVEL': '6', 15:51:46 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:46 INFO - 'PROMPT': '$P$G', 15:51:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:46 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:46 INFO - 'SYSTEMDRIVE': 'C:', 15:51:46 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:46 INFO - 'TEST1': 'testie', 15:51:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:46 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:46 INFO - 'USERNAME': 'cltbld', 15:51:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:46 INFO - 'WINDIR': 'C:\\windows', 15:51:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:46 INFO - Downloading/unpacking flake8 15:51:46 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 15:51:46 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 15:51:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:51:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:51:47 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 15:51:47 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 15:51:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:51:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:51:47 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 15:51:47 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 15:51:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:51:48 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 15:51:48 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 15:51:48 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\pep8\setup.py) egg_info for package pep8 15:51:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:48 INFO - no previously-included directories found matching 'docs\_build' 15:51:48 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:51:48 INFO - Running setup.py install for pep8 15:51:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:48 INFO - no previously-included directories found matching 'docs\_build' 15:51:48 INFO - Installing pep8-script.py script to C:\slave\test-pgo\build\venv\Scripts 15:51:48 INFO - Installing pep8.exe script to C:\slave\test-pgo\build\venv\Scripts 15:51:48 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:51:48 INFO - Cleaning up... 15:51:49 INFO - Return code: 0 15:51:49 INFO - Running post-action listener: _resource_record_post_action 15:51:49 INFO - Running post-action listener: _start_resource_monitoring 15:51:49 INFO - Starting resource monitoring. 15:51:49 INFO - ##### 15:51:49 INFO - ##### Running install step. 15:51:49 INFO - ##### 15:51:49 INFO - Running pre-action listener: _resource_record_pre_action 15:51:49 INFO - Running main action method: install 15:51:49 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 15:51:49 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 15:51:49 INFO - Reading from file tmpfile_stdout 15:51:49 INFO - Using _rmtree_windows ... 15:51:49 INFO - Using _rmtree_windows ... 15:51:49 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:49 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 15:51:49 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 15:51:49 INFO - Reading from file tmpfile_stdout 15:51:49 INFO - Output received: 15:51:49 INFO - Usage: mozinstall-script.py [options] installer 15:51:49 INFO - Options: 15:51:49 INFO - -h, --help show this help message and exit 15:51:49 INFO - -d DEST, --destination=DEST 15:51:49 INFO - Directory to install application into. [default: 15:51:49 INFO - "C:\slave\test-pgo"] 15:51:49 INFO - --app=APP Application being installed. [default: firefox] 15:51:49 INFO - Using _rmtree_windows ... 15:51:49 INFO - Using _rmtree_windows ... 15:51:49 INFO - mkdir: C:\slave\test-pgo\build\application 15:51:49 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-46.0.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 15:51:49 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-46.0.en-US.win32.zip --destination C:\slave\test-pgo\build\application 15:51:51 INFO - Reading from file tmpfile_stdout 15:51:51 INFO - Output received: 15:51:51 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 15:51:51 INFO - Using _rmtree_windows ... 15:51:51 INFO - Using _rmtree_windows ... 15:51:51 INFO - Running post-action listener: _resource_record_post_action 15:51:51 INFO - ##### 15:51:51 INFO - ##### Running run-tests step. 15:51:51 INFO - ##### 15:51:51 INFO - Running pre-action listener: _resource_record_pre_action 15:51:51 INFO - Running main action method: run_tests 15:51:51 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 15:51:51 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 15:51:51 INFO - Python 2.7.3 15:51:51 INFO - Return code: 0 15:51:51 INFO - grabbing minidump binary from tooltool 15:51: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')]} 15:51:51 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013A8BF0>, '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 0x013CA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A375C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 15:51:51 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build 15:51:51 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o 15:51:51 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 15:51:53 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpjk5yjq 15:51:53 INFO - INFO - File integrity verified, renaming tmpjk5yjq to win32-minidump_stackwalk.exe 15:51:53 INFO - Return code: 0 15:51:53 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 15:51:53 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 15:51:53 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 15:51:53 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 15:51:53 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 15:51:53 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\flake8', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos'] 15:51:53 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\flake8 C:\slave\test-pgo\build\tests\talos\talos 15:51:55 INFO - Return code: 0 15:51:55 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-269', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_errorsummary.log', '--log-raw=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_raw.log'] in C:\slave\test-pgo\build 15:51:55 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --branchName Mozilla-Beta --suite svgr --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-269 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log --log-raw=C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log 15:51:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 15:51:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 15:51:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 15:51:55 INFO - 'COMPUTERNAME': 'T-W732-IX-269', 15:51:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 15:51:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 15:51:55 INFO - 'DCLOCATION': 'SCL3', 15:51:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 15:51:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 15:51:55 INFO - 'HOMEDRIVE': 'C:', 15:51:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 15:51:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 15:51:55 INFO - 'KTS_VERSION': '1.19c', 15:51:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 15:51:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-269', 15:51:55 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 15:51:55 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 15:51:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 15:51:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 15:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:55 INFO - 'MOZ_NO_REMOTE': '1', 15:51:55 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 15:51:55 INFO - 'NO_EM_RESTART': '1', 15:51:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 15:51:55 INFO - 'OS': 'Windows_NT', 15:51:55 INFO - 'OURDRIVE': 'C:', 15:51:55 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 15:51:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 15:51:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 15:51:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 15:51:55 INFO - 'PROCESSOR_LEVEL': '6', 15:51:55 INFO - 'PROCESSOR_REVISION': '1e05', 15:51:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 15:51:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 15:51:55 INFO - 'PROMPT': '$P$G', 15:51:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 15:51:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 15:51:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 15:51:55 INFO - 'PWD': 'C:\\slave\\test-pgo', 15:51:55 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 15:51:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 15:51:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 15:51:55 INFO - 'SYSTEMDRIVE': 'C:', 15:51:55 INFO - 'SYSTEMROOT': 'C:\\windows', 15:51:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:55 INFO - 'TEST1': 'testie', 15:51:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 15:51:55 INFO - 'USERDOMAIN': 'T-W732-IX-269', 15:51:55 INFO - 'USERNAME': 'cltbld', 15:51:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 15:51:55 INFO - 'WINDIR': 'C:\\windows', 15:51:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 15:51:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 15:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 15:51:55 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-269', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_errorsummary.log', '--log-raw=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_raw.log'] with output_timeout 3600 15:51:56 INFO - mozversion application_buildid: 20160314120416 15:51:56 INFO - mozversion application_changeset: b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1 15:51:56 INFO - mozversion application_display_name: Firefox 15:51:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:51:56 INFO - mozversion application_name: Firefox 15:51:56 INFO - mozversion application_remotingname: firefox 15:51:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:51:56 INFO - mozversion application_vendor: Mozilla 15:51:56 INFO - mozversion application_version: 46.0 15:51:56 INFO - mozversion platform_buildid: 20160314120416 15:51:56 INFO - mozversion platform_changeset: b31b7bd99b5f4f0f5ce37fc807e2cf3f2f987cf1 15:51:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:51:56 INFO - mozversion platform_version: 46.0 15:51:56 INFO - using testdate: 1457995916 15:51:56 INFO - actual date: 1457995916 15:51:56 INFO - starting webserver on 'localhost:49679' 15:51:56 INFO - SUITE-START | Running 5 tests 15:51:56 INFO - TEST-START | tsvgx 15:51:56 INFO - operating with platform_type : w7_ 15:51:56 INFO - Initialising browser for tsvgx test... 15:51:56 INFO - TEST-INFO | started process 3076 (C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmgcfp3\profile http://localhost:49679/getInfo.html) 15:52:03 INFO - PROCESS | 3076 | __metrics Screen width/height:1600/1200 15:52:03 INFO - PROCESS | 3076 | colorDepth:24 15:52:03 INFO - PROCESS | 3076 | Browser inner width/height: 1010/674 15:52:03 INFO - PROCESS | 3076 | __metrics 15:52:03 INFO - PROCESS | 3076 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:52:03 INFO - PROCESS | 3076 | console.error: 15:52:03 INFO - PROCESS | 3076 | Message: Error: Connection closed before committing the transaction. 15:52:03 INFO - PROCESS | 3076 | Stack: 15:52:03 INFO - PROCESS | 3076 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:933:23 15:52:03 INFO - PROCESS | 3076 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7 15:52:03 INFO - PROCESS | 3076 | this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:746:11 15:52:03 INFO - PROCESS | 3076 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 15:52:04 INFO - PROCESS | 3076 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 15:52:04 INFO - PROCESS | 3076 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5 15:52:04 INFO - PROCESS | 3076 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 15:52:04 INFO - PROCESS | 3076 | Barrier/this.client.addBlocker/promisetsvgx: 382.76 16:10:44 INFO - RETURN: tsvgr_opacity: 497.16 16:10:44 INFO - RETURN: tart: 3.72 16:10:44 INFO - RETURN: tscrollx: 2.90 16:10:44 INFO - RETURN: cart: 23.60 16:10:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [248.0, 215.0, 215.0, 213.0, 211.0, 213.0, 209.0, 211.0, 214.0, 218.0, 208.0, 211.0, 209.0, 211.0, 215.0, 211.0, 209.0, 212.0, 209.0, 219.0, 209.0, 218.0, 209.0, 211.0, 215.0], "unit": "ms", "name": "gearflowers.svg", "value": 211.0}, {"lowerIsBetter": true, "replicates": [66.0, 42.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 40.0, 41.0, 41.0, 41.0, 41.0, 40.0, 41.0, 41.0, 41.0, 87.0, 41.0, 42.0, 41.0, 41.0], "unit": "ms", "name": "composite-scale.svg", "value": 41.0}, {"lowerIsBetter": true, "replicates": [129.0, 126.0, 123.0, 124.0, 125.0, 129.0, 124.0, 124.0, 123.0, 124.0, 124.0, 125.0, 125.0, 125.0, 124.0, 125.0, 125.0, 125.0, 124.0, 124.0, 123.0, 125.0, 126.0, 126.0, 124.0], "unit": "ms", "name": "composite-scale-opacity.svg", "value": 124.5}, {"lowerIsBetter": true, "replicates": [174.0, 169.0, 169.0, 167.0, 170.0, 166.0, 167.0, 169.0, 169.0, 168.0, 168.0, 167.0, 173.0, 170.0, 168.0, 168.0, 175.0, 174.0, 172.0, 168.0, 168.0, 168.0, 172.0, 166.0, 172.0], "unit": "ms", "name": "composite-scale-rotate.svg", "value": 168.0}, {"lowerIsBetter": true, "replicates": [247.0, 247.0, 247.0, 239.0, 245.0, 240.0, 238.0, 248.0, 239.0, 239.0, 270.0, 240.0, 249.0, 240.0, 242.0, 241.0, 248.0, 249.0, 245.0, 250.0, 243.0, 244.0, 242.0, 241.0, 243.0], "unit": "ms", "name": "composite-scale-rotate-opacity.svg", "value": 242.5}, {"lowerIsBetter": true, "replicates": [476.0, 427.0, 431.0, 432.0, 432.0, 433.0, 434.0, 431.0, 430.0, 431.0, 431.0, 428.0, 432.0, 432.0, 429.0, 432.0, 429.0, 427.0, 430.0, 433.0, 426.0, 435.0, 432.0, 429.0, 430.0], "unit": "ms", "name": "hixie-001.xml", "value": 431.0}, {"lowerIsBetter": true, "replicates": [438.0, 435.0, 435.0, 463.0, 425.0, 434.0, 431.0, 429.0, 427.0, 426.0, 427.0, 430.0, 429.0, 433.0, 425.0, 432.0, 429.0, 428.0, 433.0, 431.0, 444.0, 425.0, 431.0, 432.0, 431.0], "unit": "ms", "name": "hixie-002.xml", "value": 430.5}, {"lowerIsBetter": true, "replicates": [270.0, 214.0, 214.0, 2350.0, 213.0, 211.0, 213.0, 214.0, 215.0, 217.0, 216.0, 219.0, 213.0, 214.0, 213.0, 210.0, 213.0, 210.0, 208.0, 208.0, 206.0, 211.0, 213.0, 213.0, 212.0], "unit": "ms", "name": "hixie-003.xml", "value": 213.0}, {"lowerIsBetter": true, "replicates": [1133.0, 1116.0, 1095.0, 1140.0, 1121.0, 1098.0, 1119.0, 1086.0, 1117.0, 1111.0, 1108.0, 1114.0, 1112.0, 1108.0, 1128.0, 1091.0, 1092.0, 1108.0, 1140.0, 1080.0, 1110.0, 1127.0, 1088.0, 1106.0, 1132.0], "unit": "ms", "name": "hixie-004.xml", "value": 1109.0}, {"lowerIsBetter": true, "replicates": [2492.0, 2449.0, 2476.0, 2456.0, 2461.0, 2455.0, 2456.0, 2446.0, 2457.0, 2456.0, 2458.0, 2453.0, 2463.0, 2457.0, 2453.0, 2452.0, 2455.0, 2448.0, 2475.0, 2459.0, 2455.0, 2474.0, 2446.0, 2464.0, 2457.0], "unit": "ms", "name": "hixie-005.xml", "value": 2456.0}, {"lowerIsBetter": true, "replicates": [3756.0, 3731.0, 3765.0, 3731.0, 3727.0, 3747.0, 3732.0, 3720.0, 3728.0, 3771.0, 3764.0, 3852.0, 3741.0, 3728.0, 3764.0, 3721.0, 3726.0, 3734.0, 3734.0, 3734.0, 3730.0, 3738.0, 3739.0, 3721.0, 3722.0], "unit": "ms", "name": "hixie-006.xml", "value": 3734.0}, {"lowerIsBetter": true, "replicates": [674.0, 547.0, 544.0, 548.0, 556.0, 550.0, 562.0, 549.0, 551.0, 551.0, 545.0, 544.0, 545.0, 544.0, 543.0, 540.0, 547.0, 545.0, 543.0, 542.0, 566.0, 544.0, 559.0, 542.0, 547.0], "unit": "ms", "name": "hixie-007.xml", "value": 545.0}], "name": "tsvgx", "value": 382.7591006139563}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [737.0, 710.0, 711.0, 711.0, 67.0, 706.0, 708.0, 67.0, 715.0, 707.0, 66.0, 704.0, 67.0, 707.0, 69.0, 705.0, 708.0, 67.0, 705.0, 67.0, 704.0, 66.0, 705.0, 708.0, 709.0], "unit": "ms", "name": "big-optimizable-group-opacity-2500.svg", "value": 705.0}, {"lowerIsBetter": true, "replicates": [319.0, 352.0, 350.0, 356.0, 348.0, 351.0, 352.0, 350.0, 352.0, 352.0, 347.0, 352.0, 347.0, 350.0, 349.0, 352.0, 351.0, 350.0, 347.0, 352.0, 351.0, 350.0, 135.0, 353.0, 350.0], "unit": "ms", "name": "small-group-opacity-2500.svg", "value": 350.5}], "name": "tsvgr_opacity", "value": 497.15559818193327}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1.5081238142855755, 1.5097595344890247, 1.5489753262201944, 1.5447513802846273, 1.4975514860896322, 1.6059480673736997, 1.5073608252909276, 1.5636545387474265, 1.5503730726242066, 2.4225298886497817, 1.5521599213282267, 1.537516902287801, 1.566031141861065, 1.508343253816877, 1.5938590062807685, 1.54766974290212, 1.5676761044038308, 1.5569242416201412, 1.6229152830553726, 1.5264113655215816, 1.6799568853516509, 1.6318986180802466, 1.5381210645039876, 1.5542155133711326, 1.5474137512842814], "unit": "ms", "name": "simple-open-DPI1.half.TART", "value": 1.5512664969762167}, {"lowerIsBetter": true, "replicates": [1.690352095673416, 1.678951763159392, 1.7304810415319845, 1.7214036044620333, 1.6946554438273111, 1.8031299613046308, 1.6838470792138813, 1.7369892809489003, 1.748724888110983, 2.1012504647585972, 1.76170345230235, 1.721030303755322, 1.702655468211078, 1.6931655446688334, 1.7643354220522776, 1.7578057075368947, 1.7265940774865702, 1.7003539236386618, 1.763762104842398, 1.6990483474731446, 1.783504797028495, 1.7917920602879054, 1.7175561817916665, 1.7292193474412776, 1.732500281463675], "unit": "ms", "name": "simple-open-DPI1.all.TART", "value": 1.7298501944866311}, {"lowerIsBetter": true, "replicates": [25.51000000000022, 23.899999999997817, 24.62000000000262, 23.485000000000582, 24.514999999999418, 25.09000000001106, 24.670000000012806, 24.06500000000233, 23.889999999999418, 24.554999999993015, 24.35999999998603, 24.980000000010477, 24.08499999999185, 24.329999999987194, 24.394999999989523, 26.485000000015134, 24.105000000010477, 25.320000000006985, 24.519999999989523, 25.19000000000233, 25.420000000012806, 24.75, 24.60999999998603, 25.154999999969732, 25.035000000032596], "unit": "ms", "name": "simple-open-DPI1.error.TART", "value": 24.582499999989523}, {"lowerIsBetter": true, "replicates": [1.1555837309360504, 1.1117437361524656, 1.0748959634905664, 1.1468987152127936, 1.1056063850720723, 1.1281742190613466, 1.1201551076277947, 1.1162150691335018, 1.0957468492644173, 1.1279445793114455, 1.2067930145810049, 1.0835017617617813, 1.0625183308890107, 1.1257547192203188, 1.119141057857032, 1.0991260080110459, 1.108169623865531, 1.10582134528802, 1.1451916482188913, 1.1070804068675408, 1.1312085442683275, 1.1028708775838216, 1.0735511128549222, 1.091734674741637, 1.0915571597387206], "unit": "ms", "name": "simple-close-DPI1.half.TART", "value": 1.107625015366536}, {"lowerIsBetter": true, "replicates": [1.2660942749741178, 1.196807726633315, 1.1647291919261074, 1.327379605871566, 1.2329175449682004, 1.1935908221731, 1.2789112057985437, 1.2028104788532443, 1.200939433393525, 1.317739507523916, 1.3240321668418678, 1.185823876591562, 1.1733712774629776, 1.287402625146665, 1.3101463151234451, 1.19746277890369, 1.2928083236255343, 1.2127066109310929, 1.286244577483127, 1.276357929119889, 1.300804278634964, 1.1972463126568234, 1.1782801403449132, 1.1991926443343068, 1.2025973990632983], "unit": "ms", "name": "simple-close-DPI1.all.TART", "value": 1.2077585448921686}, {"lowerIsBetter": true, "replicates": [15.590000000000146, 17.1150000000016, 16.084999999999127, 16.885000000002037, 16.834999999999127, 16.15000000000873, 17.06500000000233, 18.43499999999767, 17.544999999998254, 17.785000000003492, 17.55999999999767, 17.17499999998836, 16.554999999993015, 17.230000000010477, 16.229999999981374, 17.154999999998836, 16.964999999996508, 16.70999999999185, 17.18000000002212, 16.570000000006985, 17.199999999982538, 16.854999999981374, 17.92499999998836, 17.289999999979045, 16.779999999969732], "unit": "ms", "name": "simple-close-DPI1.error.TART", "value": 17.090000000001965}, {"lowerIsBetter": true, "replicates": [1.5706183410979606, 1.5702706607612404, 1.5489241218566894, 1.5376756238937377, 1.51727126774035, 1.5801772075156644, 1.603958750764529, 1.845365283981202, 1.5804822967477041, 1.5647857608021916, 1.599590801530414, 1.5843064638033306, 1.5830124534972727, 1.605196065372891, 1.6676078170969866, 1.5918811722977522, 1.5696583599657625, 1.5674892970033594, 1.548591332435608, 1.6004174500703812, 1.577936347216776, 1.5756060848497364, 1.5741936532226768, 1.6283723468511877, 1.5823075852981985], "unit": "ms", "name": "icon-open-DPI1.half.TART", "value": 1.5803297521316844}, {"lowerIsBetter": true, "replicates": [1.7925411294883407, 1.8149836301803588, 1.7669940607415304, 1.7548502634311545, 1.753290449339768, 1.79752224851662, 1.8188318133354187, 1.964676168331733, 1.8363536073149538, 1.7979549161145385, 1.8265364008290428, 1.800817810897286, 1.8031517343318209, 1.807471013238244, 1.9533924812620336, 1.7849172495461845, 1.8038924940934418, 1.7850649465214123, 1.7682447052664227, 1.792711488797631, 1.796389189404501, 1.7978818945481743, 1.7897098408618444, 1.8616817684939309, 1.7980684814318804], "unit": "ms", "name": "icon-open-DPI1.all.TART", "value": 1.7980116987732093}, {"lowerIsBetter": true, "replicates": [25.17500000000109, 24.725000000002183, 25.599999999998545, 24.81999999999971, 24.540000000000873, 25.604999999995925, 24.895000000004075, 25.92500000000291, 25.610000000000582, 25.625, 26.010000000009313, 24.45000000001164, 24.539999999979045, 25.154999999998836, 28.425000000017462, 25.57500000001164, 24.820000000006985, 25.544999999983702, 24.84000000002561, 24.81500000000233, 25.364999999990687, 25.59499999997206, 24.525000000023283, 26.350000000034925, 25.794999999983702], "unit": "ms", "name": "icon-open-DPI1.error.TART", "value": 25.454999999987194}, {"lowerIsBetter": true, "replicates": [1.080634354987991, 1.0773757829844395, 1.07556705775662, 1.0610387188578965, 1.0652454396088917, 1.0664720910566825, 1.075157939830673, 1.0936727619396065, 1.0893691154021137, 1.1320217070626277, 1.0925787321801455, 1.0769774278747701, 1.0944479911957148, 1.0782191363450522, 1.0972023793629238, 1.0850556871601353, 1.1145424745403802, 1.086793450252065, 1.0969756376175654, 1.0928613014940947, 1.0958126806077504, 1.0932778896025892, 1.0692489472804245, 1.0995392629078455, 1.1716858178678184], "unit": "ms", "name": "icon-close-DPI1.half.TART", "value": 1.0909739237911296}, {"lowerIsBetter": true, "replicates": [1.1983966731443638, 1.2070994187752013, 1.1943675340675726, 1.1899844484711157, 1.2075501765106238, 1.17916813148902, 1.2059020289940199, 1.2030664764198602, 1.2000189794629228, 1.2054495040108175, 1.2076028889300776, 1.1940303628037616, 1.214647257977193, 1.2224249406833554, 1.2250715145468711, 1.2246548786759377, 1.223545126914978, 1.1926668281983404, 1.2336888067164824, 1.2073017202463805, 1.231259593963623, 1.2132376673186354, 1.210864964653464, 1.236063371449709, 1.2570416931899226], "unit": "ms", "name": "icon-close-DPI1.all.TART", "value": 1.2075765327203507}, {"lowerIsBetter": true, "replicates": [19.26000000000022, 17.830000000001746, 17.594999999993888, 17.959999999999127, 17.459999999999127, 18.494999999995343, 17.705000000001746, 18.27499999999418, 17.535000000003492, 18.744999999995343, 19.404999999998836, 17.614999999990687, 18.195000000006985, 18.47500000000582, 18.625, 17.949999999982538, 17.58499999999185, 18.595000000001164, 20.804999999993015, 19.120000000024447, 19.24000000001979, 19.05999999999767, 19.85999999998603, 20.06500000000233, 19.46500000002561], "unit": "ms", "name": "icon-close-DPI1.error.TART", "value": 18.485000000000582}, {"lowerIsBetter": true, "replicates": [1.598993491795328, 1.570929691598222, 1.627950498755549, 1.7198114893329677, 1.6214063469792757, 1.6998170123380774, 1.733148149589994, 1.602385441462199, 1.6346568876588847, 1.6489687800407409, 1.6388646112361425, 1.7225452963985615, 1.6046360913250182, 1.6542404890060425, 1.6853606389916462, 1.675588241521863, 1.6294107588244156, 1.633775316493612, 1.6478277615138464, 1.6501756259373257, 1.6475589752197266, 1.6318607531802756, 1.5887881697040716, 1.6161879284514322, 1.6566212551934378], "unit": "ms", "name": "icon-open-DPI2.half.TART", "value": 1.6432117932279344}, {"lowerIsBetter": true, "replicates": [1.8362021258899144, 1.7933168202847034, 1.8782957450431936, 1.933674903530063, 1.8510991049849468, 1.8840161325300442, 1.940165009462472, 1.835872910863204, 1.8814580458052017, 1.8904739512337578, 1.8718870617177366, 1.935638506304134, 1.8354170991362428, 1.8786797304363811, 1.9049573611857287, 1.9171467293474012, 1.862432942773304, 1.862284388855426, 1.8784736114389755, 1.8917624606026544, 1.8926925932919538, 1.8729113357908584, 1.8403482582929322, 1.831898626259395, 1.8632794061716456], "unit": "ms", "name": "icon-open-DPI2.all.TART", "value": 1.8783846782410847}, {"lowerIsBetter": true, "replicates": [27.289999999997235, 26.75, 25.900000000001455, 25.54500000000553, 25.915000000000873, 26.504999999990105, 26.485000000000582, 27.129999999990105, 26.369999999995343, 26.40000000000873, 26.75, 27.125, 25.56500000000233, 25.795000000012806, 25.730000000010477, 25.43000000002212, 26.05999999999767, 25.654999999998836, 26.019999999989523, 25.66500000000815, 25.970000000001164, 25.210000000020955, 26.080000000016298, 26.804999999993015, 25.64000000001397], "unit": "ms", "name": "icon-open-DPI2.error.TART", "value": 25.994999999995343}, {"lowerIsBetter": true, "replicates": [1.043218183087873, 1.0470397997986187, 1.0386275364471986, 1.0335814564355783, 1.1070869631856401, 1.0309483424893446, 1.0279449469276838, 1.0437371005883087, 1.0410941017640603, 1.0448994631165858, 1.0450378361049, 1.0427649976970914, 1.0581182372679405, 1.0482057533480904, 1.036745984107256, 1.055166049834785, 1.0586220146319187, 1.0397483910526242, 1.0324671837900365, 1.0377384708808348, 1.0561230366880243, 1.0615953327318943, 1.0386552144815255, 1.0572086946530777, 1.042090408436887], "unit": "ms", "name": "icon-close-DPI2.half.TART", "value": 1.0432510491427}, {"lowerIsBetter": true, "replicates": [1.1356156571446179, 1.1301406357023451, 1.1343414706212502, 1.1271604903832009, 1.1723595957916517, 1.1265836136956369, 1.1315083127417322, 1.1387591993919126, 1.1458649895911992, 1.129995806501546, 1.150717080475014, 1.13826009506403, 1.1531594139787387, 1.1410820229108944, 1.1356275005396022, 1.1468675610884813, 1.1362615366314732, 1.1508409252189136, 1.1324854973289702, 1.1239978819405108, 1.1633108246947916, 1.1591928561903395, 1.1467008488122807, 1.1430240327285395, 1.1458991869030712], "unit": "ms", "name": "icon-close-DPI2.all.TART", "value": 1.1399206111514035}, {"lowerIsBetter": true, "replicates": [16.49500000000262, 17.81500000000233, 18.57499999999709, 18.224999999998545, 18.80500000000029, 18.014999999999418, 19.25, 19.5, 19.889999999999418, 19.779999999998836, 19.80999999999767, 18.235000000015134, 18.07500000001164, 18.89000000001397, 17.68499999999767, 17.970000000001164, 17.95000000001164, 18.98499999998603, 18.10000000000582, 19.570000000006985, 19.10999999998603, 19.21999999997206, 20.149999999965075, 20.455000000016298, 18.679999999993015], "unit": "ms", "name": "icon-close-DPI2.error.TART", "value": 18.84750000000713}, {"lowerIsBetter": true, "replicates": [1.0238672157304476, 1.0230074746418842, 1.0247269578739606, 1.0235239995264374, 1.0395269844983075, 1.013491416186617, 1.0221137473013548, 1.0262901239690527, 1.0424605720751994, 1.0224773572609487, 1.0160008543416073, 1.042931052478584, 1.0153777071258479, 1.0184131201389617, 1.0174391604306405, 1.031356337347201, 1.0377315513722531, 1.0643643239222536, 1.016772217395013, 1.0204112608875848, 1.0200102751233937, 1.0257328210678776, 1.072957346284831, 1.0200714432032763, 1.256693677189424], "unit": "ms", "name": "iconFade-close-DPI2.half.TART", "value": 1.0232657370841607}, {"lowerIsBetter": true, "replicates": [1.0321706894752198, 1.0289424132984297, 1.0385082491806574, 1.0335435574561094, 1.0477136940032512, 1.028833667525148, 1.0304443484970502, 1.0334933026631674, 1.0443378871331834, 1.031010208667907, 1.030271097358349, 1.050213184859186, 1.0253467738365811, 1.0310662729022777, 1.0451179288427925, 1.0428656184619853, 1.0422250513538651, 1.061205731196837, 1.0307502472295171, 1.028406898880845, 1.0329266956117418, 1.0329981475277286, 1.064315553669516, 1.0372688368495022, 1.2745761777395788], "unit": "ms", "name": "iconFade-close-DPI2.all.TART", "value": 1.0335184300596385}, {"lowerIsBetter": true, "replicates": [2.8149999999986903, 2.6599999999998545, 2.7600000000020373, 3.125, 2.7400000000052387, 2.595000000001164, 2.6749999999883585, 2.389999999999418, 2.9799999999959255, 2.595000000001164, 2.8800000000046566, 4.4500000000116415, 2.7049999999871943, 3.0099999999802094, 3.1449999999895226, 2.444999999977881, 2.4649999999965075, 3.054999999993015, 3.0, 3.360000000015134, 2.5799999999871943, 3.455000000016298, 3.0949999999720603, 2.35999999998603, 4.260000000009313], "unit": "ms", "name": "iconFade-close-DPI2.error.TART", "value": 2.820000000003347}, {"lowerIsBetter": true, "replicates": [1.5340404733022055, 1.5507058652242025, 1.5929963327433965, 1.5899454077629194, 1.59188116739874, 1.6049294057819579, 1.6305951904243148, 1.6608890959194729, 1.6601265822138105, 1.6409035736406352, 1.5700890518523551, 1.5690927279962075, 1.6840083132619443, 1.5981401569313474, 1.5921494323913365, 1.5787622079457322, 1.7160295142846949, 1.6037347581651475, 1.6571698256901333, 1.5671935081481934, 1.563068156306808, 1.5828283420980793, 1.6230038520362642, 1.6000121351745393, 1.6396271930613988], "unit": "ms", "name": "iconFade-open-DPI2.half.TART", "value": 1.5990761460529432}, {"lowerIsBetter": true, "replicates": [1.4904405707732225, 1.4978521722249496, 1.5372693330833787, 1.485724019776484, 1.5164794558054442, 1.541049155178449, 1.5511308193206788, 1.5021769615911669, 1.5805374327160062, 1.5587526247805397, 1.5264061455633127, 1.5212962479373209, 1.5178134661990326, 1.535015884982912, 1.5354352460095757, 1.4987703141493676, 1.603312507169, 1.5399149727347672, 1.569873697048909, 1.5071936715033747, 1.49611434340477, 1.4617442623624262, 1.5544511008262634, 1.5565502723058064, 1.521860949354234], "unit": "ms", "name": "iconFade-open-DPI2.all.TART", "value": 1.5307110152731123}, {"lowerIsBetter": true, "replicates": [2.389999999999418, 3.6749999999992724, 3.6449999999967986, 3.2050000000017462, 3.570000000006985, 2.6849999999976717, 2.664999999993597, 2.875, 2.345000000001164, 2.3300000000017462, 3.4549999999871943, 2.8099999999976717, 3.75, 3.3299999999871943, 3.3649999999906868, 3.7449999999953434, 2.554999999993015, 2.3949999999895226, 2.3500000000058208, 3.6150000000197906, 3.415000000008149, 2.429999999993015, 3.179999999993015, 3.4500000000116415, 2.820000000006985], "unit": "ms", "name": "iconFade-open-DPI2.error.TART", "value": 3.1924999999973807}, {"lowerIsBetter": true, "replicates": [2.179866811685395, 1.6526386754853384, 1.7413438647540647, 1.736369127657876, 1.7211239355713575, 1.7155608131605036, 1.7231470826846451, 1.6949097829706528, 1.7292336919414464, 1.7725338037197407, 1.7316121681412655, 1.723508151609506, 1.721416238528579, 1.72638524824114, 2.3573153797461064, 1.686217669127644, 1.7041030771592085, 1.7258407802724127, 1.7265399996914081, 1.7049840092658997, 1.7092418302507961, 1.719668205104657, 1.8197834119200706, 1.7240087109453537, 1.817005444318056], "unit": "ms", "name": "newtab-open-preload-no.half.TART", "value": 1.72375843127743}, {"lowerIsBetter": true, "replicates": [3.530164192120234, 2.3932341309351344, 2.5122662069750765, 2.513718535599199, 2.5041037213455124, 2.488591085938574, 2.526044868955425, 2.5022993400258926, 2.505978991684404, 2.5921713417768477, 2.526530502473607, 2.516764976230322, 2.4631831396193733, 2.479335374557055, 3.1209596563130617, 2.4736049914589295, 2.489657326809411, 2.525950744455936, 2.5376889653065624, 2.5002414219587754, 2.4919538399548204, 2.4810714964727754, 2.644912139493592, 2.543348715742036, 2.5738547658920288], "unit": "ms", "name": "newtab-open-preload-no.all.TART", "value": 2.50912259932974}, {"lowerIsBetter": true, "replicates": [35.43000000000029, 26.44000000000233, 27.239999999997963, 29.325000000004366, 28.24000000000524, 26.830000000001746, 29.910000000003492, 28.095000000001164, 28.460000000006403, 29.635000000009313, 30.394999999989523, 27.154999999998836, 29.080000000016298, 28.160000000003492, 30.27499999999418, 27.75, 26.839999999996508, 28.045000000012806, 29.14000000001397, 27.954999999987194, 27.95000000001164, 25.975000000034925, 29.67499999998836, 27.304999999993015, 27.89000000001397], "unit": "ms", "name": "newtab-open-preload-no.error.TART", "value": 28.070000000006985}, {"lowerIsBetter": true, "replicates": [1.7156737516908085, 1.7110036944641787, 1.8049935828894377, 1.882988743243679, 1.785057961023771, 1.7317726434166751, 1.733400334173174, 1.7169286510837611, 1.8317730597087316, 1.751460440231092, 1.7493775486946106, 1.7590705300822402, 1.777691151545598, 1.8348815914184329, 1.8552110291296435, 1.7016297000295975, 1.7312912211489322, 1.7335206803990835, 1.7276289356288625, 1.7375669603917137, 1.7717185552303607, 1.736936524732789, 1.7353833451199887, 1.7934881393726056, 1.7354578562636873], "unit": "ms", "name": "newtab-open-preload-yes.half.TART", "value": 1.7434722545431622}, {"lowerIsBetter": true, "replicates": [1.9169590544879884, 1.9077460662762922, 1.9731907604276673, 2.0094712407100856, 1.9813082296718922, 1.9557197184273691, 1.979403861440145, 1.949567075028564, 2.010583912293742, 1.9794511187535067, 2.0001171734184027, 1.9791322664697042, 1.9767538692600044, 2.0096859122355153, 2.0442523837089537, 1.9231924867271482, 1.9618492244764139, 1.9917532638646662, 1.9640174549359541, 1.988392802171929, 2.0008116230484125, 1.9727725496658912, 1.99784086101739, 2.027083615618428, 1.9645402867060442], "unit": "ms", "name": "newtab-open-preload-yes.all.TART", "value": 1.9794274900968258}, {"lowerIsBetter": true, "replicates": [34.57999999999811, 34.18999999999505, 35.849999999998545, 36.125, 35.94000000000233, 38.74499999999534, 37.66000000000349, 37.66500000000815, 35.620000000009895, 37.75, 36.72500000000582, 35.73999999999069, 36.06500000000233, 35.80999999999767, 35.17500000001746, 36.345000000001164, 37.31500000000233, 35.64000000001397, 35.79999999998836, 37.23000000001048, 38.63000000000466, 36.90999999997439, 38.17499999998836, 38.89500000001863, 35.73500000004424], "unit": "ms", "name": "newtab-open-preload-yes.error.TART", "value": 36.23500000000058}], "name": "tart", "value": 3.7228424284569916}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [2.991449704142012, 2.0776331360946747, 2.1191420118343194, 2.1121005917159765, 2.0849999999999995, 2.102603550295858, 2.105147928994083, 2.1165088757396453, 2.099852071005917, 2.0871005917159766, 2.104792899408284, 2.087958579881657, 2.121715976331361, 2.105355029585799, 2.110591715976332, 2.115532544378698, 2.0868639053254436, 2.101775147928994, 2.0950591715976334, 2.1143786982248525, 2.1130769230769233, 2.104881656804734, 2.104881656804734, 2.114349112426036, 2.1046449704142014], "unit": "ms", "name": "tiled.html", "value": 2.104881656804734}, {"lowerIsBetter": true, "replicates": [3.13810650887574, 3.1410059171597635, 3.129644970414201, 3.148520710059172, 3.1302958579881657, 3.152633136094675, 3.1344970414201185, 3.139171597633136, 3.127130177514793, 3.1465976331360945, 3.1584615384615384, 3.152633136094675, 3.137988165680474, 3.137485207100591, 3.13896449704142, 3.1359171597633138, 3.1333136094674563, 3.149733727810651, 3.138047337278106, 3.1655917159763316, 3.1312426035502963, 3.1480177514792897, 3.1281360946745562, 3.1483727810650883, 3.1253846153846156], "unit": "ms", "name": "tiled-fixed.html", "value": 3.138505917159763}, {"lowerIsBetter": true, "replicates": [2.4134911242603554, 2.395118343195266, 2.3721005917159768, 2.386065088757397, 2.4000591715976336, 2.3760059171597634, 2.404585798816568, 2.3899112426035503, 2.3931952662721896, 2.4011834319526626, 2.382692307692307, 2.4003846153846156, 2.3724260355029587, 2.3993786982248526, 2.385621301775148, 2.3842899408284026, 2.3903846153846153, 2.3784615384615386, 2.3991715976331363, 2.389319526627219, 2.39707100591716, 2.4009763313609467, 2.4033136094674554, 2.3769526627218935, 2.3854733727810653], "unit": "ms", "name": "tiled-downscale.html", "value": 2.390147928994083}, {"lowerIsBetter": true, "replicates": [3.5479289940828402, 3.535798816568047, 3.530887573964497, 3.522988165680473, 3.5063313609467452, 3.5457988165680465, 3.533165680473372, 3.535502958579882, 3.524526627218935, 3.534792899408284, 3.520236686390532, 3.538284023668639, 3.5439349112426033, 3.533994082840236, 3.552337278106509, 3.536301775147929, 3.544319526627219, 3.5270414201183433, 3.5570710059171597, 3.534644970414201, 3.5471005917159766, 3.5320118343195266, 3.5357100591715973, 3.542573964497041, 3.5266568047337277], "unit": "ms", "name": "tiled-fixed-downscale.html", "value": 3.5356065088757394}, {"lowerIsBetter": true, "replicates": [3.3744186046511633, 3.3212790697674417, 3.3470348837209305, 3.3059883720930228, 3.338372093023256, 3.33453488372093, 3.321104651162791, 3.329825581395349, 3.333720930232558, 3.311860465116278, 3.3629651162790704, 3.332674418604651, 3.3394186046511636, 3.295348837209302, 3.3277325581395356, 3.316802325581395, 3.352558139534884, 3.3479069767441856, 3.3288953488372095, 3.269127906976744, 3.2921511627906983, 3.3340697674418607, 3.3210465116279066, 3.343197674418605, 3.2652906976744194], "unit": "ms", "name": "iframe.svg", "value": 3.329360465116279}, {"lowerIsBetter": true, "replicates": [3.0936416184971103, 3.087052023121388, 3.0677745664739886, 3.1087861271676305, 3.0906936416184974, 3.067514450867052, 3.107485549132948, 3.0928034682080927, 3.0771098265895955, 3.0888439306358384, 3.0556358381502897, 3.086994219653179, 3.098294797687861, 3.066878612716763, 3.078757225433526, 3.0884104046242773, 3.1038439306358385, 3.0802601156069365, 3.085433526011561, 3.0816473988439315, 3.1139884393063593, 3.106387283236994, 3.082803468208092, 3.095, 3.070491329479769], "unit": "ms", "name": "reader.htm", "value": 3.08621387283237}], "name": "tscrollx", "value": 2.895661816699953}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [5.316145193576813, 4.946077982584636, 4.938050690151396, 5.050250394003732, 5.227107661111014, 4.949899673461914, 5.086601975418272, 5.112262967228889, 4.936752410162063, 5.080110640752883, 5.025766168321882, 6.844843053817749, 5.115492971170516, 4.971019404275077, 5.00007871389389, 5.142368173599243, 5.119866004586219, 5.104156051363264, 5.033171784310114, 5.04291790439969, 4.830586887541271, 5.148077805836995, 5.136740809395199, 5.078958684489841, 4.868602400734311], "unit": "ms", "name": "1-customize-enter.half.TART", "value": 5.064604539246787}, {"lowerIsBetter": true, "replicates": [11.013525968012603, 9.57751974948617, 10.043751071139079, 10.154669587205095, 9.9077810219356, 9.842236768631707, 10.121196440080316, 10.316659533395999, 9.844184148879279, 10.16034531011814, 9.787882975169591, 13.105586667855581, 10.216033919555384, 10.097229527264107, 10.454354559502951, 10.224787348792667, 10.654763634502888, 10.216698745401894, 10.219892568704559, 9.972858937013717, 9.71949527377174, 9.900302330652872, 9.849541658446903, 10.083209056679795, 9.901198301996503], "unit": "ms", "name": "1-customize-enter.all.TART", "value": 10.090219291971952}, {"lowerIsBetter": true, "replicates": [338.15999999999985, 220.28499999999804, 220.59999999999854, 225.1150000000016, 224.5099999999984, 221.73500000000058, 223.36999999999534, 231.64500000000407, 221.79499999999825, 225.0500000000029, 219.71500000000378, 282.5049999999974, 227.1949999999997, 222.63999999999942, 237.38499999999476, 238.36000000000058, 234.7549999999901, 227.43500000001222, 227.60500000001048, 227.25500000000466, 217.33000000000175, 224.22999999999593, 222.59999999999127, 222.20000000001164, 224.27000000000407], "unit": "ms", "name": "1-customize-enter.error.TART", "value": 224.39000000000124}, {"lowerIsBetter": true, "replicates": [8.58235239982605, 8.022074048335735, 8.622937826009897, 8.398271120511568, 8.355896264314651, 8.850865960121155, 9.088989863028893, 8.4467134475708, 8.43002462387085, 8.262720905817472, 8.457317774112408, 8.0378767985564, 8.447304377189049, 8.53199003292964, 8.36040817697843, 8.422196874251732, 8.555915970068712, 8.155792905734135, 8.322092368052555, 8.212565183639526, 8.596087740017818, 8.534914310161884, 8.412390323785635, 8.610295488284184, 8.336270763323856], "unit": "ms", "name": "2-customize-exit.half.TART", "value": 8.42611074906129}, {"lowerIsBetter": true, "replicates": [10.112781014707354, 9.323464952017131, 10.47924120987163, 10.331177101415747, 10.56899444846546, 10.729978659573723, 10.19909976550511, 10.15099069731576, 9.559135791417715, 10.274605691432953, 10.351291684543385, 9.945487151543299, 9.989038659466637, 10.020969935825892, 10.861549431627447, 9.557049715841138, 10.08900295325688, 9.39435243920276, 10.376420771374422, 10.06205746105739, 10.248547462054661, 10.325417189037099, 10.060589803589714, 10.575895116609686, 10.057667146126429], "unit": "ms", "name": "2-customize-exit.all.TART", "value": 10.175045231410436}, {"lowerIsBetter": true, "replicates": [185.72000000000116, 176.22000000000116, 177.25, 173.70500000000175, 180.49499999999898, 184.3050000000003, 178.34500000000116, 175.7699999999968, 175.48999999999796, 169.97499999999854, 173.57500000000437, 178.69000000000233, 180.5399999999936, 172.86500000000524, 179.19000000000233, 174.42499999998836, 173.83000000000175, 177.41500000000815, 173.27999999999884, 173.4250000000029, 181.06500000000233, 172.32000000000698, 182.95500000000175, 180.52499999999418, 182.9149999999936], "unit": "ms", "name": "2-customize-exit.error.TART", "value": 176.73500000000058}, {"lowerIsBetter": true, "replicates": [10.064476871490479, 10.249139976501464, 10.06405429840088, 10.162049865722656, 10.16757936477661, 9.855586658824574, 9.61112135106867, 10.126760673522949, 9.82458764856512, 10.025885009765625, 10.177563190460205, 9.373987024480646, 9.802350911227139, 9.881209633567117, 10.227674579620361, 10.522928524017335, 9.785874193364924, 10.390411853790283, 9.752187382091176, 10.518128585815429, 10.47162675857544, 10.207284450531006, 9.744088346307928, 10.353087329864502, 9.484019019386984], "unit": "ms", "name": "3-customize-enter-css.half.TART", "value": 10.095407485961914}, {"lowerIsBetter": true, "replicates": [13.592874526977539, 13.203752708435058, 13.429215717315675, 13.677142095565795, 13.71690492630005, 13.314669847488403, 12.787992204938616, 13.967788362503052, 12.994727725074405, 13.384652900695801, 13.69179162979126, 12.778520266215006, 12.943400155930291, 13.405484914779663, 13.843585062026978, 14.016897050957931, 12.987596421014695, 13.76951231956482, 13.285217380523681, 14.144565431695236, 13.669769382476806, 13.965482059277987, 13.100015685671853, 13.745761966705322, 13.54840784072876], "unit": "ms", "name": "3-customize-enter-css.all.TART", "value": 13.488811779022218}, {"lowerIsBetter": true, "replicates": [71.72000000000116, 63.93000000000029, 68.38000000000102, 73.24500000000262, 74.02999999999884, 66.21499999999651, 68.3550000000032, 79.11999999999534, 72.47499999999854, 67.32500000000437, 73.71499999999651, 68.07500000000437, 71.71499999999651, 67.86000000000058, 76.59500000000116, 66.0, 72.68000000000757, 75.22000000000116, 65.25999999999476, 68.47499999999127, 73.04000000000815, 65.31999999999243, 74.91000000000349, 74.60499999999593, 70.6649999999936], "unit": "ms", "name": "3-customize-enter-css.error.TART", "value": 71.18999999999505}], "name": "cart", "value": 23.595400688830836}]} 16:10:44 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tscrollx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[287,53,25]]", "result": "2.90"}, "tsvgr_opacity": {"url": "http://graphs.mozilla.org/graph.html#tests=[[225,53,25]]", "result": "497.16"}, "tart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[293,53,25]]", "result": "3.72"}, "cart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[309,53,25]]", "result": "23.60"}, "tsvgx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[281,53,25]]", "result": "382.76"}}} 16:10:44 INFO - Return code: 0 16:10:44 INFO - Validating PERFHERDER_DATA against C:\slave\test-pgo\build\tests\talos\treeherder-schemas\performance-artifact.json 16:10:44 INFO - # TBPL SUCCESS # 16:10:44 INFO - Running post-action listener: _resource_record_post_action 16:10:44 INFO - Running post-run listener: _resource_record_post_run 16:10:45 INFO - Total resource usage - Wall time: 1134s; CPU: 8.0%; Read bytes: 51414528; Write bytes: 499765760; Read time: 990310; Write time: 9023970 16:10:45 INFO - install - Wall time: 2s; CPU: 9.0%; Read bytes: 0; Write bytes: 16339968; Read time: 0; Write time: 257220 16:10:45 INFO - run-tests - Wall time: 1133s; CPU: 8.0%; Read bytes: 51414528; Write bytes: 471320576; Read time: 990310; Write time: 8685280 16:10:45 INFO - Running post-run listener: _upload_blobber_files 16:10:45 INFO - Blob upload gear active. 16:10:45 INFO - Preparing to upload files from C:\slave\test-pgo\build\blobber_upload_dir. 16:10:45 INFO - Files from C:\slave\test-pgo\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:10:45 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test-pgo\\oauth.txt', '-b', 'Mozilla-Beta', '-d', 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test-pgo\\build\\uploaded_files.json'] 16:10:45 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test-pgo\oauth.txt -b Mozilla-Beta -d C:\slave\test-pgo\build\blobber_upload_dir --output-manifest C:\slave\test-pgo\build\uploaded_files.json 16:10:46 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:46 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log ... 16:10:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:47 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_errorsummary.log: uploaded 16:10:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:47 INFO - (blobuploader) - INFO - Done attempting. 16:10:47 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log ... 16:10:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:49 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_raw.log: uploaded 16:10:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:49 INFO - (blobuploader) - INFO - Done attempting. 16:10:49 INFO - (blobuploader) - INFO - Iteration through files over. 16:10:49 INFO - Return code: 0 16:10:49 INFO - rmtree: C:\slave\test-pgo\build\uploaded_files.json 16:10:49 INFO - Using _rmtree_windows ... 16:10:49 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build\\uploaded_files.json',), kwargs: {}, attempt #1 16:10:49 INFO - Setting buildbot property blobber_files to {"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8bfc9221fb18068ca157dbca38a4bc70857e71a8e5225a6c71c4146f981ace0980f92e5570457a48d6e38383b2cfe2a25261be906d2ae364c7892347da5b5fe4", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c7df75e1a4f123bfa7b7a8bc14e5f2101b04a7886469e42394fe11bbd8fa1f72329dde52163520bd1860ba21af6d1b56fdf9b87fdc9f779fd662b40f478e5cc"} 16:10:49 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test-pgo\properties\blobber_files 16:10:49 INFO - Writing to file C:\slave\test-pgo\properties\blobber_files 16:10:49 INFO - Contents: 16:10:49 INFO - blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8bfc9221fb18068ca157dbca38a4bc70857e71a8e5225a6c71c4146f981ace0980f92e5570457a48d6e38383b2cfe2a25261be906d2ae364c7892347da5b5fe4", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c7df75e1a4f123bfa7b7a8bc14e5f2101b04a7886469e42394fe11bbd8fa1f72329dde52163520bd1860ba21af6d1b56fdf9b87fdc9f779fd662b40f478e5cc"} 16:10:49 INFO - Running post-run listener: copy_logs_to_upload_dir 16:10:49 INFO - Copying logs to upload dir... 16:10:49 INFO - mkdir: C:\slave\test-pgo\build\upload\logs 16:10:49 INFO - Copying logs to upload dir... 16:10:49 INFO - Using _rmtree_windows ... 16:10:49 INFO - Using _rmtree_windows ... 16:10:49 INFO - Using _rmtree_windows ... 16:10:49 INFO - Using _rmtree_windows ... 16:10:49 INFO - Using _rmtree_windows ... 16:10:49 INFO - Using _rmtree_windows ... 16:10:49 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1413.346000 ========= master_lag: 0.02 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 33 secs) (at 2016-03-14 16:10:49.920323) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-14 16:10:49.921249) ========= 'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f' in dir C:\slave\test-pgo\properties (timeout 1200 secs) watching logfiles {} argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-269 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-269 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:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo\properties RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-269 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8bfc9221fb18068ca157dbca38a4bc70857e71a8e5225a6c71c4146f981ace0980f92e5570457a48d6e38383b2cfe2a25261be906d2ae364c7892347da5b5fe4", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c7df75e1a4f123bfa7b7a8bc14e5f2101b04a7886469e42394fe11bbd8fa1f72329dde52163520bd1860ba21af6d1b56fdf9b87fdc9f779fd662b40f478e5cc"} build_url:https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/FWC0kJASQU-NBkxWemwIeA/artifacts/public/build/firefox-46.0.en-US.win32.zip' blobber_files: '{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8bfc9221fb18068ca157dbca38a4bc70857e71a8e5225a6c71c4146f981ace0980f92e5570457a48d6e38383b2cfe2a25261be906d2ae364c7892347da5b5fe4", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c7df75e1a4f123bfa7b7a8bc14e5f2101b04a7886469e42394fe11bbd8fa1f72329dde52163520bd1860ba21af6d1b56fdf9b87fdc9f779fd662b40f478e5cc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-14 16:10:50.044955) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 16:10:50.045410) ========= 'rm' '-f' 'oauth.txt' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-269 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-269 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:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-269 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 16:10:50.163630) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-14 16:10:50.164040) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-14 16:10:51.008550) ========= ========= Total master_lag: 1.89 =========