builder: mozilla-beta_win7-ix_test-svgr-pgo slave: t-w732-ix-181 starttime: 1457678557.58 results: success (0) buildid: 20160310191740 builduid: c8e12bf7bd4745e1919830922edf5afe revision: 577c2d4465976cda722c0da98a0a6635b37bb689 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:37.579776) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:37.580279) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:37.580584) ========= '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-181 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-181 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-181 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=0.526000 basedir: 'C:\\slave\\test-pgo' ========= master_lag: 0.10 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:38.205008) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:38.205423) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:38.339190) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:38.339613) ========= '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-181 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-181 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-181 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.701000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:39.058711) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:39.059028) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:39.059414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:39.059691) ========= '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-181 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-181 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-181 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 --22:42:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 8.40 MB/s 22:42:40 (8.40 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.937000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:40.017426) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-03-10 22:42:40.017781) ========= '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-181 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-181 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-181 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=11.301000 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-03-10 22:42:51.353581) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-03-10 22:42:51.354008) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 577c2d4465976cda722c0da98a0a6635b37bb689 --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 577c2d4465976cda722c0da98a0a6635b37bb689 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-181 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-181 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-181 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-10 22:42:52,336 truncating revision to first 12 chars 2016-03-10 22:42:52,336 Setting DEBUG logging. 2016-03-10 22:42:52,336 attempt 1/10 2016-03-10 22:42:52,336 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/577c2d446597?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-10 22:42:53,572 unpacking tar archive at: mozilla-beta-577c2d446597/testing/mozharness/ program finished with exit code 0 elapsedTime=2.383000 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-03-10 22:42:53.799839) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:53.800267) ========= 'echo' u'577c2d4465976cda722c0da98a0a6635b37bb689' in dir C:\\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'577c2d4465976cda722c0da98a0a6635b37bb689'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-181 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-181 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-181 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 577c2d4465976cda722c0da98a0a6635b37bb689 program finished with exit code 0 elapsedTime=0.101000 script_repo_revision: '577c2d4465976cda722c0da98a0a6635b37bb689' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:53.923132) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:53.923517) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-10 22:42:53.940649) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 26 secs) (at 2016-03-10 22:42:53.941110) ========= '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-181 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-181 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-181 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 22:42:54 INFO - MultiFileLogger online at 20160310 22:42:54 in C:\slave\test-pgo 22:42:54 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 22:42:54 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 22:42:54 INFO - {'append_to_log': False, 22:42:54 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 22:42:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:42:54 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 22:42:54 INFO - 'branch': 'Mozilla-Beta', 22:42:54 INFO - 'buildbot_json_path': 'buildprops.json', 22:42:54 INFO - 'config_files': ('talos/windows_config.py',), 22:42:54 INFO - 'default_actions': ('clobber', 22:42:54 INFO - 'read-buildbot-config', 22:42:54 INFO - 'download-and-extract', 22:42:54 INFO - 'populate-webroot', 22:42:54 INFO - 'create-virtualenv', 22:42:54 INFO - 'install', 22:42:54 INFO - 'run-tests'), 22:42:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:42:54 INFO - 'download_minidump_stackwalk': True, 22:42:54 INFO - 'download_symbols': 'ondemand', 22:42:54 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 22:42:54 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 22:42:54 INFO - 'hg': 'c:/mozilla-build/hg/hg', 22:42:54 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 22:42:54 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 22:42:54 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 22:42:54 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 22:42:54 INFO - 'C:/mozilla-build/tooltool.py'), 22:42:54 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 22:42:54 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 22:42:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:42:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:42:54 INFO - 'installer_path': 'installer.exe', 22:42:54 INFO - 'log_level': 'info', 22:42:54 INFO - 'log_name': 'talos', 22:42:54 INFO - 'log_to_console': True, 22:42:54 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 22:42:54 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 22:42:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 22:42:54 INFO - 'opt_config_files': (), 22:42:54 INFO - 'pip_index': False, 22:42:54 INFO - 'sps_profile': False, 22:42:54 INFO - 'sps_profile_interval': 0, 22:42:54 INFO - 'suite': 'svgr', 22:42:54 INFO - 'system_bits': '32', 22:42:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:42:54 INFO - 'title': 'T-W732-IX-181', 22:42:54 INFO - 'use_talos_json': True, 22:42:54 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 22:42:54 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 22:42:54 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 22:42:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:42:54 INFO - 'work_dir': 'build'} 22:42:54 INFO - ##### 22:42:54 INFO - ##### Running clobber step. 22:42:54 INFO - ##### 22:42:54 INFO - Running pre-action listener: _resource_record_pre_action 22:42:54 INFO - Running main action method: clobber 22:42:54 INFO - rmtree: C:\slave\test-pgo\build 22:42:54 INFO - Using _rmtree_windows ... 22:42:54 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 22:44:27 INFO - Running post-action listener: _resource_record_post_action 22:44:27 INFO - ##### 22:44:27 INFO - ##### Running read-buildbot-config step. 22:44:27 INFO - ##### 22:44:27 INFO - Running pre-action listener: _resource_record_pre_action 22:44:27 INFO - Running main action method: read_buildbot_config 22:44:27 INFO - Using buildbot properties: 22:44:27 INFO - { 22:44:27 INFO - "project": "", 22:44:27 INFO - "product": "firefox", 22:44:27 INFO - "script_repo_revision": "production", 22:44:27 INFO - "builddir": "mozilla-beta_win7-ix_test-svgr", 22:44:27 INFO - "repository": "", 22:44:27 INFO - "buildername": "Windows 7 32-bit mozilla-beta pgo talos svgr", 22:44:27 INFO - "buildid": "20160310191740", 22:44:27 INFO - "pgo_build": "True", 22:44:27 INFO - "basedir": "C:\\slave\\test-pgo", 22:44:27 INFO - "buildnumber": 4, 22:44:27 INFO - "slavename": "t-w732-ix-181", 22:44:27 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 22:44:27 INFO - "platform": "win7-ix", 22:44:27 INFO - "scheduler": "tests-mozilla-beta-win32-pgo-talos", 22:44:27 INFO - "branch": "mozilla-beta", 22:44:27 INFO - "repo_path": "releases/mozilla-beta", 22:44:27 INFO - "revision": "577c2d4465976cda722c0da98a0a6635b37bb689", 22:44:27 INFO - "stage_platform": "win32-pgo", 22:44:27 INFO - "builduid": "c8e12bf7bd4745e1919830922edf5afe", 22:44:27 INFO - "slavebuilddir": "test" 22:44:27 INFO - } 22:44:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.zip. 22:44:27 INFO - Running post-action listener: _resource_record_post_action 22:44:27 INFO - ##### 22:44:27 INFO - ##### Running download-and-extract step. 22:44:27 INFO - ##### 22:44:27 INFO - Running pre-action listener: _resource_record_pre_action 22:44:27 INFO - Running main action method: download_and_extract 22:44:27 INFO - mkdir: C:\slave\test-pgo\build\tests 22:44:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:27 INFO - https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:44:27 INFO - trying https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/test_packages.json 22:44:27 INFO - Downloading https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 22:44:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 22:44:28 INFO - Downloaded 1267 bytes. 22:44:28 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 22:44:28 INFO - Using the following test package requirements: 22:44:28 INFO - {u'common': [u'firefox-46.0.en-US.win32.common.tests.zip'], 22:44:28 INFO - u'cppunittest': [u'firefox-46.0.en-US.win32.common.tests.zip', 22:44:28 INFO - u'firefox-46.0.en-US.win32.cppunittest.tests.zip'], 22:44:28 INFO - u'gtest': [u'firefox-46.0.en-US.win32.common.tests.zip'], 22:44:28 INFO - u'jittest': [u'firefox-46.0.en-US.win32.common.tests.zip', 22:44:28 INFO - u'jsshell-win32.zip'], 22:44:28 INFO - u'mochitest': [u'firefox-46.0.en-US.win32.common.tests.zip', 22:44:28 INFO - u'firefox-46.0.en-US.win32.mochitest.tests.zip'], 22:44:28 INFO - u'mozbase': [u'firefox-46.0.en-US.win32.common.tests.zip'], 22:44:28 INFO - u'reftest': [u'firefox-46.0.en-US.win32.common.tests.zip', 22:44:28 INFO - u'firefox-46.0.en-US.win32.reftest.tests.zip'], 22:44:28 INFO - u'talos': [u'firefox-46.0.en-US.win32.common.tests.zip', 22:44:28 INFO - u'firefox-46.0.en-US.win32.talos.tests.zip'], 22:44:28 INFO - u'web-platform': [u'firefox-46.0.en-US.win32.common.tests.zip', 22:44:28 INFO - u'firefox-46.0.en-US.win32.web-platform.tests.zip'], 22:44:28 INFO - u'webapprt': [u'firefox-46.0.en-US.win32.common.tests.zip'], 22:44:28 INFO - u'xpcshell': [u'firefox-46.0.en-US.win32.common.tests.zip', 22:44:28 INFO - u'firefox-46.0.en-US.win32.xpcshell.tests.zip']} 22:44:28 INFO - Downloading packages: [u'firefox-46.0.en-US.win32.common.tests.zip'] for test suite category: common 22:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:28 INFO - https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 22:44:28 INFO - trying https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip 22:44:28 INFO - Downloading https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/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 22:44:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/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 22:44:30 INFO - Downloaded 17906903 bytes. 22:44:30 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'] 22:44:30 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 22:44:59 INFO - Return code: 0 22:44:59 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 22:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:59 INFO - https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 22:44:59 INFO - trying https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip 22:44:59 INFO - Downloading https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/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 22:44:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/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 22:45:01 INFO - Downloaded 17906903 bytes. 22:45: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'] 22:45: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 22:45:22 INFO - Return code: 0 22:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:22 INFO - https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 22:45:22 INFO - trying https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip 22:45:22 INFO - Downloading https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/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 22:45:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/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 22:45:26 INFO - Downloaded 10801536 bytes. 22:45:26 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'] 22:45:26 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 22:45:28 INFO - Return code: 0 22:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:28 INFO - https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.zip matches https://queue.taskcluster.net 22:45:28 INFO - trying https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.zip 22:45:28 INFO - Downloading https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.zip to C:\slave\test-pgo\build\firefox-46.0.en-US.win32.zip 22:45:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/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 22:45:32 INFO - Downloaded 55024313 bytes. 22:45:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.zip 22:45:32 INFO - mkdir: C:\slave\test-pgo\properties 22:45:32 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 22:45:32 INFO - Writing to file C:\slave\test-pgo\properties\build_url 22:45:32 INFO - Contents: 22:45:32 INFO - build_url:https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.zip 22:45:32 INFO - Running post-action listener: _resource_record_post_action 22:45:32 INFO - Running post-action listener: set_extra_try_arguments 22:45:32 INFO - ##### 22:45:32 INFO - ##### Running populate-webroot step. 22:45:32 INFO - ##### 22:45:32 INFO - Running pre-action listener: _resource_record_pre_action 22:45:32 INFO - Running main action method: populate_webroot 22:45:32 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 22:45:32 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 22:45:32 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 22:45:32 INFO - u'--tppagecycles', 22:45:32 INFO - u'7'], 22:45:32 INFO - u'tests': [u'tsvgm']}}, 22:45:32 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 22:45:32 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 22:45:32 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 22:45:32 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 22:45:32 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 22:45:32 INFO - u'tests': [u'dromaeo_css', 22:45:32 INFO - u'kraken', 22:45:32 INFO - u'v8_7']}, 22:45:32 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:32 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 22:45:32 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:32 INFO - u'talos_options': [u'--e10s'], 22:45:32 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 22:45:32 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:32 INFO - u'tests': [u'damp', u'tps']}, 22:45:32 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:32 INFO - u'talos_options': [u'--e10s'], 22:45:32 INFO - u'tests': [u'damp', u'tps']}, 22:45:32 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 22:45:32 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 22:45:32 INFO - u'other': {u'tests': [u'a11yr', 22:45:32 INFO - u'ts_paint', 22:45:32 INFO - u'tpaint', 22:45:32 INFO - u'sessionrestore', 22:45:32 INFO - u'sessionrestore_no_auto_restore']}, 22:45:32 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 22:45:32 INFO - u'tests': [u'a11yr', 22:45:32 INFO - u'ts_paint', 22:45:32 INFO - u'tpaint', 22:45:32 INFO - u'sessionrestore', 22:45:32 INFO - u'sessionrestore_no_auto_restore']}, 22:45:32 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 22:45:32 INFO - u'tests': [u'a11yr', 22:45:32 INFO - u'ts_paint', 22:45:32 INFO - u'tpaint', 22:45:32 INFO - u'sessionrestore', 22:45:32 INFO - u'sessionrestore_no_auto_restore']}, 22:45:32 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 22:45:33 INFO - u'tests': [u'a11yr', 22:45:33 INFO - u'ts_paint', 22:45:33 INFO - u'tpaint', 22:45:33 INFO - u'sessionrestore', 22:45:33 INFO - u'sessionrestore_no_auto_restore']}, 22:45:33 INFO - u'other_l64': {u'tests': [u'a11yr', 22:45:33 INFO - u'ts_paint', 22:45:33 INFO - u'tpaint', 22:45:33 INFO - u'sessionrestore', 22:45:33 INFO - u'sessionrestore_no_auto_restore']}, 22:45:33 INFO - u'other_nol64': {u'tests': [u'a11yr', 22:45:33 INFO - u'ts_paint', 22:45:33 INFO - u'tpaint', 22:45:33 INFO - u'sessionrestore', 22:45:33 INFO - u'sessionrestore_no_auto_restore']}, 22:45:33 INFO - u'svgr': {u'tests': [u'tsvgx', 22:45:33 INFO - u'tsvgr_opacity', 22:45:33 INFO - u'tart', 22:45:33 INFO - u'tscrollx', 22:45:33 INFO - u'cart']}, 22:45:33 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 22:45:33 INFO - u'tests': [u'tsvgx', 22:45:33 INFO - u'tsvgr_opacity', 22:45:33 INFO - u'tart', 22:45:33 INFO - u'tscrollx', 22:45:33 INFO - u'cart']}, 22:45:33 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:33 INFO - u'tests': [u'tp5o']}, 22:45:33 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:33 INFO - u'talos_options': [u'--e10s'], 22:45:33 INFO - u'tests': [u'tp5o']}, 22:45:33 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:33 INFO - u'talos_options': [u'--xperf_path', 22:45:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:33 INFO - u'tests': [u'tp5n']}, 22:45:33 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:33 INFO - u'talos_options': [u'--e10s', 22:45:33 INFO - u'--xperf_path', 22:45:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:33 INFO - u'tests': [u'tp5n']}}, 22:45:33 INFO - u'talos.zip': {u'path': u'', 22:45:33 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:45:33 INFO - Running post-action listener: _resource_record_post_action 22:45:33 INFO - ##### 22:45:33 INFO - ##### Running create-virtualenv step. 22:45:33 INFO - ##### 22:45:33 INFO - Running pre-action listener: _resource_record_pre_action 22:45:33 INFO - Running main action method: create_virtualenv 22:45:33 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 22:45:33 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 22:45:33 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 22:45:33 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 22:45:33 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build 22:45:33 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv 22:45:37 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 22:45:41 INFO - Installing distribute......................................................................................................................................................................................done. 22:45:42 INFO - Return code: 0 22:45:42 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 22:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:42 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 0x017CB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01871EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0177B608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-181', 'USERDOMAIN': 'T-W732-IX-181', '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-181', '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 22:45:42 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 22:45:42 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 22:45:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:45:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:45:42 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:45:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:45:42 INFO - 'DCLOCATION': 'SCL3', 22:45:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:42 INFO - 'HOMEDRIVE': 'C:', 22:45:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:45:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:42 INFO - 'KTS_VERSION': '1.19c', 22:45:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:45:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:45:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:42 INFO - 'MOZ_NO_REMOTE': '1', 22:45:42 INFO - 'NO_EM_RESTART': '1', 22:45:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:42 INFO - 'OS': 'Windows_NT', 22:45:42 INFO - 'OURDRIVE': 'C:', 22:45:42 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;', 22:45:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:42 INFO - 'PROCESSOR_LEVEL': '6', 22:45:42 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:45:42 INFO - 'PROMPT': '$P$G', 22:45:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:42 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:42 INFO - 'SYSTEMDRIVE': 'C:', 22:45:42 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:42 INFO - 'TEST1': 'testie', 22:45:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:42 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:45:42 INFO - 'USERNAME': 'cltbld', 22:45:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:45:42 INFO - 'WINDIR': 'C:\\windows', 22:45:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:45:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:44 INFO - Ignoring indexes: http://pypi.python.org/simple/ 22:45:44 INFO - Downloading/unpacking pip>=1.5 22:45:48 INFO - Running setup.py egg_info for package pip 22:45:48 INFO - warning: no files found matching 'pip\cacert.pem' 22:45:48 INFO - warning: no files found matching '*.html' under directory 'docs' 22:45:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 22:45:48 INFO - no previously-included directories found matching 'docs\_build\_sources' 22:45:48 INFO - Installing collected packages: pip 22:45:48 INFO - Found existing installation: pip 0.8.2 22:45:48 INFO - Uninstalling pip: 22:45:48 INFO - Successfully uninstalled pip 22:45:48 INFO - Running setup.py install for pip 22:45:48 INFO - warning: no files found matching 'pip\cacert.pem' 22:45:48 INFO - warning: no files found matching '*.html' under directory 'docs' 22:45:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 22:45:48 INFO - no previously-included directories found matching 'docs\_build\_sources' 22:45:48 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:48 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:48 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:48 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:48 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:45:48 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 22:45:48 INFO - Successfully installed pip 22:45:48 INFO - Cleaning up... 22:45:48 INFO - Return code: 0 22:45:48 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 22:45:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:48 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 0x017CB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01871EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0177B608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-181', 'USERDOMAIN': 'T-W732-IX-181', '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-181', '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 22:45:48 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 22:45:48 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 22:45:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:45:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:45:48 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:45:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:48 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:45:48 INFO - 'DCLOCATION': 'SCL3', 22:45:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:48 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:48 INFO - 'HOMEDRIVE': 'C:', 22:45:48 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:45:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:48 INFO - 'KTS_VERSION': '1.19c', 22:45:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:45:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:45:48 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:48 INFO - 'MOZ_NO_REMOTE': '1', 22:45:48 INFO - 'NO_EM_RESTART': '1', 22:45:48 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:48 INFO - 'OS': 'Windows_NT', 22:45:48 INFO - 'OURDRIVE': 'C:', 22:45:48 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;', 22:45:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:48 INFO - 'PROCESSOR_LEVEL': '6', 22:45:48 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:48 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:45:48 INFO - 'PROMPT': '$P$G', 22:45:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:48 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:48 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:48 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:48 INFO - 'SYSTEMDRIVE': 'C:', 22:45:48 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:48 INFO - 'TEST1': 'testie', 22:45:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:48 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:45:48 INFO - 'USERNAME': 'cltbld', 22:45:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:45:48 INFO - 'WINDIR': 'C:\\windows', 22:45:48 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:45:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:50 INFO - Downloading/unpacking psutil>=0.7.1 22:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:51 INFO - Installing collected packages: psutil 22:45:51 INFO - Successfully installed psutil 22:45:51 INFO - Cleaning up... 22:45:51 INFO - Return code: 0 22:45:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 22:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:51 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 0x017CB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01871EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0177B608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-181', 'USERDOMAIN': 'T-W732-IX-181', '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-181', '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 22:45:51 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 22:45:51 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 22:45:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:45:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:45:51 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:45:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:45:51 INFO - 'DCLOCATION': 'SCL3', 22:45:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:51 INFO - 'HOMEDRIVE': 'C:', 22:45:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:45:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:51 INFO - 'KTS_VERSION': '1.19c', 22:45:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:45:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:45:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:51 INFO - 'MOZ_NO_REMOTE': '1', 22:45:51 INFO - 'NO_EM_RESTART': '1', 22:45:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:51 INFO - 'OS': 'Windows_NT', 22:45:51 INFO - 'OURDRIVE': 'C:', 22:45:51 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;', 22:45:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:51 INFO - 'PROCESSOR_LEVEL': '6', 22:45:51 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:45:51 INFO - 'PROMPT': '$P$G', 22:45:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:51 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:51 INFO - 'SYSTEMDRIVE': 'C:', 22:45:51 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:51 INFO - 'TEST1': 'testie', 22:45:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:51 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:45:51 INFO - 'USERNAME': 'cltbld', 22:45:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:45:51 INFO - 'WINDIR': 'C:\\windows', 22:45:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:45:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:45:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:45:52 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 22:45:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 22:45:53 INFO - Installing collected packages: mozsystemmonitor 22:45:53 INFO - Running setup.py install for mozsystemmonitor 22:45:53 INFO - Successfully installed mozsystemmonitor 22:45:53 INFO - Cleaning up... 22:45:53 INFO - Return code: 0 22:45:53 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 22:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:53 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 0x017CB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01871EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0177B608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-181', 'USERDOMAIN': 'T-W732-IX-181', '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-181', '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 22:45:53 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 22:45:53 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 22:45:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:45:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:45:53 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:45:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:45:53 INFO - 'DCLOCATION': 'SCL3', 22:45:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:53 INFO - 'HOMEDRIVE': 'C:', 22:45:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:45:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:53 INFO - 'KTS_VERSION': '1.19c', 22:45:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:45:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:45:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:53 INFO - 'MOZ_NO_REMOTE': '1', 22:45:53 INFO - 'NO_EM_RESTART': '1', 22:45:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:53 INFO - 'OS': 'Windows_NT', 22:45:53 INFO - 'OURDRIVE': 'C:', 22:45:53 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;', 22:45:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:53 INFO - 'PROCESSOR_LEVEL': '6', 22:45:53 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:45:53 INFO - 'PROMPT': '$P$G', 22:45:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:53 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:53 INFO - 'SYSTEMDRIVE': 'C:', 22:45:53 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:53 INFO - 'TEST1': 'testie', 22:45:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:53 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:45:53 INFO - 'USERNAME': 'cltbld', 22:45:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:45:53 INFO - 'WINDIR': 'C:\\windows', 22:45:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:45:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:53 INFO - Downloading/unpacking blobuploader==1.2.4 22:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:54 INFO - Downloading blobuploader-1.2.4.tar.gz 22:45:54 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 22:45:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:55 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 22:45:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:55 INFO - Downloading docopt-0.6.1.tar.gz 22:45:55 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 22:45:55 INFO - Installing collected packages: blobuploader, requests, docopt 22:45:55 INFO - Running setup.py install for blobuploader 22:45:55 INFO - Running setup.py install for requests 22:45:56 INFO - Running setup.py install for docopt 22:45:56 INFO - Successfully installed blobuploader requests docopt 22:45:56 INFO - Cleaning up... 22:45:57 INFO - Return code: 0 22:45:57 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:57 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 0x017CB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01871EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0177B608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-181', 'USERDOMAIN': 'T-W732-IX-181', '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-181', '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 22:45:57 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 22:45:57 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 22:45:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:45:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:45:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:45:57 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:45:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:45:57 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:45:57 INFO - 'DCLOCATION': 'SCL3', 22:45:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:45:57 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:45:57 INFO - 'HOMEDRIVE': 'C:', 22:45:57 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:45:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:45:57 INFO - 'KTS_VERSION': '1.19c', 22:45:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:45:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:45:57 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:45:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:57 INFO - 'MOZ_NO_REMOTE': '1', 22:45:57 INFO - 'NO_EM_RESTART': '1', 22:45:57 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:45:57 INFO - 'OS': 'Windows_NT', 22:45:57 INFO - 'OURDRIVE': 'C:', 22:45:57 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;', 22:45:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:45:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:45:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:45:57 INFO - 'PROCESSOR_LEVEL': '6', 22:45:57 INFO - 'PROCESSOR_REVISION': '1e05', 22:45:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:45:57 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:45:57 INFO - 'PROMPT': '$P$G', 22:45:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:45:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:45:57 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:45:57 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:45:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:45:57 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:45:57 INFO - 'SYSTEMDRIVE': 'C:', 22:45:57 INFO - 'SYSTEMROOT': 'C:\\windows', 22:45:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:57 INFO - 'TEST1': 'testie', 22:45:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:45:57 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:45:57 INFO - 'USERNAME': 'cltbld', 22:45:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:45:57 INFO - 'WINDIR': 'C:\\windows', 22:45:57 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:45:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 22:45:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mejfki-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 22:45:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 22:45:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k4u6of-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 22:45:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 22:45:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ualmay-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 22:45:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 22:45:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nhdwdg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 22:45:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 22:45:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6gugcs-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 22:45:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 22:45:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x2zwhl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 22:45:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 22:45:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-up10fw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 22:45:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 22:45:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8ie0na-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 22:45:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 22:45:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-binuz0-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 22:45:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 22:45:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vjwgui-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 22:45:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 22:45:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h2je27-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 22:45:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 22:45:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5lpu6s-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 22:46:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 22:46:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g7gnql-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 22:46:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 22:46:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jk1d_2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 22:46:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 22:46:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l5qdgq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:46:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 22:46:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kq3iwk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 22:46:00 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 22:46:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7d_x_i-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 22:46:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:46:00 INFO - Running setup.py install for manifestparser 22:46:00 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:00 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:01 INFO - Running setup.py install for mozcrash 22:46:01 INFO - Running setup.py install for mozdebug 22:46:02 INFO - Running setup.py install for mozdevice 22:46:02 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:02 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:02 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:02 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:02 INFO - Running setup.py install for mozfile 22:46:03 INFO - Running setup.py install for mozhttpd 22:46:03 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:03 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:03 INFO - Running setup.py install for mozinfo 22:46:03 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:03 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:03 INFO - Running setup.py install for mozInstall 22:46:04 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:04 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:04 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:04 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:04 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:04 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:04 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:04 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:04 INFO - Running setup.py install for mozleak 22:46:04 INFO - Running setup.py install for mozlog 22:46:04 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:04 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:05 INFO - Running setup.py install for moznetwork 22:46:05 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:05 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:05 INFO - Running setup.py install for mozprocess 22:46:06 INFO - Running setup.py install for mozprofile 22:46:06 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:06 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:06 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:06 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:06 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:06 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:07 INFO - Running setup.py install for mozrunner 22:46:07 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:07 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:07 INFO - Running setup.py install for mozscreenshot 22:46:08 INFO - Running setup.py install for moztest 22:46:08 INFO - Running setup.py install for mozversion 22:46:08 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:08 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:46:08 INFO - Cleaning up... 22:46:08 INFO - Return code: 0 22:46:08 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:08 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 0x017CB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01871EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0177B608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-181', 'USERDOMAIN': 'T-W732-IX-181', '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-181', '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 22:46:08 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 22:46:08 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 22:46:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:46:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:46:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:46:08 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:46:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:46:08 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:46:08 INFO - 'DCLOCATION': 'SCL3', 22:46:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:46:08 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:46:08 INFO - 'HOMEDRIVE': 'C:', 22:46:08 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:46:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:46:08 INFO - 'KTS_VERSION': '1.19c', 22:46:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:46:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:46:08 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:46:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:08 INFO - 'MOZ_NO_REMOTE': '1', 22:46:08 INFO - 'NO_EM_RESTART': '1', 22:46:08 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:46:08 INFO - 'OS': 'Windows_NT', 22:46:08 INFO - 'OURDRIVE': 'C:', 22:46:08 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;', 22:46:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:46:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:46:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:46:08 INFO - 'PROCESSOR_LEVEL': '6', 22:46:08 INFO - 'PROCESSOR_REVISION': '1e05', 22:46:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:46:08 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:46:08 INFO - 'PROMPT': '$P$G', 22:46:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:46:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:46:08 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:46:08 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:46:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:46:08 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:46:08 INFO - 'SYSTEMDRIVE': 'C:', 22:46:08 INFO - 'SYSTEMROOT': 'C:\\windows', 22:46:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:08 INFO - 'TEST1': 'testie', 22:46:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:08 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:46:08 INFO - 'USERNAME': 'cltbld', 22:46:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:46:08 INFO - 'WINDIR': 'C:\\windows', 22:46:08 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:46:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 22:46:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qxzjxs-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 22:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1)) 22:46:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 22:46:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vrss4g-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 22:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 22:46:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 22:46:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ty1oay-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 22:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 22:46:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 22:46:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-crmxa5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 22:46:09 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)) 22:46:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 22:46:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xpq5bp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 22:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5)) 22:46:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 22:46:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xuyfnz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 22:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6)) 22:46:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 22:46:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-agxwc_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 22:46:10 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)) 22:46:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 22:46:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sps1nw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 22:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8)) 22:46:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 22:46:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3twchh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 22:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9)) 22:46:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 22:46:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zb_d4i-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 22:46:10 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)) 22:46:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 22:46:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vxc3l4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 22:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11)) 22:46:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 22:46:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w0eosr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 22:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12)) 22:46:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 22:46:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u9jbw6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 22:46:11 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)) 22:46:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 22:46:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tugpcx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 22:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 22:46:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 22:46:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ukixb3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15)) 22:46:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 22:46:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bops_v-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 22:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16)) 22:46:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 22:46:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pnd4xy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 22:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17)) 22:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 22:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 22:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 22:46:11 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)) 22:46:11 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)) 22:46:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 22:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:12 INFO - Downloading blessings-1.6.tar.gz 22:46:12 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 22:46:13 INFO - Installing collected packages: blessings 22:46:13 INFO - Running setup.py install for blessings 22:46:13 INFO - Successfully installed blessings 22:46:13 INFO - Cleaning up... 22:46:16 INFO - Return code: 0 22:46:16 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 22:46:16 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 22:46:16 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 22:46:16 INFO - Reading from file tmpfile_stdout 22:46:16 INFO - Using _rmtree_windows ... 22:46:16 INFO - Using _rmtree_windows ... 22:46:16 INFO - Current package versions: 22:46:16 INFO - blessings == 1.6 22:46:16 INFO - blobuploader == 1.2.4 22:46:16 INFO - docopt == 0.6.1 22:46:16 INFO - manifestparser == 1.1 22:46:16 INFO - mozInstall == 1.12 22:46:16 INFO - mozcrash == 0.16 22:46:16 INFO - mozdebug == 0.1 22:46:16 INFO - mozdevice == 0.48 22:46:16 INFO - mozfile == 1.2 22:46:16 INFO - mozhttpd == 0.7 22:46:16 INFO - mozinfo == 0.9 22:46:16 INFO - mozleak == 0.1 22:46:16 INFO - mozlog == 3.1 22:46:16 INFO - moznetwork == 0.27 22:46:16 INFO - mozprocess == 0.22 22:46:16 INFO - mozprofile == 0.28 22:46:16 INFO - mozrunner == 6.11 22:46:16 INFO - mozscreenshot == 0.1 22:46:16 INFO - mozsystemmonitor == 0.0 22:46:16 INFO - moztest == 0.7 22:46:16 INFO - mozversion == 1.4 22:46:16 INFO - psutil == 3.1.1 22:46:16 INFO - requests == 1.2.3 22:46:16 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 22:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:16 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 0x017CB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01871EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0177B608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-181', 'USERDOMAIN': 'T-W732-IX-181', '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-181', '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 22:46:16 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 22:46:16 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 22:46:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:46:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:46:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:46:16 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:46:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:46:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:46:16 INFO - 'DCLOCATION': 'SCL3', 22:46:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:46:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:46:16 INFO - 'HOMEDRIVE': 'C:', 22:46:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:46:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:46:16 INFO - 'KTS_VERSION': '1.19c', 22:46:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:46:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:46:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:46:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:16 INFO - 'MOZ_NO_REMOTE': '1', 22:46:16 INFO - 'NO_EM_RESTART': '1', 22:46:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:46:16 INFO - 'OS': 'Windows_NT', 22:46:16 INFO - 'OURDRIVE': 'C:', 22:46:16 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;', 22:46:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:46:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:46:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:46:16 INFO - 'PROCESSOR_LEVEL': '6', 22:46:16 INFO - 'PROCESSOR_REVISION': '1e05', 22:46:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:46:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:46:16 INFO - 'PROMPT': '$P$G', 22:46:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:46:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:46:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:46:16 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:46:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:46:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:46:16 INFO - 'SYSTEMDRIVE': 'C:', 22:46:16 INFO - 'SYSTEMROOT': 'C:\\windows', 22:46:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:16 INFO - 'TEST1': 'testie', 22:46:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:16 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:46:16 INFO - 'USERNAME': 'cltbld', 22:46:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:46:16 INFO - 'WINDIR': 'C:\\windows', 22:46:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:46:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:46:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 INFO - Cleaning up... 22:46:16 INFO - Return code: 0 22:46:16 INFO - Installing jsonschema into virtualenv C:\slave\test-pgo\build/venv 22:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:16 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 0x017CB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01871EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0177B608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-181', 'USERDOMAIN': 'T-W732-IX-181', '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-181', '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 22:46:16 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 22:46:16 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 22:46:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:46:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:46:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:46:16 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:46:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:46:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:46:16 INFO - 'DCLOCATION': 'SCL3', 22:46:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:46:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:46:16 INFO - 'HOMEDRIVE': 'C:', 22:46:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:46:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:46:16 INFO - 'KTS_VERSION': '1.19c', 22:46:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:46:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:46:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:46:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:16 INFO - 'MOZ_NO_REMOTE': '1', 22:46:16 INFO - 'NO_EM_RESTART': '1', 22:46:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:46:16 INFO - 'OS': 'Windows_NT', 22:46:16 INFO - 'OURDRIVE': 'C:', 22:46:16 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;', 22:46:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:46:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:46:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:46:16 INFO - 'PROCESSOR_LEVEL': '6', 22:46:16 INFO - 'PROCESSOR_REVISION': '1e05', 22:46:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:46:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:46:16 INFO - 'PROMPT': '$P$G', 22:46:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:46:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:46:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:46:16 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:46:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:46:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:46:16 INFO - 'SYSTEMDRIVE': 'C:', 22:46:16 INFO - 'SYSTEMROOT': 'C:\\windows', 22:46:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:16 INFO - 'TEST1': 'testie', 22:46:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:16 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:46:16 INFO - 'USERNAME': 'cltbld', 22:46:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:46:16 INFO - 'WINDIR': 'C:\\windows', 22:46:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:46:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:46:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:17 INFO - Downloading/unpacking jsonschema 22:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:46:18 INFO - Downloading/unpacking functools32 (from jsonschema) 22:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:18 INFO - Downloading functools32-3.2.3-2.tar.gz 22:46:18 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\functools32\setup.py) egg_info for package functools32 22:46:18 INFO - warning: no files found matching '*.txt' 22:46:18 INFO - no previously-included directories found matching 'build' 22:46:18 INFO - no previously-included directories found matching 'dist' 22:46:18 INFO - no previously-included directories found matching '.git*' 22:46:18 INFO - Installing collected packages: jsonschema, functools32 22:46:18 INFO - Running setup.py install for functools32 22:46:18 INFO - warning: no files found matching '*.txt' 22:46:18 INFO - no previously-included directories found matching 'build' 22:46:18 INFO - no previously-included directories found matching 'dist' 22:46:18 INFO - no previously-included directories found matching '.git*' 22:46:18 INFO - Successfully installed jsonschema functools32 22:46:18 INFO - Cleaning up... 22:46:19 INFO - Return code: 0 22:46:19 INFO - Installing flake8 into virtualenv C:\slave\test-pgo\build/venv 22:46: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')]} 22:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46: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')]} 22:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01871EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0177B608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-181', 'USERDOMAIN': 'T-W732-IX-181', '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-181', '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 22:46: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', 'flake8'] in C:\slave\test-pgo\build 22:46: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 flake8 22:46:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:46:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:46:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:46:19 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:46:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:46:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:46:19 INFO - 'DCLOCATION': 'SCL3', 22:46:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:46:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:46:19 INFO - 'HOMEDRIVE': 'C:', 22:46:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:46:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:46:19 INFO - 'KTS_VERSION': '1.19c', 22:46:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:46:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:46:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:46:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:19 INFO - 'MOZ_NO_REMOTE': '1', 22:46:19 INFO - 'NO_EM_RESTART': '1', 22:46:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:46:19 INFO - 'OS': 'Windows_NT', 22:46:19 INFO - 'OURDRIVE': 'C:', 22:46: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;', 22:46:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:46:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:46:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:46:19 INFO - 'PROCESSOR_LEVEL': '6', 22:46:19 INFO - 'PROCESSOR_REVISION': '1e05', 22:46:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:46:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:46:19 INFO - 'PROMPT': '$P$G', 22:46:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:46:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:46:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:46:19 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:46:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:46:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:46:19 INFO - 'SYSTEMDRIVE': 'C:', 22:46:19 INFO - 'SYSTEMROOT': 'C:\\windows', 22:46:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:19 INFO - 'TEST1': 'testie', 22:46:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:19 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:46:19 INFO - 'USERNAME': 'cltbld', 22:46:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:46:19 INFO - 'WINDIR': 'C:\\windows', 22:46:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:46:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:19 INFO - Downloading/unpacking flake8 22:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:46:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:46:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:20 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\pep8\setup.py) egg_info for package pep8 22:46:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:46:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:46:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:46:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:46:21 INFO - no previously-included directories found matching 'docs\_build' 22:46:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:46:21 INFO - Running setup.py install for pep8 22:46:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:46:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:46:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:46:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:46:21 INFO - no previously-included directories found matching 'docs\_build' 22:46:21 INFO - Installing pep8-script.py script to C:\slave\test-pgo\build\venv\Scripts 22:46:21 INFO - Installing pep8.exe script to C:\slave\test-pgo\build\venv\Scripts 22:46:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:46:21 INFO - Cleaning up... 22:46:22 INFO - Return code: 0 22:46:22 INFO - Running post-action listener: _resource_record_post_action 22:46:22 INFO - Running post-action listener: _start_resource_monitoring 22:46:22 INFO - Starting resource monitoring. 22:46:22 INFO - ##### 22:46:22 INFO - ##### Running install step. 22:46:22 INFO - ##### 22:46:22 INFO - Running pre-action listener: _resource_record_pre_action 22:46:22 INFO - Running main action method: install 22:46:22 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 22:46:22 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 22:46:22 INFO - Reading from file tmpfile_stdout 22:46:22 INFO - Using _rmtree_windows ... 22:46:22 INFO - Using _rmtree_windows ... 22:46:22 INFO - Detecting whether we're running mozinstall >=1.0... 22:46:22 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 22:46:22 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 22:46:22 INFO - Reading from file tmpfile_stdout 22:46:22 INFO - Output received: 22:46:22 INFO - Usage: mozinstall-script.py [options] installer 22:46:22 INFO - Options: 22:46:22 INFO - -h, --help show this help message and exit 22:46:22 INFO - -d DEST, --destination=DEST 22:46:22 INFO - Directory to install application into. [default: 22:46:22 INFO - "C:\slave\test-pgo"] 22:46:22 INFO - --app=APP Application being installed. [default: firefox] 22:46:22 INFO - Using _rmtree_windows ... 22:46:22 INFO - Using _rmtree_windows ... 22:46:22 INFO - mkdir: C:\slave\test-pgo\build\application 22:46:22 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'] 22:46:22 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 22:46:23 INFO - Reading from file tmpfile_stdout 22:46:23 INFO - Output received: 22:46:23 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 22:46:23 INFO - Using _rmtree_windows ... 22:46:23 INFO - Using _rmtree_windows ... 22:46:23 INFO - Running post-action listener: _resource_record_post_action 22:46:23 INFO - ##### 22:46:23 INFO - ##### Running run-tests step. 22:46:23 INFO - ##### 22:46:23 INFO - Running pre-action listener: _resource_record_pre_action 22:46:23 INFO - Running main action method: run_tests 22:46:23 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 22:46:23 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 22:46:23 INFO - Python 2.7.3 22:46:23 INFO - Return code: 0 22:46:23 INFO - grabbing minidump binary from tooltool 22:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:23 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-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 0x01238BF0>, '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 0x0125A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EC25C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 22:46:23 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build 22:46:23 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o 22:46:24 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 22:46:24 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpw530g_ 22:46:24 INFO - INFO - File integrity verified, renaming tmpw530g_ to win32-minidump_stackwalk.exe 22:46:24 INFO - Return code: 0 22:46:24 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 22:46:24 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 22:46:24 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 22:46:24 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 22:46:24 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 22:46:24 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\flake8', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos'] 22:46:24 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\flake8 C:\slave\test-pgo\build\tests\talos\talos 22:46:25 INFO - Return code: 0 22:46:25 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/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-181', '--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 22:46:25 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/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-181 --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 22:46:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 22:46:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 22:46:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 22:46:25 INFO - 'COMPUTERNAME': 'T-W732-IX-181', 22:46:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 22:46:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 22:46:25 INFO - 'DCLOCATION': 'SCL3', 22:46:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 22:46:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 22:46:25 INFO - 'HOMEDRIVE': 'C:', 22:46:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 22:46:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 22:46:25 INFO - 'KTS_VERSION': '1.19c', 22:46:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 22:46:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-181', 22:46:25 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 22:46:25 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 22:46:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 22:46:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 22:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:25 INFO - 'MOZ_NO_REMOTE': '1', 22:46:25 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 22:46:25 INFO - 'NO_EM_RESTART': '1', 22:46:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 22:46:25 INFO - 'OS': 'Windows_NT', 22:46:25 INFO - 'OURDRIVE': 'C:', 22:46:25 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;', 22:46:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 22:46:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 22:46:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 22:46:25 INFO - 'PROCESSOR_LEVEL': '6', 22:46:25 INFO - 'PROCESSOR_REVISION': '1e05', 22:46:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 22:46:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 22:46:25 INFO - 'PROMPT': '$P$G', 22:46:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 22:46:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 22:46:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 22:46:25 INFO - 'PWD': 'C:\\slave\\test-pgo', 22:46:25 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 22:46:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 22:46:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 22:46:25 INFO - 'SYSTEMDRIVE': 'C:', 22:46:25 INFO - 'SYSTEMROOT': 'C:\\windows', 22:46:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:25 INFO - 'TEST1': 'testie', 22:46:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 22:46:25 INFO - 'USERDOMAIN': 'T-W732-IX-181', 22:46:25 INFO - 'USERNAME': 'cltbld', 22:46:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 22:46:25 INFO - 'WINDIR': 'C:\\windows', 22:46:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 22:46:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 22:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 22:46:25 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/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-181', '--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 22:46:26 INFO - mozversion application_buildid: 20160310191740 22:46:26 INFO - mozversion application_changeset: 577c2d4465976cda722c0da98a0a6635b37bb689 22:46:26 INFO - mozversion application_display_name: Firefox 22:46:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:46:26 INFO - mozversion application_name: Firefox 22:46:26 INFO - mozversion application_remotingname: firefox 22:46:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:46:26 INFO - mozversion application_vendor: Mozilla 22:46:26 INFO - mozversion application_version: 46.0 22:46:26 INFO - mozversion platform_buildid: 20160310191740 22:46:26 INFO - mozversion platform_changeset: 577c2d4465976cda722c0da98a0a6635b37bb689 22:46:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:46:26 INFO - mozversion platform_version: 46.0 22:46:26 INFO - using testdate: 1457678786 22:46:26 INFO - actual date: 1457678786 22:46:26 INFO - starting webserver on 'localhost:49586' 22:46:26 INFO - SUITE-START | Running 5 tests 22:46:26 INFO - TEST-START | tsvgx 22:46:26 INFO - operating with platform_type : w7_ 22:46:26 INFO - Initialising browser for tsvgx test... 22:46:27 INFO - TEST-INFO | started process 420 (C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpaafa_i\profile http://localhost:49586/getInfo.html) 22:46:35 INFO - PROCESS | 420 | __metrics Screen width/height:1600/1200 22:46:35 INFO - PROCESS | 420 | colorDepth:24 22:46:35 INFO - PROCESS | 420 | Browser inner width/height: 1010/674 22:46:35 INFO - PROCESS | 420 | __metrics 22:46:35 INFO - PROCESS | 420 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:46:35 INFO - PROCESS | 420 | console.error: 22:46:35 INFO - PROCESS | 420 | Message: Error: Connection closed before committing the transaction. 22:46:35 INFO - PROCESS | 420 | Stack: 22:46:35 INFO - PROCESS | 420 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:933:23 22:46:35 INFO - PROCESS | 420 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7 22:46:35 INFO - PROCESS | 420 | this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:746:11 22:46:35 INFO - PROCESS | 420 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 22:46:35 INFO - PROCESS | 420 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 22:46:35 INFO - PROCESS | 420 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5 22:46:35 INFO - PROCESS | 420 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 22:46:35 INFO - PROCESS | 420 | Barrier/this.client.addBlocker/promise resource://gre/modules/Promise-backend.js:933:23 22:54:22 INFO - PROCESS | 3280 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7 22:54:22 INFO - PROCESS | 3280 | this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:746:11 22:54:22 INFO - PROCESS | 3280 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 22:54:22 INFO - PROCESS | 3280 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 22:54:22 INFO - PROCESS | 3280 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5 22:54:22 INFO - PROCESS | 3280 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 22:54:22 INFO - PROCESS | 3280 | Barrier/this.client.addBlocker/promisetsvgx: 384.28 23:05:14 INFO - RETURN: tsvgr_opacity: 498.74 23:05:14 INFO - RETURN: tart: 3.71 23:05:14 INFO - RETURN: tscrollx: 2.77 23:05:14 INFO - RETURN: cart: 23.49 23:05:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [246.0, 214.0, 213.0, 211.0, 210.0, 210.0, 208.0, 215.0, 215.0, 210.0, 209.0, 210.0, 207.0, 210.0, 207.0, 209.0, 208.0, 209.0, 211.0, 214.0, 208.0, 215.0, 207.0, 210.0, 210.0], "unit": "ms", "name": "gearflowers.svg", "value": 210.0}, {"lowerIsBetter": true, "replicates": [66.0, 40.0, 41.0, 40.0, 41.0, 40.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 42.0, 40.0, 42.0, 41.0, 40.0, 41.0, 40.0, 41.0, 40.0, 41.0, 41.0, 40.0], "unit": "ms", "name": "composite-scale.svg", "value": 41.0}, {"lowerIsBetter": true, "replicates": [130.0, 125.0, 123.0, 123.0, 123.0, 124.0, 127.0, 127.0, 126.0, 124.0, 124.0, 125.0, 123.0, 125.0, 124.0, 123.0, 125.0, 123.0, 123.0, 124.0, 123.0, 125.0, 123.0, 123.0, 123.0], "unit": "ms", "name": "composite-scale-opacity.svg", "value": 124.0}, {"lowerIsBetter": true, "replicates": [173.0, 170.0, 170.0, 173.0, 172.0, 168.0, 170.0, 168.0, 168.0, 170.0, 170.0, 167.0, 169.0, 172.0, 174.0, 173.0, 170.0, 172.0, 171.0, 171.0, 174.0, 178.0, 171.0, 177.0, 174.0], "unit": "ms", "name": "composite-scale-rotate.svg", "value": 171.0}, {"lowerIsBetter": true, "replicates": [253.0, 244.0, 251.0, 244.0, 243.0, 242.0, 241.0, 247.0, 247.0, 240.0, 257.0, 241.0, 245.0, 247.0, 248.0, 246.0, 251.0, 244.0, 243.0, 243.0, 243.0, 241.0, 248.0, 240.0, 242.0], "unit": "ms", "name": "composite-scale-rotate-opacity.svg", "value": 243.5}, {"lowerIsBetter": true, "replicates": [467.0, 432.0, 443.0, 438.0, 434.0, 435.0, 437.0, 440.0, 431.0, 432.0, 438.0, 436.0, 437.0, 439.0, 440.0, 438.0, 433.0, 435.0, 432.0, 434.0, 433.0, 439.0, 435.0, 441.0, 432.0], "unit": "ms", "name": "hixie-001.xml", "value": 435.5}, {"lowerIsBetter": true, "replicates": [441.0, 437.0, 432.0, 437.0, 439.0, 440.0, 436.0, 428.0, 437.0, 436.0, 434.0, 434.0, 440.0, 436.0, 436.0, 433.0, 441.0, 446.0, 437.0, 427.0, 439.0, 433.0, 449.0, 436.0, 432.0], "unit": "ms", "name": "hixie-002.xml", "value": 436.0}, {"lowerIsBetter": true, "replicates": [257.0, 217.0, 2951.0, 220.0, 210.0, 210.0, 211.0, 214.0, 214.0, 216.0, 215.0, 214.0, 217.0, 217.0, 218.0, 214.0, 209.0, 216.0, 214.0, 214.0, 208.0, 214.0, 211.0, 213.0, 213.0], "unit": "ms", "name": "hixie-003.xml", "value": 214.0}, {"lowerIsBetter": true, "replicates": [1115.0, 1087.0, 1105.0, 1082.0, 1099.0, 1115.0, 1096.0, 1104.0, 1099.0, 1083.0, 1112.0, 1081.0, 1086.0, 1108.0, 1112.0, 1111.0, 1081.0, 1088.0, 1099.0, 1083.0, 1088.0, 1083.0, 1081.0, 1099.0, 1130.0], "unit": "ms", "name": "hixie-004.xml", "value": 1097.5}, {"lowerIsBetter": true, "replicates": [2462.0, 2463.0, 2515.0, 2468.0, 2465.0, 2461.0, 2462.0, 2478.0, 2468.0, 2460.0, 2464.0, 2462.0, 2462.0, 2460.0, 2462.0, 2458.0, 2471.0, 2462.0, 2472.0, 2474.0, 2462.0, 2467.0, 2452.0, 2468.0, 2454.0], "unit": "ms", "name": "hixie-005.xml", "value": 2462.0}, {"lowerIsBetter": true, "replicates": [3754.0, 3772.0, 3764.0, 3754.0, 3758.0, 3736.0, 3751.0, 3778.0, 3749.0, 3762.0, 3768.0, 3864.0, 3774.0, 3764.0, 3755.0, 3818.0, 3772.0, 3792.0, 3756.0, 3806.0, 3774.0, 3764.0, 3766.0, 3747.0, 3763.0], "unit": "ms", "name": "hixie-006.xml", "value": 3765.0}, {"lowerIsBetter": true, "replicates": [793.0, 544.0, 544.0, 555.0, 550.0, 544.0, 548.0, 550.0, 561.0, 550.0, 546.0, 549.0, 542.0, 553.0, 545.0, 552.0, 543.0, 548.0, 546.0, 549.0, 546.0, 550.0, 563.0, 548.0, 551.0], "unit": "ms", "name": "hixie-007.xml", "value": 548.5}], "name": "tsvgx", "value": 384.27616436836087}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [748.0, 708.0, 67.0, 726.0, 717.0, 705.0, 723.0, 67.0, 702.0, 706.0, 65.0, 711.0, 66.0, 701.0, 65.0, 708.0, 66.0, 720.0, 65.0, 710.0, 65.0, 706.0, 64.0, 701.0, 703.0], "unit": "ms", "name": "big-optimizable-group-opacity-2500.svg", "value": 701.5}, {"lowerIsBetter": true, "replicates": [80.0, 352.0, 353.0, 361.0, 366.0, 362.0, 352.0, 354.0, 360.0, 364.0, 364.0, 354.0, 361.0, 354.0, 351.0, 356.0, 352.0, 355.0, 349.0, 354.0, 351.0, 392.0, 357.0, 395.0, 352.0], "unit": "ms", "name": "small-group-opacity-2500.svg", "value": 354.5}], "name": "tsvgr_opacity", "value": 498.7386817127528}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1.5044235053000512, 1.559119234213958, 1.535120768547058, 1.5792514036779535, 1.5498303620020548, 1.5805085979095876, 1.6270094236857455, 1.608305361535814, 1.5794618097070146, 1.521661948216589, 1.5395136896769206, 1.6338943091916367, 1.6336509291554842, 1.5266084090659493, 1.5220206740655398, 1.6762449240338975, 1.5564709091186524, 1.567619022485372, 1.5357300567626953, 1.5663217386683903, 1.6981624522629906, 1.560701921179488, 1.5350081427892048, 1.5364212385813396, 1.6379614077823264], "unit": "ms", "name": "simple-open-DPI1.half.TART", "value": 1.563511829923939}, {"lowerIsBetter": true, "replicates": [1.680403826252514, 1.7335280596393428, 1.6982086730003356, 1.7747078133629752, 1.7050599547840606, 1.7513016232128802, 1.7703522743878666, 1.7603109417290523, 1.7889634815739914, 1.8367891870439053, 1.7399008029127774, 1.7732846203383865, 1.7620154519875844, 1.695464387734731, 1.6832494514667435, 1.8037699520165194, 1.7318341675258817, 1.763972758418984, 1.716453502307067, 1.7255909775390106, 1.796542116936217, 1.761372791396247, 1.7289006564081932, 1.7064387278268802, 1.805118016317381], "unit": "ms", "name": "simple-open-DPI1.all.TART", "value": 1.7558062824709664}, {"lowerIsBetter": true, "replicates": [24.145000000000437, 23.345000000001164, 25.025000000001455, 24.239999999997963, 24.409999999996217, 24.31500000000233, 23.625, 25.554999999993015, 24.705000000001746, 28.535000000003492, 24.339999999996508, 24.00999999998021, 24.04999999998836, 24.630000000004657, 24.60999999998603, 25.22500000000582, 24.779999999998836, 24.294999999983702, 24.329999999987194, 24.170000000012806, 23.744999999995343, 23.93499999999767, 24.84499999997206, 24.574999999953434, 24.85999999998603], "unit": "ms", "name": "simple-open-DPI1.error.TART", "value": 24.374999999996362}, {"lowerIsBetter": true, "replicates": [1.1714104257448754, 1.09581268401373, 1.087782418952798, 1.1825995250624053, 1.1301732653496313, 1.1306927864458047, 1.0741093302214588, 1.1416330355228763, 1.0987383609726316, 1.067776965322318, 1.215761246806697, 1.161416621208191, 1.1432792733211328, 1.185397200438441, 1.134769910690831, 1.0872354063222993, 1.143940807569145, 1.1364941258056491, 1.1514519381523132, 1.0800852786714785, 1.088032381152207, 1.1009838206427438, 1.1359143596069485, 1.08954663040503, 1.1656194642336681], "unit": "ms", "name": "simple-close-DPI1.half.TART", "value": 1.132731348568318}, {"lowerIsBetter": true, "replicates": [1.265088287803034, 1.1970304238972405, 1.181683811458569, 1.3264405719288017, 1.2615807087151045, 1.2174731197938398, 1.175828646922457, 1.301715244004067, 1.2047008669435098, 1.177517694206192, 1.3463488128962438, 1.2934103237187609, 1.3183988548613883, 1.3242263457049495, 1.317167988338986, 1.181685678009848, 1.2988189420801528, 1.3087114935280175, 1.3162530753561246, 1.1880156864480274, 1.1857232162095968, 1.184797810903494, 1.2961554727021685, 1.1996616383491479, 1.3172068663181797], "unit": "ms", "name": "simple-close-DPI1.all.TART", "value": 1.2774955162169328}, {"lowerIsBetter": true, "replicates": [15.274999999999636, 15.809999999997672, 16.025000000001455, 16.6449999999968, 17.510000000002037, 17.279999999998836, 16.029999999998836, 17.739999999990687, 17.32500000001164, 17.69999999999709, 16.345000000001164, 15.839999999996508, 17.035000000003492, 16.93499999999767, 15.940000000002328, 15.940000000002328, 16.864999999990687, 16.205000000016298, 17.375, 16.10999999998603, 16.885000000009313, 17.820000000006985, 16.43499999999767, 16.21500000002561, 17.600000000034925], "unit": "ms", "name": "simple-close-DPI1.error.TART", "value": 16.754999999993743}, {"lowerIsBetter": true, "replicates": [1.6301787487218078, 1.5466867192586262, 1.5549471781060502, 1.6114146593544219, 1.586179109468852, 1.5811503439733428, 1.5809031071728223, 1.8624140408731276, 1.603185428513421, 1.5713915180515599, 1.601404117213355, 1.5779416152875718, 1.692927681347903, 1.588267401473163, 1.631882388826827, 1.5608368338765324, 1.6266578889228929, 1.5864368546498966, 1.6574276515415736, 1.6050680726766586, 1.602657440635893, 1.6201840504794054, 1.6063853899637859, 1.568361068094099, 1.5700631045006417], "unit": "ms", "name": "icon-open-DPI1.half.TART", "value": 1.5948357593432592}, {"lowerIsBetter": true, "replicates": [1.8473120219465615, 1.7586213654485243, 1.7965433303738985, 1.8464772519857988, 1.8181954911776952, 1.8188263245991299, 1.8328451527108391, 1.9853393528610468, 1.799148382870018, 1.7894177839789591, 1.834668439926861, 1.8116241422950798, 1.8891200021461205, 1.8193940911974227, 1.8920828656238668, 1.7727299878994625, 1.813274862936565, 1.8335938050592546, 1.8728943984298145, 1.825551733800343, 1.8111217473234449, 1.8391423795534216, 1.845542309940725, 1.7865215984868332, 1.778867316412759], "unit": "ms", "name": "icon-open-DPI1.all.TART", "value": 1.8191102078982762}, {"lowerIsBetter": true, "replicates": [25.325000000000728, 25.465000000000146, 25.544999999998254, 25.029999999998836, 24.904999999998836, 25.04000000000815, 25.114999999990687, 24.55000000000291, 24.145000000004075, 24.455000000001746, 25.29000000000815, 25.764999999984866, 25.279999999998836, 24.925000000017462, 27.869999999995343, 25.579999999987194, 24.269999999989523, 25.045000000012806, 25.0, 25.75, 24.029999999998836, 24.125, 24.989999999990687, 24.150000000023283, 24.75], "unit": "ms", "name": "icon-open-DPI1.error.TART", "value": 25.014999999999418}, {"lowerIsBetter": true, "replicates": [1.1193908424053378, 1.1205614648976372, 1.1180039800486519, 1.0822277603862442, 1.1120501911410918, 1.1055698145003545, 1.0796618132947762, 1.0907457099770599, 1.0906261621781115, 1.1143836946441577, 1.1104440608849893, 1.0850618464766808, 1.0931293638247364, 1.1230593005430352, 1.1050358817690895, 1.1041179515066601, 1.1049663912682306, 1.134137444636401, 1.0954908507210868, 1.1103148328570218, 1.086369607808455, 1.1112378990420928, 1.0841405575520524, 1.0856595815352674, 1.1096465329711254], "unit": "ms", "name": "icon-close-DPI1.half.TART", "value": 1.10500113651866}, {"lowerIsBetter": true, "replicates": [1.2191031232918843, 1.221554112136364, 1.2478806657754644, 1.1979088955650143, 1.2316328718434626, 1.2345929129339344, 1.2108187032218027, 1.20742287864826, 1.2198673135573321, 1.1922134073769175, 1.2233070571720601, 1.2179507319606953, 1.24942056165666, 1.2365189498723155, 1.2454524748216427, 1.2201992993924156, 1.2189594357464444, 1.2415225207805634, 1.229164891242981, 1.221192722000293, 1.2348173113167287, 1.2644411667799338, 1.2079241607576756, 1.2131714578626192, 1.2204585098984218], "unit": "ms", "name": "icon-close-DPI1.all.TART", "value": 1.2213734170683286}, {"lowerIsBetter": true, "replicates": [19.790000000000873, 17.3650000000016, 17.2699999999968, 17.44000000000233, 18.00499999999738, 18.5, 17.560000000012224, 18.05000000000291, 19.125, 17.279999999998836, 17.64999999999418, 17.605000000010477, 17.789999999979045, 17.739999999990687, 18.970000000001164, 18.195000000006985, 17.704999999987194, 18.625, 18.654999999998836, 18.294999999983702, 19.714999999996508, 19.55999999999767, 17.919999999983702, 18.96500000002561, 19.294999999983702], "unit": "ms", "name": "icon-close-DPI1.error.TART", "value": 18.027500000000146}, {"lowerIsBetter": true, "replicates": [1.554916043539305, 1.5925281668362552, 1.647290175301688, 1.617574582497279, 1.573840785670925, 1.6181772500276566, 1.6550962482179914, 1.6206113150422001, 1.662847466128213, 1.662095935004098, 1.703798136290382, 1.7656624425541272, 1.7154704665436464, 1.697247629656511, 1.7059609364060795, 1.6558422957147871, 1.7145556509494781, 1.626776869867889, 1.6643560188157218, 1.638204791176487, 1.6884381788364355, 1.742008702078862, 1.5911289633136907, 1.618875899248653, 1.6574715307780674], "unit": "ms", "name": "icon-open-DPI2.half.TART", "value": 1.6566569132464273}, {"lowerIsBetter": true, "replicates": [1.797003697682094, 1.8431660674458785, 1.8548903682806197, 1.8446129292681597, 1.813876381157138, 1.8825959291528254, 1.859372189445217, 1.8689217400901459, 1.9198317949036907, 1.8497801587201548, 1.9278393360701473, 1.9618050602766184, 1.9126974397630834, 1.927344796332446, 1.936394877506025, 1.9133962706515664, 1.9300822272445217, 1.8648546159702495, 1.898079834767242, 1.880083005217945, 1.9119988859147954, 1.9606944230886607, 1.7912593408369681, 1.8400775288506377, 1.8712398173177944], "unit": "ms", "name": "icon-open-DPI2.all.TART", "value": 1.8813394671853851}, {"lowerIsBetter": true, "replicates": [27.38500000000022, 26.520000000000437, 24.55500000000029, 24.994999999995343, 26.224999999998545, 26.395000000004075, 25.229999999995925, 24.554999999993015, 25.635000000009313, 25.639999999999418, 24.894999999989523, 25.264999999984866, 26.670000000012806, 24.854999999981374, 26.070000000006985, 24.889999999984866, 25.664999999979045, 26.27499999999418, 24.76500000001397, 26.56500000000233, 24.929999999993015, 25.260000000009313, 24.81500000000233, 26.020000000018626, 25.505000000004657], "unit": "ms", "name": "icon-open-DPI2.error.TART", "value": 25.38499999999476}, {"lowerIsBetter": true, "replicates": [1.0144850899252975, 1.0026249092558155, 1.0204248533839673, 1.0421941919369742, 1.0616939734975133, 1.0120194298133516, 1.0060341181962387, 1.0041976182357124, 1.028198663677488, 1.0075500638588615, 1.0010822508646093, 1.0430901823817074, 1.0269833565813251, 1.0482685858553107, 1.0166341181386982, 1.0116590141204365, 1.0343391565339906, 1.0234730248957609, 1.042218413438883, 1.0157179178898794, 1.0284626542457513, 1.0126425869632185, 1.0198369664428508, 1.0462613355029713, 1.039264071095097], "unit": "ms", "name": "icon-close-DPI2.half.TART", "value": 1.0219489391398642}, {"lowerIsBetter": true, "replicates": [1.104684462059628, 1.0989441957559671, 1.1100272379138254, 1.1113572604276918, 1.1280558878375637, 1.0962943869775958, 1.1140630969173833, 1.1032252204310786, 1.120866301941545, 1.1181993772994439, 1.1113118748773227, 1.128261548915395, 1.1134325718337839, 1.1521281164856585, 1.1153062669109537, 1.1108756166507756, 1.1323610544204712, 1.1137112751265996, 1.1444968999818315, 1.1105767667832958, 1.1449237504670786, 1.1051521811010612, 1.131701501707236, 1.1527013996957054, 1.1251236421799442], "unit": "ms", "name": "icon-close-DPI2.all.TART", "value": 1.1146846819141685}, {"lowerIsBetter": true, "replicates": [16.575000000000728, 17.414999999997235, 17.69000000000233, 18.090000000003783, 18.215000000003783, 19.445000000006985, 17.494999999995343, 18.485000000000582, 19.020000000004075, 18.189999999987776, 17.904999999998836, 17.345000000001164, 18.544999999983702, 19.574999999982538, 17.70999999999185, 19.08499999999185, 18.195000000006985, 19.54999999998836, 19.505000000004657, 18.929999999993015, 19.945000000006985, 17.614999999990687, 18.115000000048894, 18.98499999998603, 18.744999999995343], "unit": "ms", "name": "icon-close-DPI2.error.TART", "value": 18.350000000002183}, {"lowerIsBetter": true, "replicates": [0.9835463507562621, 1.0049722863280255, 1.0018201900565107, 1.0166341134330683, 0.9903827803766626, 0.998437257676289, 1.0262629447785099, 1.001215812952622, 1.0050023389899212, 0.9974507884732609, 1.0040907662847767, 1.0114804940265523, 1.0080709664717964, 1.0031825309214384, 1.0068188009054764, 1.0268306790718011, 1.0174661022529268, 1.0181820450630863, 1.008464977533921, 1.0047185156656349, 0.9964444549946949, 1.018317977939032, 1.0154888640370285, 1.0018001561579497, 1.549113564491272], "unit": "ms", "name": "iconFade-close-DPI2.half.TART", "value": 1.005910569947699}, {"lowerIsBetter": true, "replicates": [1.0016896313634411, 1.01244183027226, 1.0248394888221173, 1.0314553714958967, 0.9952713370832622, 1.0210989010438585, 1.025328172986203, 1.011625421565512, 1.016110074051603, 1.0109275530213895, 1.031903929942477, 1.0228127451195066, 1.0179361492785823, 1.009131157553041, 1.017387818821653, 1.0356385530365837, 1.0317221265451042, 1.0174968156752107, 1.0198492331985842, 1.008379791722153, 1.0106036608633788, 1.0322133212619358, 1.0322474484973483, 1.0214188990362905, 1.2433252248534545], "unit": "ms", "name": "iconFade-close-DPI2.all.TART", "value": 1.0204740671212214}, {"lowerIsBetter": true, "replicates": [2.4900000000016007, 2.9350000000013097, 3.444999999999709, 3.055000000000291, 2.8699999999953434, 2.8150000000023283, 2.7549999999901047, 2.764999999999418, 2.7699999999895226, 2.5749999999970896, 3.360000000015134, 2.179999999993015, 3.1300000000046566, 3.084999999991851, 2.8849999999802094, 2.389999999984866, 2.639999999984866, 2.5249999999941792, 2.5249999999941792, 2.959999999991851, 2.4600000000209548, 2.3800000000046566, 2.3250000000116415, 2.900000000023283, 2.6349999999511056], "unit": "ms", "name": "iconFade-close-DPI2.error.TART", "value": 2.7674999999944703}, {"lowerIsBetter": true, "replicates": [1.573410087340587, 1.569046041449985, 1.6403897732076511, 1.6144706126716402, 1.5830755821646076, 1.640032820298638, 1.6340457268164192, 1.7064522294437183, 1.6153559419843886, 1.6133879671494167, 1.7006414707969217, 1.6195958968665864, 1.6236670571313778, 1.6229206944855166, 1.5978628214862611, 1.6246730089187622, 1.6070893787675433, 1.7033125004347633, 1.7412579006223536, 1.6195159008105595, 1.629464844582786, 1.6062627219491534, 1.6671262638909476, 1.6710582125014153, 1.6164012617535062], "unit": "ms", "name": "iconFade-open-DPI2.half.TART", "value": 1.6232938758084472}, {"lowerIsBetter": true, "replicates": [1.5017533279234363, 1.463398590402783, 1.5517349735895793, 1.5397929047906636, 1.5029697272085374, 1.5659454412908362, 1.5630409909574778, 1.5963549760922993, 1.5512255374590556, 1.560729298015569, 1.5311279343931299, 1.550286037127177, 1.5586136094729106, 1.5583396911621095, 1.5374007013283277, 1.5583064119021097, 1.5486604468027751, 1.5933908436396351, 1.6241575096037004, 1.5496102039019266, 1.544876393892907, 1.5524466443061828, 1.5898003383558623, 1.580685239385914, 1.5474372083777623], "unit": "ms", "name": "iconFade-open-DPI2.all.TART", "value": 1.552090808947881}, {"lowerIsBetter": true, "replicates": [2.850000000002183, 2.540000000000873, 2.724999999998545, 2.4250000000029104, 3.029999999998836, 3.389999999999418, 2.9250000000029104, 3.055000000007567, 2.779999999998836, 2.5899999999965075, 2.8099999999976717, 2.6149999999906868, 3.8150000000023283, 3.6849999999976717, 3.6000000000058208, 4.2700000000186265, 2.2699999999895226, 2.529999999998836, 2.3550000000104774, 2.4649999999965075, 3.194999999977881, 2.7999999999883585, 3.6849999999976717, 4.429999999993015, 3.6450000000186265], "unit": "ms", "name": "iconFade-open-DPI2.error.TART", "value": 2.867500000000291}, {"lowerIsBetter": true, "replicates": [1.94167504545118, 1.7381286318622418, 1.7849279917203462, 1.713477075099945, 1.7261044196228483, 1.7455201437979033, 1.7505935376340693, 1.7442634412736604, 1.7207112881674695, 1.7813656990344708, 1.7388106513379225, 1.7399741001983187, 1.7435102871994475, 1.7220810687364037, 2.985838611920675, 1.7165733148802573, 1.6955309387515574, 1.7302710565168467, 1.7136013034512014, 1.7276518558388325, 1.7181494111445412, 1.7115119204801672, 1.7407134344328696, 1.8066375683993101, 1.732145181342737], "unit": "ms", "name": "newtab-open-preload-no.half.TART", "value": 1.7351369066024893}, {"lowerIsBetter": true, "replicates": [3.2805660258639944, 2.4527104634505053, 2.5342367691736594, 2.464876451744483, 2.5429266762025287, 2.5494393924675367, 2.5036041834979383, 2.546652581432078, 2.5300730639228632, 2.5459187963221335, 2.5058634153847557, 2.5513479804048442, 2.5331450236194275, 2.524648179026211, 3.406964422861735, 2.4904103938815663, 2.479442436534625, 2.534927409769285, 2.4999767096297254, 2.57505667835474, 2.530201052918154, 2.4711422375761547, 2.4972216504291422, 2.584556712806225, 2.4952308437199266], "unit": "ms", "name": "newtab-open-preload-no.all.TART", "value": 2.5301370584205083}, {"lowerIsBetter": true, "replicates": [33.93499999999767, 25.56999999999971, 28.880000000004657, 26.834999999999127, 27.305000000007567, 28.33499999999185, 28.345000000001164, 27.529999999998836, 30.05000000000291, 27.479999999995925, 28.519999999989523, 28.14999999999418, 28.744999999995343, 28.494999999995343, 40.18499999999767, 26.970000000001164, 28.150000000023283, 26.535000000003492, 27.94000000000233, 27.85999999998603, 28.54000000000815, 27.350000000034925, 27.57999999995809, 28.794999999983702, 27.42499999998836], "unit": "ms", "name": "newtab-open-preload-no.error.TART", "value": 28.044999999998254}, {"lowerIsBetter": true, "replicates": [1.8448350013248505, 1.7709801031992987, 1.755632019404209, 1.8833169994815704, 1.7585643475705928, 1.7887206829511202, 1.7401460391371997, 1.7231814647788433, 1.7718248954186073, 1.80898954346776, 1.7933050008920524, 1.8460784071967715, 1.7527695027264683, 1.910986888604086, 1.8228313643485308, 1.7341511249542236, 1.7338072364010029, 1.7429078167135066, 1.7733845013838547, 1.738140091967227, 1.772173445041363, 1.7667620651649707, 1.7970031683261578, 1.8578122911914703, 1.7663198799797983], "unit": "ms", "name": "newtab-open-preload-yes.half.TART", "value": 1.771402499308953}, {"lowerIsBetter": true, "replicates": [1.9713045065219585, 1.9988796180627477, 1.9804896421210711, 2.0909421693106167, 1.9905896166036294, 2.018062570265361, 1.9897918467835862, 1.9752862416780912, 2.0676145279795173, 2.023564169256706, 2.0378780574798583, 2.0641013335797096, 1.970217495239698, 2.0953297949716694, 2.029728136777878, 1.9533514949201627, 1.968628354026721, 1.9643642501976655, 1.9928752579726279, 1.9666197749284597, 2.0284230760702, 1.9832341684851535, 2.019144452753521, 2.0732800691358504, 2.0195548942708594], "unit": "ms", "name": "newtab-open-preload-yes.all.TART", "value": 2.0084710941640544}, {"lowerIsBetter": true, "replicates": [35.92000000000189, 34.450000000004366, 35.76000000000204, 35.69499999999971, 38.05999999999767, 34.82499999999709, 37.060000000012224, 37.229999999995925, 34.6649999999936, 37.49000000000524, 35.35000000000582, 36.88000000000466, 36.64499999998952, 36.045000000012806, 33.545000000012806, 36.27499999999418, 36.539999999979045, 38.00500000000466, 35.54999999998836, 36.17500000001746, 38.085000000020955, 36.3300000000163, 35.14000000001397, 37.51000000000931, 36.90000000002328], "unit": "ms", "name": "newtab-open-preload-yes.error.TART", "value": 36.30250000000524}], "name": "tart", "value": 3.7056203257033697}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [3.2483431952662722, 2.0050591715976336, 2.020650887573965, 2.024792899408284, 2.018195266272189, 2.0280177514792905, 2.0210355029585796, 2.0228402366863905, 2.010384615384616, 2.02603550295858, 2.0124852071005916, 2.00310650887574, 2.015976331360947, 2.0151775147928994, 2.017692307692308, 1.9989349112426036, 2.034644970414201, 2.018254437869823, 2.02585798816568, 2.0247928994082844, 2.0162721893491122, 2.0283431952662725, 2.039230769230769, 1.998284023668639, 2.0161242603550296], "unit": "ms", "name": "tiled.html", "value": 2.017973372781065}, {"lowerIsBetter": true, "replicates": [3.0252366863905324, 3.0168047337278106, 3.013816568047337, 3.02792899408284, 2.9937278106508876, 3.024408284023669, 2.9907988165680477, 3.0097337278106506, 2.9979881656804737, 3.0096745562130174, 3.016893491124261, 3.0216863905325444, 3.0044378698224854, 2.982603550295858, 3.159852071005917, 3.00698224852071, 3.0145562130177512, 2.9994674556213017, 3.0025739644970413, 3.0196745562130176, 3.00491124260355, 3.0286686390532545, 3.0026923076923078, 3.033343195266272, 2.9837278106508878], "unit": "ms", "name": "tiled-fixed.html", "value": 3.008328402366864}, {"lowerIsBetter": true, "replicates": [2.317189349112426, 2.284437869822485, 2.2611242603550297, 2.287041420118343, 2.283786982248521, 2.29, 2.282603550295858, 2.264260355029586, 2.2728106508875743, 2.26603550295858, 2.259674556213018, 2.2878994082840234, 2.2541124260355034, 2.290976331360947, 2.268402366863905, 2.259142011834319, 2.2882248520710062, 2.279674556213018, 2.296863905325444, 2.2609171597633138, 2.2818343195266273, 2.259822485207101, 2.2888165680473374, 2.2660059171597635, 2.296153846153846], "unit": "ms", "name": "tiled-downscale.html", "value": 2.276242603550296}, {"lowerIsBetter": true, "replicates": [3.3769230769230765, 3.3592011834319524, 3.3708875739644966, 3.368165680473372, 3.372485207100592, 3.375769230769231, 3.3694082840236685, 3.3940828402366865, 3.3732840236686394, 3.3701775147929, 3.3754733727810655, 3.3880473372781066, 3.404852071005917, 3.3939644970414204, 3.4129881656804733, 3.3632544378698226, 3.379260355029585, 3.3755029585798817, 3.381153846153846, 3.3729585798816575, 3.3825443786982246, 3.3885502958579887, 3.365325443786982, 3.3882840236686396, 3.375532544378698], "unit": "ms", "name": "tiled-fixed-downscale.html", "value": 3.377514792899408}, {"lowerIsBetter": true, "replicates": [3.257965116279069, 3.2376162790697673, 3.2062790697674415, 3.150639534883721, 3.1795930232558147, 3.1986046511627904, 3.1695348837209307, 3.193779069767442, 3.1748837209302323, 3.1743604651162793, 3.16046511627907, 3.141046511627907, 3.193313953488372, 3.1496511627906982, 3.1699418604651157, 3.2525581395348833, 3.160755813953488, 3.164651162790698, 3.179011627906977, 3.1804069767441865, 3.1934302325581387, 3.215813953488372, 3.174593023255814, 3.170290697674419, 3.174011627906977], "unit": "ms", "name": "iframe.svg", "value": 3.1744767441860464}, {"lowerIsBetter": true, "replicates": [2.9651445086705204, 2.974508670520232, 2.9524277456647403, 2.9704624277456646, 2.931907514450867, 2.9450578034682087, 2.9851734104046246, 2.969017341040463, 7.986849710982659, 2.9734393063583813, 2.9571965317919076, 2.9563294797687862, 2.9658670520231216, 2.9780057803468205, 3.0056069364161844, 2.961387283236994, 2.9646820809248555, 2.96150289017341, 2.9848265895953756, 2.957254335260116, 2.9725144508670516, 2.956416184971098, 2.96242774566474, 2.9588150289017343, 2.958179190751445], "unit": "ms", "name": "reader.htm", "value": 2.963554913294798}], "name": "tscrollx", "value": 2.7698859944622063}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [5.042817389965057, 4.91532183828808, 5.028763139247895, 5.064878821372986, 5.12203559577465, 5.225809437888009, 4.901333451271057, 5.0604903016771585, 5.053797789982387, 5.077770088400159, 5.056660449504852, 7.3942945258958, 5.256721967458725, 5.053914803266525, 4.944231180917649, 4.858490535191128, 4.862568162736439, 5.052408104851132, 5.013112596103123, 5.061898322332473, 4.998685348601568, 5.012435992558797, 5.044069914590745, 4.91689437911624, 4.972719964526949], "unit": "ms", "name": "1-customize-enter.half.TART", "value": 5.048239009720938}, {"lowerIsBetter": true, "replicates": [10.58124194753931, 9.901454147838411, 9.953357577323914, 10.137371176626624, 9.983601704949425, 10.200992712160437, 9.716176580815088, 9.752299450692677, 9.870204232987904, 10.052105457317538, 10.25102433344213, 12.693624839186668, 10.20944588241123, 10.223517147506156, 10.063447306795817, 10.071426926589593, 10.011635983862528, 9.982424328966838, 9.937056101503826, 9.949453626360212, 10.031753615635198, 10.334809225797652, 10.123003953840675, 9.765437585966927, 10.097379358803353], "unit": "ms", "name": "1-customize-enter.all.TART", "value": 10.041929536476367}, {"lowerIsBetter": true, "replicates": [327.27000000000044, 224.3299999999981, 226.40500000000247, 224.02999999999884, 228.58000000000175, 226.4499999999971, 216.4449999999997, 218.2300000000032, 223.1750000000029, 220.66500000000087, 229.56500000000233, 318.7099999999991, 238.20499999999447, 227.9649999999965, 221.1750000000029, 221.50999999999476, 218.80999999999767, 217.6350000000093, 225.94000000000233, 226.47000000000116, 219.73500000000058, 221.7600000000093, 223.47999999999593, 218.56999999999243, 222.6600000000035], "unit": "ms", "name": "1-customize-enter.error.TART", "value": 223.32749999999942}, {"lowerIsBetter": true, "replicates": [8.389261997663057, 7.996375762499296, 8.530778930737423, 8.259826082449694, 8.490016460418701, 9.07879920800527, 8.929233054320017, 8.157771908319914, 8.270312180885902, 8.22795461691343, 8.524369230637184, 8.111338138580322, 8.523453492384691, 8.34516155719757, 8.730958691010109, 8.458587967432463, 8.540438010142399, 8.518284485890316, 8.294208453251766, 8.339224549440237, 8.537986379403334, 8.505228656988878, 8.279114347237806, 8.496957934819735, 8.490991225609413], "unit": "ms", "name": "2-customize-exit.half.TART", "value": 8.490503843014057}, {"lowerIsBetter": true, "replicates": [10.172362838472639, 9.277968210143012, 10.37483966701171, 10.053390281541008, 10.305031545021954, 10.847911870840823, 10.465417251867407, 9.393655140148965, 9.986916012234158, 10.122004682677133, 10.393226293956532, 9.810990483420236, 9.851163003179762, 9.671490736909815, 10.236358400753566, 10.044174327169145, 10.481658073032603, 10.062723100185394, 10.232200316020421, 10.385456053649678, 10.527048626366783, 10.465417353545918, 10.307900235933417, 10.29004446548574, 10.273611692821278], "unit": "ms", "name": "2-customize-exit.all.TART", "value": 10.254985046787422}, {"lowerIsBetter": true, "replicates": [177.0049999999992, 164.3650000000016, 173.50500000000102, 173.03499999999985, 170.8650000000016, 179.39999999999418, 177.0, 177.5350000000035, 181.4449999999997, 175.08499999999913, 174.09999999999854, 163.84500000000116, 175.20499999999447, 178.56500000000233, 178.52499999999418, 171.95500000000175, 176.83499999999185, 182.49499999999534, 179.1149999999907, 174.06500000000233, 179.51499999999942, 176.0899999999965, 170.9200000000128, 170.4250000000029, 170.8000000000029], "unit": "ms", "name": "2-customize-exit.error.TART", "value": 175.1449999999968}, {"lowerIsBetter": true, "replicates": [10.087900352478027, 10.30435848236084, 9.546540347012607, 9.589513085105203, 9.431865345348012, 9.310802286321467, 10.120117568969727, 9.711658304387873, 10.149301242828368, 9.659085793928666, 10.119272804260254, 10.003180503845215, 9.899012912403453, 10.511447143554687, 10.3617657661438, 10.250637531280518, 10.13597650527954, 9.683172919533469, 10.328435039520263, 10.099420070648193, 10.526729965209961, 9.665962826121937, 10.02081651687622, 10.297446537017823, 9.871051268144088], "unit": "ms", "name": "3-customize-enter-css.half.TART", "value": 10.060118293762207}, {"lowerIsBetter": true, "replicates": [13.675471830368043, 13.224258279800415, 12.745844114394416, 12.915003186180478, 12.759868894304548, 12.34160683371804, 13.239042234420776, 12.997854550679525, 13.519915199279785, 12.923414366585869, 13.424127864837647, 13.51154408454895, 13.082351911635627, 13.949556752255088, 14.11720065066689, 13.559562683105469, 13.450604009628297, 12.914875257582892, 14.006610318234092, 13.536926364898681, 13.647324895858764, 12.9444245383853, 13.400262594223022, 13.574173927307129, 13.231189441680907], "unit": "ms", "name": "3-customize-enter-css.all.TART", "value": 13.319652414321899}, {"lowerIsBetter": true, "replicates": [73.18000000000029, 64.27999999999884, 67.36999999999898, 70.98999999999796, 67.71500000000015, 71.21999999999389, 64.62000000000262, 72.71999999999389, 69.9800000000032, 71.26499999999942, 68.20000000000437, 69.85499999999593, 74.50999999999476, 64.81500000000233, 67.93999999998778, 70.90499999999884, 68.66999999999825, 70.93000000000757, 65.95500000000175, 70.43499999999767, 72.69500000000698, 71.64999999999418, 67.73500000000058, 71.22000000000116, 64.43500000001222], "unit": "ms", "name": "3-customize-enter-css.error.TART", "value": 69.91749999999956}], "name": "cart", "value": 23.487541154801853}]} 23:05:14 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tscrollx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[287,53,25]]", "result": "2.77"}, "tsvgr_opacity": {"url": "http://graphs.mozilla.org/graph.html#tests=[[225,53,25]]", "result": "498.74"}, "tart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[293,53,25]]", "result": "3.71"}, "cart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[309,53,25]]", "result": "23.49"}, "tsvgx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[281,53,25]]", "result": "384.28"}}} 23:05:14 INFO - Return code: 0 23:05:14 INFO - Validating PERFHERDER_DATA against C:\slave\test-pgo\build\tests\talos\treeherder-schemas\performance-artifact.json 23:05:14 INFO - # TBPL SUCCESS # 23:05:14 INFO - Running post-action listener: _resource_record_post_action 23:05:14 INFO - Running post-run listener: _resource_record_post_run 23:05:15 INFO - Total resource usage - Wall time: 1132s; CPU: 8.0%; Read bytes: 194597888; Write bytes: 507570176; Read time: 2813840; Write time: 9281590 23:05:15 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 403968; Read time: 0; Write time: 2430 23:05:15 INFO - run-tests - Wall time: 1131s; CPU: 8.0%; Read bytes: 194595840; Write bytes: 483264512; Read time: 2808290; Write time: 9261890 23:05:15 INFO - Running post-run listener: _upload_blobber_files 23:05:15 INFO - Blob upload gear active. 23:05:16 INFO - Preparing to upload files from C:\slave\test-pgo\build\blobber_upload_dir. 23:05:16 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 23:05:16 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'] 23:05:16 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 23:05:16 INFO - (blobuploader) - INFO - Open directory for files ... 23:05:16 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log ... 23:05:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:17 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_errorsummary.log: uploaded 23:05:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:17 INFO - (blobuploader) - INFO - Done attempting. 23:05:17 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log ... 23:05:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_raw.log: uploaded 23:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:20 INFO - (blobuploader) - INFO - Done attempting. 23:05:20 INFO - (blobuploader) - INFO - Iteration through files over. 23:05:20 INFO - Return code: 0 23:05:20 INFO - rmtree: C:\slave\test-pgo\build\uploaded_files.json 23:05:20 INFO - Using _rmtree_windows ... 23:05:20 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build\\uploaded_files.json',), kwargs: {}, attempt #1 23:05:20 INFO - Setting buildbot property blobber_files to {"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6811bc832359655586721b574f6242ccd2e9ca0ba868557c814eab84088041ec743ffbac50b7991d1985fa27d028d5988ee5b0748e5e137f06042b4f0d5fca9c", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e06120513b78a38ecfc9d0521598e120ba73c136091c43604a79c8e41ec48a60a67278c279446eaa8e222ca523930a9de342e253acd1cf78c39d6bc6aef125a"} 23:05:20 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test-pgo\properties\blobber_files 23:05:20 INFO - Writing to file C:\slave\test-pgo\properties\blobber_files 23:05:20 INFO - Contents: 23:05:20 INFO - blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6811bc832359655586721b574f6242ccd2e9ca0ba868557c814eab84088041ec743ffbac50b7991d1985fa27d028d5988ee5b0748e5e137f06042b4f0d5fca9c", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e06120513b78a38ecfc9d0521598e120ba73c136091c43604a79c8e41ec48a60a67278c279446eaa8e222ca523930a9de342e253acd1cf78c39d6bc6aef125a"} 23:05:20 INFO - Running post-run listener: copy_logs_to_upload_dir 23:05:20 INFO - Copying logs to upload dir... 23:05:20 INFO - mkdir: C:\slave\test-pgo\build\upload\logs 23:05:20 INFO - Copying logs to upload dir... 23:05:20 INFO - Using _rmtree_windows ... 23:05:20 INFO - Using _rmtree_windows ... 23:05:20 INFO - Using _rmtree_windows ... 23:05:20 INFO - Using _rmtree_windows ... 23:05:20 INFO - Using _rmtree_windows ... 23:05:20 INFO - Using _rmtree_windows ... 23:05:20 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1345.890000 ========= master_lag: 0.79 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 26 secs) (at 2016-03-10 23:05:20.618717) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-10 23:05:20.619690) ========= '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-181 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-181 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-181 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/6811bc832359655586721b574f6242ccd2e9ca0ba868557c814eab84088041ec743ffbac50b7991d1985fa27d028d5988ee5b0748e5e137f06042b4f0d5fca9c", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e06120513b78a38ecfc9d0521598e120ba73c136091c43604a79c8e41ec48a60a67278c279446eaa8e222ca523930a9de342e253acd1cf78c39d6bc6aef125a"} build_url:https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/artifacts/public/build/firefox-46.0.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/JoR12S_lRHKHWdwnV4GWIw/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/6811bc832359655586721b574f6242ccd2e9ca0ba868557c814eab84088041ec743ffbac50b7991d1985fa27d028d5988ee5b0748e5e137f06042b4f0d5fca9c", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e06120513b78a38ecfc9d0521598e120ba73c136091c43604a79c8e41ec48a60a67278c279446eaa8e222ca523930a9de342e253acd1cf78c39d6bc6aef125a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-10 23:05:20.747823) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-10 23:05:20.748234) ========= '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-181 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-181 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-181 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-10 23:05:20.870421) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-10 23:05:20.870844) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-10 23:05:21.660089) ========= ========= Total master_lag: 1.09 =========