builder: mozilla-beta_win7-ix_test-svgr-pgo slave: t-w732-ix-178 starttime: 1457925292.0 results: success (0) buildid: 20160313163332 builduid: 003dd43cdf29498484fd2725ded1ae8f revision: a466f1fd87d814f81c4b4e39924a2ae69e80ed7f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:52.002533) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:52.002977) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:52.003285) ========= '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-178 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-178 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-178 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.517000 basedir: 'C:\\slave\\test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:52.540601) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:52.540909) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:52.672357) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:52.672729) ========= '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-178 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-178 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-178 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.401000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:53.095656) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:53.095936) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:53.096295) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:53.096574) ========= '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-178 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-178 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-178 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 --20:14:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 21.54 MB/s 20:14:53 (21.54 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.629000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 20:14:53.745751) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-03-13 20:14:53.746045) ========= '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-178 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-178 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-178 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=6.301000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-03-13 20:15:00.065210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-13 20:15:00.065582) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a466f1fd87d814f81c4b4e39924a2ae69e80ed7f --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 a466f1fd87d814f81c4b4e39924a2ae69e80ed7f --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-178 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-178 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-178 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-13 20:15:00,611 truncating revision to first 12 chars 2016-03-13 20:15:00,611 Setting DEBUG logging. 2016-03-13 20:15:00,611 attempt 1/10 2016-03-13 20:15:00,611 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a466f1fd87d8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-13 20:15:01,066 unpacking tar archive at: mozilla-beta-a466f1fd87d8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.183000 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-13 20:15:01.287703) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-13 20:15:01.288078) ========= 'echo' u'a466f1fd87d814f81c4b4e39924a2ae69e80ed7f' in dir C:\\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'a466f1fd87d814f81c4b4e39924a2ae69e80ed7f'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-178 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-178 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-178 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 a466f1fd87d814f81c4b4e39924a2ae69e80ed7f program finished with exit code 0 elapsedTime=0.101000 script_repo_revision: 'a466f1fd87d814f81c4b4e39924a2ae69e80ed7f' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-13 20:15:01.405726) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-13 20:15:01.406061) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-13 20:15:01.422327) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 33 secs) (at 2016-03-13 20:15:01.422605) ========= '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-178 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-178 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-178 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 20:15:02 INFO - MultiFileLogger online at 20160313 20:15:02 in C:\slave\test-pgo 20:15:02 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 20:15:02 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 20:15:02 INFO - {'append_to_log': False, 20:15:02 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 20:15:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:15:02 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 20:15:02 INFO - 'branch': 'Mozilla-Beta', 20:15:02 INFO - 'buildbot_json_path': 'buildprops.json', 20:15:02 INFO - 'config_files': ('talos/windows_config.py',), 20:15:02 INFO - 'default_actions': ('clobber', 20:15:02 INFO - 'read-buildbot-config', 20:15:02 INFO - 'download-and-extract', 20:15:02 INFO - 'populate-webroot', 20:15:02 INFO - 'create-virtualenv', 20:15:02 INFO - 'install', 20:15:02 INFO - 'run-tests'), 20:15:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:15:02 INFO - 'download_minidump_stackwalk': True, 20:15:02 INFO - 'download_symbols': 'ondemand', 20:15:02 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 20:15:02 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 20:15:02 INFO - 'hg': 'c:/mozilla-build/hg/hg', 20:15:02 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 20:15:02 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 20:15:02 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 20:15:02 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 20:15:02 INFO - 'C:/mozilla-build/tooltool.py'), 20:15:02 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 20:15:02 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 20:15:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:15:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:15:02 INFO - 'installer_path': 'installer.exe', 20:15:02 INFO - 'log_level': 'info', 20:15:02 INFO - 'log_name': 'talos', 20:15:02 INFO - 'log_to_console': True, 20:15:02 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 20:15:02 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 20:15:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 20:15:02 INFO - 'opt_config_files': (), 20:15:02 INFO - 'pip_index': False, 20:15:02 INFO - 'sps_profile': False, 20:15:02 INFO - 'sps_profile_interval': 0, 20:15:02 INFO - 'suite': 'svgr', 20:15:02 INFO - 'system_bits': '32', 20:15:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:15:02 INFO - 'title': 'T-W732-IX-178', 20:15:02 INFO - 'use_talos_json': True, 20:15:02 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 20:15:02 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 20:15:02 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 20:15:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:15:02 INFO - 'work_dir': 'build'} 20:15:02 INFO - ##### 20:15:02 INFO - ##### Running clobber step. 20:15:02 INFO - ##### 20:15:02 INFO - Running pre-action listener: _resource_record_pre_action 20:15:02 INFO - Running main action method: clobber 20:15:02 INFO - rmtree: C:\slave\test-pgo\build 20:15:02 INFO - Using _rmtree_windows ... 20:15:02 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 20:17:39 INFO - Running post-action listener: _resource_record_post_action 20:17:39 INFO - ##### 20:17:39 INFO - ##### Running read-buildbot-config step. 20:17:39 INFO - ##### 20:17:39 INFO - Running pre-action listener: _resource_record_pre_action 20:17:39 INFO - Running main action method: read_buildbot_config 20:17:39 INFO - Using buildbot properties: 20:17:39 INFO - { 20:17:39 INFO - "project": "", 20:17:39 INFO - "product": "firefox", 20:17:39 INFO - "script_repo_revision": "production", 20:17:39 INFO - "builddir": "mozilla-beta_win7-ix_test-svgr", 20:17:39 INFO - "repository": "", 20:17:39 INFO - "buildername": "Windows 7 32-bit mozilla-beta pgo talos svgr", 20:17:39 INFO - "buildid": "20160313163332", 20:17:39 INFO - "pgo_build": "True", 20:17:39 INFO - "basedir": "C:\\slave\\test-pgo", 20:17:39 INFO - "buildnumber": 100, 20:17:39 INFO - "slavename": "t-w732-ix-178", 20:17:39 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 20:17:39 INFO - "platform": "win7-ix", 20:17:39 INFO - "scheduler": "tests-mozilla-beta-win32-pgo-talos", 20:17:39 INFO - "branch": "mozilla-beta", 20:17:39 INFO - "repo_path": "releases/mozilla-beta", 20:17:39 INFO - "revision": "a466f1fd87d814f81c4b4e39924a2ae69e80ed7f", 20:17:39 INFO - "stage_platform": "win32-pgo", 20:17:39 INFO - "builduid": "003dd43cdf29498484fd2725ded1ae8f", 20:17:39 INFO - "slavebuilddir": "test" 20:17:39 INFO - } 20:17:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.zip. 20:17:39 INFO - Running post-action listener: _resource_record_post_action 20:17:39 INFO - ##### 20:17:39 INFO - ##### Running download-and-extract step. 20:17:39 INFO - ##### 20:17:39 INFO - Running pre-action listener: _resource_record_pre_action 20:17:39 INFO - Running main action method: download_and_extract 20:17:39 INFO - mkdir: C:\slave\test-pgo\build\tests 20:17:39 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')]} 20:17:39 INFO - https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 20:17:39 INFO - trying https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/test_packages.json 20:17:39 INFO - Downloading https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 20:17:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 20:17:40 INFO - Downloaded 1267 bytes. 20:17:40 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 20:17:40 INFO - Using the following test package requirements: 20:17:40 INFO - {u'common': [u'firefox-46.0.en-US.win32.common.tests.zip'], 20:17:40 INFO - u'cppunittest': [u'firefox-46.0.en-US.win32.common.tests.zip', 20:17:40 INFO - u'firefox-46.0.en-US.win32.cppunittest.tests.zip'], 20:17:40 INFO - u'gtest': [u'firefox-46.0.en-US.win32.common.tests.zip'], 20:17:40 INFO - u'jittest': [u'firefox-46.0.en-US.win32.common.tests.zip', 20:17:40 INFO - u'jsshell-win32.zip'], 20:17:40 INFO - u'mochitest': [u'firefox-46.0.en-US.win32.common.tests.zip', 20:17:40 INFO - u'firefox-46.0.en-US.win32.mochitest.tests.zip'], 20:17:40 INFO - u'mozbase': [u'firefox-46.0.en-US.win32.common.tests.zip'], 20:17:40 INFO - u'reftest': [u'firefox-46.0.en-US.win32.common.tests.zip', 20:17:40 INFO - u'firefox-46.0.en-US.win32.reftest.tests.zip'], 20:17:40 INFO - u'talos': [u'firefox-46.0.en-US.win32.common.tests.zip', 20:17:40 INFO - u'firefox-46.0.en-US.win32.talos.tests.zip'], 20:17:40 INFO - u'web-platform': [u'firefox-46.0.en-US.win32.common.tests.zip', 20:17:40 INFO - u'firefox-46.0.en-US.win32.web-platform.tests.zip'], 20:17:40 INFO - u'webapprt': [u'firefox-46.0.en-US.win32.common.tests.zip'], 20:17:40 INFO - u'xpcshell': [u'firefox-46.0.en-US.win32.common.tests.zip', 20:17:40 INFO - u'firefox-46.0.en-US.win32.xpcshell.tests.zip']} 20:17:40 INFO - Downloading packages: [u'firefox-46.0.en-US.win32.common.tests.zip'] for test suite category: common 20:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:40 INFO - https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 20:17:40 INFO - trying https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip 20:17:40 INFO - Downloading https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/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 20:17:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/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 20:17:42 INFO - Downloaded 17906892 bytes. 20:17:42 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'] 20:17:42 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 20:18:09 INFO - Return code: 0 20:18:09 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 20:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:18:09 INFO - https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 20:18:09 INFO - trying https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip 20:18:09 INFO - Downloading https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/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 20:18:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/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 20:18:11 INFO - Downloaded 17906892 bytes. 20:18:11 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'] 20:18:11 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 20:18:36 INFO - Return code: 0 20:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:18:36 INFO - https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 20:18:36 INFO - trying https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip 20:18:36 INFO - Downloading https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/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 20:18:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/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 20:18:38 INFO - Downloaded 10801536 bytes. 20:18:38 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'] 20:18:38 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 20:18:42 INFO - Return code: 0 20:18: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')]} 20:18:42 INFO - https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.zip matches https://queue.taskcluster.net 20:18:42 INFO - trying https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.zip 20:18:42 INFO - Downloading https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.zip to C:\slave\test-pgo\build\firefox-46.0.en-US.win32.zip 20:18:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/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 20:18:46 INFO - Downloaded 54960564 bytes. 20:18:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.zip 20:18:46 INFO - mkdir: C:\slave\test-pgo\properties 20:18:46 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 20:18:46 INFO - Writing to file C:\slave\test-pgo\properties\build_url 20:18:46 INFO - Contents: 20:18:46 INFO - build_url:https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.zip 20:18:46 INFO - Running post-action listener: _resource_record_post_action 20:18:46 INFO - Running post-action listener: set_extra_try_arguments 20:18:46 INFO - ##### 20:18:46 INFO - ##### Running populate-webroot step. 20:18:46 INFO - ##### 20:18:46 INFO - Running pre-action listener: _resource_record_pre_action 20:18:46 INFO - Running main action method: populate_webroot 20:18:46 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 20:18:46 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 20:18:46 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 20:18:46 INFO - u'--tppagecycles', 20:18:46 INFO - u'7'], 20:18:46 INFO - u'tests': [u'tsvgm']}}, 20:18:46 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 20:18:46 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 20:18:46 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 20:18:46 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 20:18:46 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 20:18:46 INFO - u'tests': [u'dromaeo_css', 20:18:46 INFO - u'kraken', 20:18:46 INFO - u'v8_7']}, 20:18:46 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:18:46 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:46 INFO - u'talos_options': [u'--e10s'], 20:18:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:18:46 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:46 INFO - u'tests': [u'damp', u'tps']}, 20:18:46 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:46 INFO - u'talos_options': [u'--e10s'], 20:18:46 INFO - u'tests': [u'damp', u'tps']}, 20:18:46 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 20:18:46 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 20:18:46 INFO - u'other': {u'tests': [u'a11yr', 20:18:46 INFO - u'ts_paint', 20:18:46 INFO - u'tpaint', 20:18:46 INFO - u'sessionrestore', 20:18:46 INFO - u'sessionrestore_no_auto_restore']}, 20:18:46 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 20:18:46 INFO - u'tests': [u'a11yr', 20:18:46 INFO - u'ts_paint', 20:18:46 INFO - u'tpaint', 20:18:46 INFO - u'sessionrestore', 20:18:46 INFO - u'sessionrestore_no_auto_restore']}, 20:18:46 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 20:18:46 INFO - u'tests': [u'a11yr', 20:18:46 INFO - u'ts_paint', 20:18:46 INFO - u'tpaint', 20:18:46 INFO - u'sessionrestore', 20:18:46 INFO - u'sessionrestore_no_auto_restore']}, 20:18:46 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 20:18:46 INFO - u'tests': [u'a11yr', 20:18:46 INFO - u'ts_paint', 20:18:46 INFO - u'tpaint', 20:18:46 INFO - u'sessionrestore', 20:18:46 INFO - u'sessionrestore_no_auto_restore']}, 20:18:46 INFO - u'other_l64': {u'tests': [u'a11yr', 20:18:46 INFO - u'ts_paint', 20:18:46 INFO - u'tpaint', 20:18:46 INFO - u'sessionrestore', 20:18:46 INFO - u'sessionrestore_no_auto_restore']}, 20:18:46 INFO - u'other_nol64': {u'tests': [u'a11yr', 20:18:46 INFO - u'ts_paint', 20:18:46 INFO - u'tpaint', 20:18:46 INFO - u'sessionrestore', 20:18:46 INFO - u'sessionrestore_no_auto_restore']}, 20:18:46 INFO - u'svgr': {u'tests': [u'tsvgx', 20:18:46 INFO - u'tsvgr_opacity', 20:18:46 INFO - u'tart', 20:18:46 INFO - u'tscrollx', 20:18:46 INFO - u'cart']}, 20:18:46 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 20:18:46 INFO - u'tests': [u'tsvgx', 20:18:46 INFO - u'tsvgr_opacity', 20:18:46 INFO - u'tart', 20:18:46 INFO - u'tscrollx', 20:18:46 INFO - u'cart']}, 20:18:46 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:46 INFO - u'tests': [u'tp5o']}, 20:18:46 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:46 INFO - u'talos_options': [u'--e10s'], 20:18:46 INFO - u'tests': [u'tp5o']}, 20:18:46 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:46 INFO - u'talos_options': [u'--xperf_path', 20:18:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:18:46 INFO - u'tests': [u'tp5n']}, 20:18:46 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:18:46 INFO - u'talos_options': [u'--e10s', 20:18:46 INFO - u'--xperf_path', 20:18:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:18:46 INFO - u'tests': [u'tp5n']}}, 20:18:46 INFO - u'talos.zip': {u'path': u'', 20:18:46 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:18:46 INFO - Running post-action listener: _resource_record_post_action 20:18:46 INFO - ##### 20:18:46 INFO - ##### Running create-virtualenv step. 20:18:46 INFO - ##### 20:18:46 INFO - Running pre-action listener: _resource_record_pre_action 20:18:46 INFO - Running main action method: create_virtualenv 20:18:46 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 20:18:46 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 20:18:46 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 20:18:46 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'! 20:18:46 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 20:18:46 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 20:18:49 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 20:18:51 INFO - Installing distribute......................................................................................................................................................................................done. 20:18:54 INFO - Return code: 0 20:18:54 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 20:18:54 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')]} 20:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18:54 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')]} 20:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:54 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 0x0180B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-178', 'USERDOMAIN': 'T-W732-IX-178', '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-178', '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 20:18:54 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 20:18:54 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 20:18:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:18:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:18:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:18:54 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:18:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:18:54 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:18:54 INFO - 'DCLOCATION': 'SCL3', 20:18:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:18:54 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:18:54 INFO - 'HOMEDRIVE': 'C:', 20:18:54 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:18:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:18:54 INFO - 'KTS_VERSION': '1.19c', 20:18:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:18:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:18:54 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:18:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:54 INFO - 'MOZ_NO_REMOTE': '1', 20:18:54 INFO - 'NO_EM_RESTART': '1', 20:18:54 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:18:54 INFO - 'OS': 'Windows_NT', 20:18:54 INFO - 'OURDRIVE': 'C:', 20:18:54 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;', 20:18:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:18:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:18:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:18:54 INFO - 'PROCESSOR_LEVEL': '6', 20:18:54 INFO - 'PROCESSOR_REVISION': '1e05', 20:18:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:18:54 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:18:54 INFO - 'PROMPT': '$P$G', 20:18:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:18:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:18:54 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:18:54 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:18:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:18:54 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:18:54 INFO - 'SYSTEMDRIVE': 'C:', 20:18:54 INFO - 'SYSTEMROOT': 'C:\\windows', 20:18:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:18:54 INFO - 'TEST1': 'testie', 20:18:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:18:54 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:18:54 INFO - 'USERNAME': 'cltbld', 20:18:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:18:54 INFO - 'WINDIR': 'C:\\windows', 20:18:54 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:18:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:18:55 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:18:55 INFO - Downloading/unpacking pip>=1.5 20:18:59 INFO - Running setup.py egg_info for package pip 20:18:59 INFO - warning: no files found matching 'pip\cacert.pem' 20:18:59 INFO - warning: no files found matching '*.html' under directory 'docs' 20:18:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:18:59 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:18:59 INFO - Installing collected packages: pip 20:18:59 INFO - Found existing installation: pip 0.8.2 20:18:59 INFO - Uninstalling pip: 20:18:59 INFO - Successfully uninstalled pip 20:18:59 INFO - Running setup.py install for pip 20:18:59 INFO - warning: no files found matching 'pip\cacert.pem' 20:18:59 INFO - warning: no files found matching '*.html' under directory 'docs' 20:18:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:18:59 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:18:59 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:18:59 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 20:18:59 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:18:59 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 20:18:59 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:18:59 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 20:18:59 INFO - Successfully installed pip 20:18:59 INFO - Cleaning up... 20:18:59 INFO - Return code: 0 20:18:59 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 20:18: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')]} 20:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:18: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')]} 20:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:18:59 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 0x0180B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-178', 'USERDOMAIN': 'T-W732-IX-178', '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-178', '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 20:18:59 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 20:18:59 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 20:18:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:18:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:18:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:18:59 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:18:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:18:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:18:59 INFO - 'DCLOCATION': 'SCL3', 20:18:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:18:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:18:59 INFO - 'HOMEDRIVE': 'C:', 20:18:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:18:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:18:59 INFO - 'KTS_VERSION': '1.19c', 20:18:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:18:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:18:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:18:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:59 INFO - 'MOZ_NO_REMOTE': '1', 20:18:59 INFO - 'NO_EM_RESTART': '1', 20:18:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:18:59 INFO - 'OS': 'Windows_NT', 20:18:59 INFO - 'OURDRIVE': 'C:', 20:18:59 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;', 20:18:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:18:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:18:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:18:59 INFO - 'PROCESSOR_LEVEL': '6', 20:18:59 INFO - 'PROCESSOR_REVISION': '1e05', 20:18:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:18:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:18:59 INFO - 'PROMPT': '$P$G', 20:18:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:18:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:18:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:18:59 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:18:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:18:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:18:59 INFO - 'SYSTEMDRIVE': 'C:', 20:18:59 INFO - 'SYSTEMROOT': 'C:\\windows', 20:18:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:18:59 INFO - 'TEST1': 'testie', 20:18:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:18:59 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:18:59 INFO - 'USERNAME': 'cltbld', 20:18:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:18:59 INFO - 'WINDIR': 'C:\\windows', 20:18:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:18:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:01 INFO - Downloading/unpacking psutil>=0.7.1 20:19:01 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 20:19:01 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 20:19:02 INFO - Installing collected packages: psutil 20:19:02 INFO - Successfully installed psutil 20:19:02 INFO - Cleaning up... 20:19:02 INFO - Return code: 0 20:19:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 20:19:02 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')]} 20:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:02 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')]} 20:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:02 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 0x0180B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-178', 'USERDOMAIN': 'T-W732-IX-178', '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-178', '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 20:19:03 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 20:19:03 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 20:19:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:03 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:19:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:03 INFO - 'DCLOCATION': 'SCL3', 20:19:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:03 INFO - 'HOMEDRIVE': 'C:', 20:19:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:03 INFO - 'KTS_VERSION': '1.19c', 20:19:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:19:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:03 INFO - 'MOZ_NO_REMOTE': '1', 20:19:03 INFO - 'NO_EM_RESTART': '1', 20:19:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:03 INFO - 'OS': 'Windows_NT', 20:19:03 INFO - 'OURDRIVE': 'C:', 20:19:03 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;', 20:19:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:03 INFO - 'PROCESSOR_LEVEL': '6', 20:19:03 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:03 INFO - 'PROMPT': '$P$G', 20:19:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:03 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:03 INFO - 'SYSTEMDRIVE': 'C:', 20:19:03 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:03 INFO - 'TEST1': 'testie', 20:19:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:03 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:19:03 INFO - 'USERNAME': 'cltbld', 20:19:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:03 INFO - 'WINDIR': 'C:\\windows', 20:19:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:19:03 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 20:19:03 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 20:19:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:19:04 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 20:19:04 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) 20:19:04 INFO - Installing collected packages: mozsystemmonitor 20:19:04 INFO - Running setup.py install for mozsystemmonitor 20:19:04 INFO - Successfully installed mozsystemmonitor 20:19:04 INFO - Cleaning up... 20:19:04 INFO - Return code: 0 20:19:04 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 20:19:04 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')]} 20:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:04 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')]} 20:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:04 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 0x0180B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-178', 'USERDOMAIN': 'T-W732-IX-178', '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-178', '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 20:19:04 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 20:19:04 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 20:19:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:04 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:19:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:04 INFO - 'DCLOCATION': 'SCL3', 20:19:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:04 INFO - 'HOMEDRIVE': 'C:', 20:19:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:04 INFO - 'KTS_VERSION': '1.19c', 20:19:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:19:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:04 INFO - 'MOZ_NO_REMOTE': '1', 20:19:04 INFO - 'NO_EM_RESTART': '1', 20:19:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:04 INFO - 'OS': 'Windows_NT', 20:19:04 INFO - 'OURDRIVE': 'C:', 20:19:04 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;', 20:19:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:04 INFO - 'PROCESSOR_LEVEL': '6', 20:19:04 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:04 INFO - 'PROMPT': '$P$G', 20:19:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:04 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:04 INFO - 'SYSTEMDRIVE': 'C:', 20:19:04 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:04 INFO - 'TEST1': 'testie', 20:19:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:04 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:19:04 INFO - 'USERNAME': 'cltbld', 20:19:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:04 INFO - 'WINDIR': 'C:\\windows', 20:19:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:05 INFO - Downloading/unpacking blobuploader==1.2.4 20:19:05 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 20:19:05 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 20:19:06 INFO - Downloading blobuploader-1.2.4.tar.gz 20:19:06 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 20:19:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:19:06 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 20:19:06 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 20:19:06 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 20:19:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:19:06 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 20:19:06 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 20:19:06 INFO - Downloading docopt-0.6.1.tar.gz 20:19:06 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 20:19:07 INFO - Installing collected packages: blobuploader, requests, docopt 20:19:07 INFO - Running setup.py install for blobuploader 20:19:07 INFO - Running setup.py install for requests 20:19:07 INFO - Running setup.py install for docopt 20:19:07 INFO - Successfully installed blobuploader requests docopt 20:19:07 INFO - Cleaning up... 20:19:09 INFO - Return code: 0 20:19:09 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 20:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:09 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 0x0180B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-178', 'USERDOMAIN': 'T-W732-IX-178', '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-178', '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 20:19:09 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 20:19:09 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 20:19:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:09 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:19:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:09 INFO - 'DCLOCATION': 'SCL3', 20:19:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:09 INFO - 'HOMEDRIVE': 'C:', 20:19:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:09 INFO - 'KTS_VERSION': '1.19c', 20:19:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:19:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:09 INFO - 'MOZ_NO_REMOTE': '1', 20:19:09 INFO - 'NO_EM_RESTART': '1', 20:19:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:09 INFO - 'OS': 'Windows_NT', 20:19:09 INFO - 'OURDRIVE': 'C:', 20:19:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:19:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:09 INFO - 'PROCESSOR_LEVEL': '6', 20:19:09 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:09 INFO - 'PROMPT': '$P$G', 20:19:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:09 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:09 INFO - 'SYSTEMDRIVE': 'C:', 20:19:09 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:09 INFO - 'TEST1': 'testie', 20:19:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:09 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:19:09 INFO - 'USERNAME': 'cltbld', 20:19:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:09 INFO - 'WINDIR': 'C:\\windows', 20:19:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 20:19:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mlaeai-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 20:19:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 20:19:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n4b9ub-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 20:19:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 20:19:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tjs3db-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 20:19:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 20:19:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fly1ja-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 20:19:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 20:19:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vp2qfl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 20:19:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 20:19:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tevs_s-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 20:19:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 20:19:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hjuqy1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 20:19:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 20:19:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m1hqcz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 20:19:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 20:19:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-62tqbi-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 20:19:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 20:19:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sxzdd2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 20:19:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 20:19:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5iqpsk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 20:19:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 20:19:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-drl2ew-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 20:19:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 20:19:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-strxgz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 20:19:12 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 20:19:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sa0epg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 20:19:12 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 20:19:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hlnmnx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:19:12 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 20:19:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mjsplo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 20:19:12 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 20:19:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-34ppnk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 20:19:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:19:12 INFO - Running setup.py install for manifestparser 20:19:12 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:12 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:13 INFO - Running setup.py install for mozcrash 20:19:13 INFO - Running setup.py install for mozdebug 20:19:13 INFO - Running setup.py install for mozdevice 20:19:14 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:14 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:14 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:14 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:14 INFO - Running setup.py install for mozfile 20:19:14 INFO - Running setup.py install for mozhttpd 20:19:15 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Running setup.py install for mozinfo 20:19:15 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Running setup.py install for mozInstall 20:19:15 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:15 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:16 INFO - Running setup.py install for mozleak 20:19:16 INFO - Running setup.py install for mozlog 20:19:16 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:16 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:17 INFO - Running setup.py install for moznetwork 20:19:17 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:17 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:17 INFO - Running setup.py install for mozprocess 20:19:18 INFO - Running setup.py install for mozprofile 20:19:18 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:18 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:18 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:18 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:18 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:18 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:18 INFO - Running setup.py install for mozrunner 20:19:19 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:19 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:19 INFO - Running setup.py install for mozscreenshot 20:19:19 INFO - Running setup.py install for moztest 20:19:20 INFO - Running setup.py install for mozversion 20:19:20 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:20 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:19:20 INFO - Cleaning up... 20:19:20 INFO - Return code: 0 20:19:20 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 20:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:20 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 0x0180B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-178', 'USERDOMAIN': 'T-W732-IX-178', '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-178', '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 20:19:20 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 20:19:20 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 20:19:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:20 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:19:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:20 INFO - 'DCLOCATION': 'SCL3', 20:19:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:20 INFO - 'HOMEDRIVE': 'C:', 20:19:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:20 INFO - 'KTS_VERSION': '1.19c', 20:19:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:19:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:20 INFO - 'MOZ_NO_REMOTE': '1', 20:19:20 INFO - 'NO_EM_RESTART': '1', 20:19:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:20 INFO - 'OS': 'Windows_NT', 20:19:20 INFO - 'OURDRIVE': 'C:', 20:19:20 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;', 20:19:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:20 INFO - 'PROCESSOR_LEVEL': '6', 20:19:20 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:20 INFO - 'PROMPT': '$P$G', 20:19:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:20 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:20 INFO - 'SYSTEMDRIVE': 'C:', 20:19:20 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:20 INFO - 'TEST1': 'testie', 20:19:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:20 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:19:20 INFO - 'USERNAME': 'cltbld', 20:19:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:20 INFO - 'WINDIR': 'C:\\windows', 20:19:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 20:19:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xb4q5t-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 20:19:21 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)) 20:19:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 20:19:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ucrgab-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 20:19:21 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)) 20:19:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 20:19:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dxjuqb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 20:19:21 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)) 20:19:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 20:19:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cj_78t-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 20:19:21 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)) 20:19:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 20:19:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3ptqan-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 20:19:21 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)) 20:19:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 20:19:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xgdkhg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 20:19:21 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)) 20:19:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 20:19:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3gx8zf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 20:19:22 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)) 20:19:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 20:19:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qwimjt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 20:19:22 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)) 20:19:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 20:19:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hol6oe-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 20:19:22 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)) 20:19:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 20:19:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gp61ww-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 20:19:22 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)) 20:19:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 20:19:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-teticx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 20:19:22 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)) 20:19:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 20:19:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u0qutl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 20:19:22 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)) 20:19:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 20:19:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gjcmlt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 20:19:23 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)) 20:19:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 20:19:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-plf1zo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 20:19:23 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)) 20:19:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 20:19:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ehrvyb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:19:23 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)) 20:19:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 20:19:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yj6dwn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 20:19:23 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)) 20:19:23 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 20:19:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ttuabx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 20:19:23 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)) 20:19:23 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)) 20:19:23 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)) 20:19:23 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)) 20:19:23 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)) 20:19:23 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)) 20:19:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 20:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:24 INFO - Downloading blessings-1.6.tar.gz 20:19:24 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 20:19:24 INFO - Installing collected packages: blessings 20:19:24 INFO - Running setup.py install for blessings 20:19:25 INFO - Successfully installed blessings 20:19:25 INFO - Cleaning up... 20:19:27 INFO - Return code: 0 20:19:27 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 20:19:27 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 20:19:27 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 20:19:28 INFO - Reading from file tmpfile_stdout 20:19:28 INFO - Using _rmtree_windows ... 20:19:28 INFO - Using _rmtree_windows ... 20:19:28 INFO - Current package versions: 20:19:28 INFO - blessings == 1.6 20:19:28 INFO - blobuploader == 1.2.4 20:19:28 INFO - docopt == 0.6.1 20:19:28 INFO - manifestparser == 1.1 20:19:28 INFO - mozInstall == 1.12 20:19:28 INFO - mozcrash == 0.16 20:19:28 INFO - mozdebug == 0.1 20:19:28 INFO - mozdevice == 0.48 20:19:28 INFO - mozfile == 1.2 20:19:28 INFO - mozhttpd == 0.7 20:19:28 INFO - mozinfo == 0.9 20:19:28 INFO - mozleak == 0.1 20:19:28 INFO - mozlog == 3.1 20:19:28 INFO - moznetwork == 0.27 20:19:28 INFO - mozprocess == 0.22 20:19:28 INFO - mozprofile == 0.28 20:19:28 INFO - mozrunner == 6.11 20:19:28 INFO - mozscreenshot == 0.1 20:19:28 INFO - mozsystemmonitor == 0.0 20:19:28 INFO - moztest == 0.7 20:19:28 INFO - mozversion == 1.4 20:19:28 INFO - psutil == 3.1.1 20:19:28 INFO - requests == 1.2.3 20:19:28 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 20:19: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')]} 20:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19: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')]} 20:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:28 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 0x0180B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-178', 'USERDOMAIN': 'T-W732-IX-178', '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-178', '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 20:19:28 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 20:19:28 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 20:19:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:28 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:19:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:28 INFO - 'DCLOCATION': 'SCL3', 20:19:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:28 INFO - 'HOMEDRIVE': 'C:', 20:19:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:28 INFO - 'KTS_VERSION': '1.19c', 20:19:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:19:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:28 INFO - 'MOZ_NO_REMOTE': '1', 20:19:28 INFO - 'NO_EM_RESTART': '1', 20:19:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:28 INFO - 'OS': 'Windows_NT', 20:19:28 INFO - 'OURDRIVE': 'C:', 20:19:28 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;', 20:19:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:28 INFO - 'PROCESSOR_LEVEL': '6', 20:19:28 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:28 INFO - 'PROMPT': '$P$G', 20:19:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:28 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:28 INFO - 'SYSTEMDRIVE': 'C:', 20:19:28 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:28 INFO - 'TEST1': 'testie', 20:19:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:28 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:19:28 INFO - 'USERNAME': 'cltbld', 20:19:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:28 INFO - 'WINDIR': 'C:\\windows', 20:19:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:28 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)) 20:19:28 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)) 20:19:28 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)) 20:19:28 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)) 20:19:28 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)) 20:19:28 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)) 20:19:28 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)) 20:19:28 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)) 20:19:28 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)) 20:19:28 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)) 20:19:28 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)) 20:19:28 INFO - Cleaning up... 20:19:28 INFO - Return code: 0 20:19:28 INFO - Installing jsonschema into virtualenv C:\slave\test-pgo\build/venv 20:19: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')]} 20:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19: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')]} 20:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:28 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 0x0180B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-178', 'USERDOMAIN': 'T-W732-IX-178', '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-178', '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 20:19:28 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 20:19:28 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 20:19:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:28 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:19:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:28 INFO - 'DCLOCATION': 'SCL3', 20:19:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:28 INFO - 'HOMEDRIVE': 'C:', 20:19:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:28 INFO - 'KTS_VERSION': '1.19c', 20:19:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:19:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:28 INFO - 'MOZ_NO_REMOTE': '1', 20:19:28 INFO - 'NO_EM_RESTART': '1', 20:19:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:28 INFO - 'OS': 'Windows_NT', 20:19:28 INFO - 'OURDRIVE': 'C:', 20:19:28 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;', 20:19:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:28 INFO - 'PROCESSOR_LEVEL': '6', 20:19:28 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:28 INFO - 'PROMPT': '$P$G', 20:19:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:28 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:28 INFO - 'SYSTEMDRIVE': 'C:', 20:19:28 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:28 INFO - 'TEST1': 'testie', 20:19:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:28 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:19:28 INFO - 'USERNAME': 'cltbld', 20:19:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:28 INFO - 'WINDIR': 'C:\\windows', 20:19:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:28 INFO - Downloading/unpacking jsonschema 20:19:28 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 20:19:28 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 20:19:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:19:29 INFO - Downloading/unpacking functools32 (from jsonschema) 20:19:29 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 20:19:29 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 20:19:30 INFO - Downloading functools32-3.2.3-2.tar.gz 20:19:30 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\functools32\setup.py) egg_info for package functools32 20:19:30 INFO - warning: no files found matching '*.txt' 20:19:30 INFO - no previously-included directories found matching 'build' 20:19:30 INFO - no previously-included directories found matching 'dist' 20:19:30 INFO - no previously-included directories found matching '.git*' 20:19:30 INFO - Installing collected packages: jsonschema, functools32 20:19:30 INFO - Running setup.py install for functools32 20:19:30 INFO - warning: no files found matching '*.txt' 20:19:30 INFO - no previously-included directories found matching 'build' 20:19:30 INFO - no previously-included directories found matching 'dist' 20:19:30 INFO - no previously-included directories found matching '.git*' 20:19:30 INFO - Successfully installed jsonschema functools32 20:19:30 INFO - Cleaning up... 20:19:30 INFO - Return code: 0 20:19:30 INFO - Installing flake8 into virtualenv C:\slave\test-pgo\build/venv 20:19:30 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')]} 20:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:30 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')]} 20:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:30 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 0x0180B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B1EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB608>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-178', 'USERDOMAIN': 'T-W732-IX-178', '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-178', '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 20:19:30 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 20:19:30 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 20:19:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:30 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:19:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:30 INFO - 'DCLOCATION': 'SCL3', 20:19:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:30 INFO - 'HOMEDRIVE': 'C:', 20:19:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:31 INFO - 'KTS_VERSION': '1.19c', 20:19:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:19:31 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:31 INFO - 'MOZ_NO_REMOTE': '1', 20:19:31 INFO - 'NO_EM_RESTART': '1', 20:19:31 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:31 INFO - 'OS': 'Windows_NT', 20:19:31 INFO - 'OURDRIVE': 'C:', 20:19:31 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;', 20:19:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:31 INFO - 'PROCESSOR_LEVEL': '6', 20:19:31 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:31 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:31 INFO - 'PROMPT': '$P$G', 20:19:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:31 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:31 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:31 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:31 INFO - 'SYSTEMDRIVE': 'C:', 20:19:31 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:31 INFO - 'TEST1': 'testie', 20:19:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:31 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:19:31 INFO - 'USERNAME': 'cltbld', 20:19:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:31 INFO - 'WINDIR': 'C:\\windows', 20:19:31 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:31 INFO - Downloading/unpacking flake8 20:19:31 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 20:19:31 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 20:19:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:19:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:19:32 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 20:19:32 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 20:19:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:19:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:19:32 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 20:19:32 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 20:19:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:19:32 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 20:19:32 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 20:19:32 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\pep8\setup.py) egg_info for package pep8 20:19:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:19:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:19:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:19:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:19:32 INFO - no previously-included directories found matching 'docs\_build' 20:19:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:19:33 INFO - Running setup.py install for pep8 20:19:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:19:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:19:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:19:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:19:33 INFO - no previously-included directories found matching 'docs\_build' 20:19:33 INFO - Installing pep8-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:33 INFO - Installing pep8.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:19:33 INFO - Cleaning up... 20:19:33 INFO - Return code: 0 20:19:33 INFO - Running post-action listener: _resource_record_post_action 20:19:33 INFO - Running post-action listener: _start_resource_monitoring 20:19:33 INFO - Starting resource monitoring. 20:19:34 INFO - ##### 20:19:34 INFO - ##### Running install step. 20:19:34 INFO - ##### 20:19:34 INFO - Running pre-action listener: _resource_record_pre_action 20:19:34 INFO - Running main action method: install 20:19:34 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 20:19:34 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 20:19:34 INFO - Reading from file tmpfile_stdout 20:19:34 INFO - Using _rmtree_windows ... 20:19:34 INFO - Using _rmtree_windows ... 20:19:34 INFO - Detecting whether we're running mozinstall >=1.0... 20:19:34 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 20:19:34 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 20:19:34 INFO - Reading from file tmpfile_stdout 20:19:34 INFO - Output received: 20:19:34 INFO - Usage: mozinstall-script.py [options] installer 20:19:34 INFO - Options: 20:19:34 INFO - -h, --help show this help message and exit 20:19:34 INFO - -d DEST, --destination=DEST 20:19:34 INFO - Directory to install application into. [default: 20:19:34 INFO - "C:\slave\test-pgo"] 20:19:34 INFO - --app=APP Application being installed. [default: firefox] 20:19:34 INFO - Using _rmtree_windows ... 20:19:34 INFO - Using _rmtree_windows ... 20:19:34 INFO - mkdir: C:\slave\test-pgo\build\application 20:19:34 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'] 20:19:34 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 20:19:35 INFO - Reading from file tmpfile_stdout 20:19:35 INFO - Output received: 20:19:35 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 20:19:35 INFO - Using _rmtree_windows ... 20:19:35 INFO - Using _rmtree_windows ... 20:19:35 INFO - Running post-action listener: _resource_record_post_action 20:19:35 INFO - ##### 20:19:35 INFO - ##### Running run-tests step. 20:19:35 INFO - ##### 20:19:35 INFO - Running pre-action listener: _resource_record_pre_action 20:19:35 INFO - Running main action method: run_tests 20:19:35 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 20:19:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 20:19:35 INFO - Python 2.7.3 20:19:35 INFO - Return code: 0 20:19:35 INFO - grabbing minidump binary from tooltool 20:19:35 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')]} 20:19:35 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 0x01248BF0>, '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 0x0126A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F225C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 20:19:35 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 20:19:35 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 20:19:35 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 20:19:37 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmph2jzlp 20:19:37 INFO - INFO - File integrity verified, renaming tmph2jzlp to win32-minidump_stackwalk.exe 20:19:37 INFO - Return code: 0 20:19:37 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 20:19:37 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 20:19:37 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 20:19:37 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 20:19:37 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 20:19:37 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\flake8', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos'] 20:19:37 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\flake8 C:\slave\test-pgo\build\tests\talos\talos 20:19:38 INFO - Return code: 0 20:19:38 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/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-178', '--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 20:19:38 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/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-178 --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 20:19:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:38 INFO - 'COMPUTERNAME': 'T-W732-IX-178', 20:19:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:38 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:38 INFO - 'DCLOCATION': 'SCL3', 20:19:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:38 INFO - 'HOMEDRIVE': 'C:', 20:19:38 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:38 INFO - 'KTS_VERSION': '1.19c', 20:19:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-178', 20:19:38 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 20:19:38 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 20:19:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:38 INFO - 'MOZ_NO_REMOTE': '1', 20:19:38 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 20:19:38 INFO - 'NO_EM_RESTART': '1', 20:19:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:38 INFO - 'OS': 'Windows_NT', 20:19:38 INFO - 'OURDRIVE': 'C:', 20:19:38 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;', 20:19:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:39 INFO - 'PROCESSOR_LEVEL': '6', 20:19:39 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:39 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:39 INFO - 'PROMPT': '$P$G', 20:19:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:39 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:39 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:39 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 20:19:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:39 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:39 INFO - 'SYSTEMDRIVE': 'C:', 20:19:39 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:39 INFO - 'TEST1': 'testie', 20:19:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:39 INFO - 'USERDOMAIN': 'T-W732-IX-178', 20:19:39 INFO - 'USERNAME': 'cltbld', 20:19:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:39 INFO - 'WINDIR': 'C:\\windows', 20:19:39 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:39 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/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-178', '--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 20:19:40 INFO - mozversion application_buildid: 20160313163332 20:19:40 INFO - mozversion application_changeset: a466f1fd87d814f81c4b4e39924a2ae69e80ed7f 20:19:40 INFO - mozversion application_display_name: Firefox 20:19:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:19:40 INFO - mozversion application_name: Firefox 20:19:40 INFO - mozversion application_remotingname: firefox 20:19:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:19:40 INFO - mozversion application_vendor: Mozilla 20:19:40 INFO - mozversion application_version: 46.0 20:19:40 INFO - mozversion platform_buildid: 20160313163332 20:19:40 INFO - mozversion platform_changeset: a466f1fd87d814f81c4b4e39924a2ae69e80ed7f 20:19:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:19:40 INFO - mozversion platform_version: 46.0 20:19:40 INFO - using testdate: 1457925580 20:19:40 INFO - actual date: 1457925580 20:19:40 INFO - starting webserver on 'localhost:49895' 20:19:40 INFO - SUITE-START | Running 5 tests 20:19:40 INFO - TEST-START | tsvgx 20:19:40 INFO - operating with platform_type : w7_ 20:19:40 INFO - Initialising browser for tsvgx test... 20:19:40 INFO - TEST-INFO | started process 2512 (C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmzt8_n\profile http://localhost:49895/getInfo.html) 20:19:47 INFO - PROCESS | 2512 | __metrics Screen width/height:1600/1200 20:19:47 INFO - PROCESS | 2512 | colorDepth:24 20:19:47 INFO - PROCESS | 2512 | Browser inner width/height: 1010/674 20:19:47 INFO - PROCESS | 2512 | __metrics 20:19:47 INFO - PROCESS | 2512 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:19:48 INFO - PROCESS | 2512 | console.error: 20:19:48 INFO - PROCESS | 2512 | Message: Error: Connection closed before committing the transaction. 20:19:48 INFO - PROCESS | 2512 | Stack: 20:19:48 INFO - PROCESS | 2512 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:933:23 20:19:48 INFO - PROCESS | 2512 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7 20:19:48 INFO - PROCESS | 2512 | this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:746:11 20:19:48 INFO - PROCESS | 2512 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 20:19:48 INFO - PROCESS | 2512 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 20:19:48 INFO - PROCESS | 2512 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5 20:19:48 INFO - PROCESS | 2512 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 20:19:48 INFO - PROCESS | 2512 | Barrier/this.client.addBlocker/promisetsvgx: 380.45 20:38:26 INFO - RETURN: tsvgr_opacity: 496.45 20:38:26 INFO - RETURN: tart: 3.73 20:38:26 INFO - RETURN: tscrollx: 2.77 20:38:26 INFO - RETURN: cart: 23.39 20:38:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [246.0, 214.0, 208.0, 210.0, 207.0, 208.0, 207.0, 211.0, 212.0, 211.0, 207.0, 214.0, 207.0, 209.0, 207.0, 209.0, 213.0, 209.0, 208.0, 209.0, 207.0, 208.0, 207.0, 209.0, 207.0], "unit": "ms", "name": "gearflowers.svg", "value": 208.5}, {"lowerIsBetter": true, "replicates": [65.0, 41.0, 40.0, 40.0, 41.0, 39.0, 40.0, 40.0, 40.0, 40.0, 41.0, 40.0, 40.0, 40.0, 40.0, 40.0, 40.0, 40.0, 40.0, 41.0, 40.0, 40.0, 40.0, 40.0, 40.0], "unit": "ms", "name": "composite-scale.svg", "value": 40.0}, {"lowerIsBetter": true, "replicates": [129.0, 124.0, 123.0, 126.0, 126.0, 127.0, 122.0, 126.0, 123.0, 123.0, 126.0, 122.0, 125.0, 123.0, 127.0, 125.0, 123.0, 124.0, 126.0, 123.0, 124.0, 123.0, 123.0, 123.0, 122.0], "unit": "ms", "name": "composite-scale-opacity.svg", "value": 123.0}, {"lowerIsBetter": true, "replicates": [173.0, 166.0, 166.0, 167.0, 168.0, 166.0, 166.0, 166.0, 168.0, 165.0, 167.0, 166.0, 168.0, 165.0, 172.0, 165.0, 168.0, 170.0, 171.0, 167.0, 169.0, 166.0, 168.0, 166.0, 166.0], "unit": "ms", "name": "composite-scale-rotate.svg", "value": 166.5}, {"lowerIsBetter": true, "replicates": [246.0, 245.0, 240.0, 239.0, 239.0, 239.0, 240.0, 240.0, 246.0, 239.0, 237.0, 246.0, 245.0, 239.0, 239.0, 240.0, 240.0, 239.0, 241.0, 240.0, 240.0, 246.0, 246.0, 240.0, 244.0], "unit": "ms", "name": "composite-scale-rotate-opacity.svg", "value": 240.0}, {"lowerIsBetter": true, "replicates": [462.0, 429.0, 432.0, 428.0, 431.0, 427.0, 435.0, 431.0, 432.0, 435.0, 423.0, 431.0, 432.0, 435.0, 429.0, 430.0, 433.0, 431.0, 429.0, 427.0, 430.0, 431.0, 432.0, 425.0, 431.0], "unit": "ms", "name": "hixie-001.xml", "value": 431.0}, {"lowerIsBetter": true, "replicates": [436.0, 432.0, 430.0, 429.0, 429.0, 430.0, 444.0, 432.0, 422.0, 434.0, 428.0, 440.0, 429.0, 434.0, 430.0, 431.0, 431.0, 437.0, 436.0, 437.0, 430.0, 431.0, 433.0, 462.0, 432.0], "unit": "ms", "name": "hixie-002.xml", "value": 432.0}, {"lowerIsBetter": true, "replicates": [264.0, 214.0, 211.0, 2347.0, 209.0, 215.0, 212.0, 209.0, 214.0, 213.0, 211.0, 211.0, 212.0, 213.0, 214.0, 211.0, 210.0, 212.0, 216.0, 215.0, 213.0, 213.0, 212.0, 211.0, 212.0], "unit": "ms", "name": "hixie-003.xml", "value": 212.0}, {"lowerIsBetter": true, "replicates": [1103.0, 1089.0, 1102.0, 1102.0, 1116.0, 1111.0, 1087.0, 1095.0, 1105.0, 1109.0, 1104.0, 1103.0, 1084.0, 1106.0, 1096.0, 1109.0, 1100.0, 1109.0, 1118.0, 1107.0, 1102.0, 1108.0, 1122.0, 1103.0, 2004.0], "unit": "ms", "name": "hixie-004.xml", "value": 1105.5}, {"lowerIsBetter": true, "replicates": [2437.0, 2430.0, 2452.0, 2439.0, 2436.0, 2435.0, 2431.0, 2446.0, 2441.0, 2434.0, 2451.0, 2441.0, 2438.0, 2442.0, 2433.0, 2442.0, 2429.0, 2445.0, 2447.0, 2454.0, 2444.0, 2440.0, 2442.0, 2454.0, 2434.0], "unit": "ms", "name": "hixie-005.xml", "value": 2441.5}, {"lowerIsBetter": true, "replicates": [3741.0, 3741.0, 3767.0, 3759.0, 3763.0, 3756.0, 3750.0, 3756.0, 3762.0, 3777.0, 3778.0, 3836.0, 3751.0, 3754.0, 3736.0, 3753.0, 3754.0, 3751.0, 3741.0, 3759.0, 3741.0, 3752.0, 3753.0, 3760.0, 3752.0], "unit": "ms", "name": "hixie-006.xml", "value": 3753.5}, {"lowerIsBetter": true, "replicates": [672.0, 546.0, 538.0, 547.0, 543.0, 545.0, 542.0, 545.0, 542.0, 543.0, 549.0, 548.0, 545.0, 561.0, 547.0, 551.0, 544.0, 566.0, 545.0, 560.0, 544.0, 545.0, 555.0, 546.0, 545.0], "unit": "ms", "name": "hixie-007.xml", "value": 545.0}], "name": "tsvgx", "value": 380.453804685237}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [749.0, 721.0, 709.0, 68.0, 704.0, 68.0, 705.0, 724.0, 720.0, 68.0, 705.0, 66.0, 702.0, 68.0, 723.0, 709.0, 67.0, 708.0, 69.0, 707.0, 705.0, 777.0, 728.0, 67.0, 708.0], "unit": "ms", "name": "big-optimizable-group-opacity-2500.svg", "value": 705.0}, {"lowerIsBetter": true, "replicates": [322.0, 354.0, 357.0, 349.0, 348.0, 361.0, 346.0, 351.0, 346.0, 348.0, 350.0, 348.0, 346.0, 351.0, 346.0, 348.0, 350.0, 347.0, 350.0, 351.0, 349.0, 349.0, 353.0, 351.0, 353.0], "unit": "ms", "name": "small-group-opacity-2500.svg", "value": 349.5}], "name": "tsvgr_opacity", "value": 496.4464795332257}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1.5185594809682745, 1.535525946240676, 1.6024812310934067, 1.5601516704301577, 1.4953321125600245, 1.5818181805414697, 1.5612517595291138, 1.547572234471639, 1.5555489304903392, 4.819411545991898, 1.5635245909561981, 1.5632755144222363, 1.5401739549636841, 1.571297875932745, 1.5709398227768976, 1.6573835253715514, 1.5425291236241658, 1.5642510636432752, 1.595620922846337, 1.6494074208395821, 1.6751261051150337, 1.6086837963925467, 1.5553361854037724, 1.5909446102299103, 1.6015105893214543], "unit": "ms", "name": "simple-open-DPI1.half.TART", "value": 1.5675954432100863}, {"lowerIsBetter": true, "replicates": [1.708937808971277, 1.709677452209012, 1.7915830620577637, 1.7263869639156626, 1.6963033119837443, 1.743179781796181, 1.7388164539859718, 1.6982053669293722, 1.7370016803480175, 2.6398812984165394, 1.7358865239848829, 1.742177720755747, 1.7307597917764366, 1.7625800263550546, 1.7531917054077675, 1.7954581531000808, 1.7194780191859684, 1.7523866415023803, 1.7876890343679508, 1.77536224902093, 1.7946050403811407, 1.7593800748387973, 1.7252402135304041, 1.7711329468480357, 1.7436926511869038], "unit": "ms", "name": "simple-open-DPI1.all.TART", "value": 1.7434362164915425}, {"lowerIsBetter": true, "replicates": [24.909999999999854, 24.979999999999563, 24.68000000000029, 24.160000000003492, 24.790000000000873, 24.705000000001746, 24.205000000001746, 25.020000000004075, 23.99000000000524, 25.125, 23.729999999981374, 24.720000000001164, 24.67499999998836, 24.154999999998836, 24.529999999998836, 26.41500000000815, 24.929999999993015, 24.894999999989523, 24.304999999993015, 24.270000000018626, 23.479999999981374, 23.79999999998836, 23.904999999969732, 23.660000000032596, 24.85999999998603], "unit": "ms", "name": "simple-open-DPI1.error.TART", "value": 24.602499999993597}, {"lowerIsBetter": true, "replicates": [1.1639553495127746, 1.0987016456467764, 1.0937957741179556, 1.138724422690892, 1.07621289803603, 1.1348864623144561, 1.139306115041865, 1.1557179737091063, 1.1440889262917018, 1.2337152273096936, 1.2062808647751808, 1.1478300189027693, 1.1334445949862986, 1.1314342775765587, 1.109077778000098, 1.091473692992948, 1.140351640706015, 1.1461609802623787, 1.0808244197168082, 1.1636605551748564, 1.1434844360493197, 1.1331471929363175, 1.1424274916696076, 1.1436250935686696, 1.14472765143555], "unit": "ms", "name": "simple-close-DPI1.half.TART", "value": 1.13982887787394}, {"lowerIsBetter": true, "replicates": [1.229276977705233, 1.1897629611375855, 1.1943208063934363, 1.2986490040066394, 1.177098235831811, 1.203554177225517, 1.3204637173059823, 1.3151481520104151, 1.3125075964047948, 1.3997239864420616, 1.364746520306145, 1.3248144512915092, 1.3305054835003356, 1.3384788805438625, 1.3007105616005985, 1.176661207480131, 1.2881187333001032, 1.332953454359718, 1.1886419526581626, 1.3611623969342974, 1.3058938288942297, 1.302701362904082, 1.3060063961992927, 1.3254037451099705, 1.3143430031755918], "unit": "ms", "name": "simple-close-DPI1.all.TART", "value": 1.3092569963020437}, {"lowerIsBetter": true, "replicates": [16.06499999999869, 16.610000000000582, 16.25, 16.735000000000582, 17.43000000000029, 17.10000000000582, 17.394999999989523, 17.625, 18.139999999999418, 16.125, 16.779999999998836, 16.704999999987194, 17.380000000004657, 16.869999999995343, 16.0, 16.639999999984866, 16.220000000001164, 18.260000000009313, 17.480000000010477, 17.56500000000233, 18.070000000006985, 18.005000000004657, 17.235000000044238, 17.76500000001397, 17.130000000004657], "unit": "ms", "name": "simple-close-DPI1.error.TART", "value": 17.182500000024447}, {"lowerIsBetter": true, "replicates": [1.6163476804892223, 1.561033817561897, 1.5347110033035278, 1.5758215711541372, 1.5378751118977865, 1.5815604255624014, 1.5906553039812061, 1.7888680953245897, 1.582786057093372, 1.5468759187062582, 1.5594200089171126, 1.5984706282615662, 1.6111317508750491, 1.5826387699336222, 1.6283937575111926, 1.5916968224799797, 1.5736692950532243, 1.6044918712642458, 1.5815867381553128, 1.5773101963409006, 1.6044812086555693, 1.6680806581524834, 1.6168543315596051, 1.6914875034023733, 1.5799455463069758], "unit": "ms", "name": "icon-open-DPI1.half.TART", "value": 1.5827124135134971}, {"lowerIsBetter": true, "replicates": [1.8330708870784842, 1.798792406706743, 1.7814068052318546, 1.7973719818372254, 1.7714758780267503, 1.802535467959465, 1.817407502446856, 1.9233207359458462, 1.8123552322387695, 1.7929513664312766, 1.7812188380248064, 1.8202901942389351, 1.8223034126417978, 1.7846479299185158, 1.8896143854099468, 1.832808434534416, 1.810882352079664, 1.8447373284809832, 1.806560607666665, 1.8140201125826154, 1.790990842060304, 1.8365371106327444, 1.8390720141106758, 1.8603888053963655, 1.793462465346699], "unit": "ms", "name": "icon-open-DPI1.all.TART", "value": 1.8116187921592166}, {"lowerIsBetter": true, "replicates": [25.36499999999978, 25.960000000002765, 25.18000000000029, 23.955000000001746, 25.419999999998254, 24.44000000000233, 24.705000000001746, 24.279999999998836, 24.169999999998254, 24.779999999998836, 25.06500000000233, 25.085000000020955, 25.419999999983702, 25.64999999999418, 29.410000000003492, 25.05999999999767, 24.605000000010477, 24.739999999990687, 25.110000000015134, 24.35000000000582, 24.89000000001397, 23.98000000003958, 24.25, 25.164999999979045, 25.01500000001397], "unit": "ms", "name": "icon-open-DPI1.error.TART", "value": 24.95250000001397}, {"lowerIsBetter": true, "replicates": [1.0936146451617188, 1.1276558892240802, 1.0842767675346303, 1.097758122285207, 1.1094248552735035, 1.0743758385932003, 1.099111238547734, 1.0879779041938062, 1.0864238131721065, 1.128915728891597, 1.1053575424920945, 1.117489367434122, 1.0912780727980271, 1.1011262944766453, 1.1065005883574486, 1.1026805525734311, 1.114560773739448, 1.1120574233623652, 1.1231411626038041, 1.132157089663487, 1.098387142590114, 1.1108500584959984, 1.0940457261958212, 1.102622043518793, 1.1109903620985837], "unit": "ms", "name": "icon-close-DPI1.half.TART", "value": 1.1040190475327627}, {"lowerIsBetter": true, "replicates": [1.2022380869563032, 1.2374397513240274, 1.2069514760830131, 1.2268051214479094, 1.217956433496853, 1.2049465204278629, 1.217125042754026, 1.2128618614803446, 1.1996194371363011, 1.2205962633612144, 1.2286512159821976, 1.216730311955556, 1.2283485080301761, 1.2364179270351352, 1.229612164461433, 1.223418015241623, 1.2313931979725707, 1.2312342178821565, 1.2271965225040913, 1.2296828863024711, 1.2223022758071103, 1.2322037971019746, 1.2147875329350482, 1.2182770232063027, 1.2294870497286319], "unit": "ms", "name": "icon-close-DPI1.all.TART", "value": 1.2251115683447662}, {"lowerIsBetter": true, "replicates": [20.0049999999992, 17.719999999997526, 17.95499999999447, 19.395000000004075, 18.93499999999767, 18.845000000001164, 17.604999999995925, 17.93499999999767, 18.89000000001397, 18.279999999998836, 17.470000000001164, 18.52499999999418, 18.664999999979045, 18.864999999990687, 18.369999999995343, 17.5, 17.989999999990687, 19.22500000000582, 18.34499999997206, 18.760000000009313, 18.730000000010477, 19.289999999979045, 18.210000000020955, 17.834999999962747, 18.619999999995343], "unit": "ms", "name": "icon-close-DPI1.error.TART", "value": 18.44749999999476}, {"lowerIsBetter": true, "replicates": [1.567535764462239, 1.6207030709360686, 1.6345917439796556, 1.6436365451131547, 1.6106731014119253, 1.6634945154190064, 1.6044811954100926, 1.6338616206612386, 1.635343502944624, 1.734918850571362, 1.6723324181377024, 1.6703679250634236, 1.6776471138000488, 1.6639168960707529, 1.697518469656215, 1.6853659550348918, 1.6504935809544155, 1.6624248164040702, 1.6601702196257455, 1.7655108805858728, 1.6596381170409067, 1.6836184971574424, 1.6950281542890213, 1.6773521641026372, 1.6824832100799119], "unit": "ms", "name": "icon-open-DPI2.half.TART", "value": 1.6637057057448796}, {"lowerIsBetter": true, "replicates": [1.7816303736633725, 1.8255920606000082, 1.883842816176238, 1.8766177401823156, 1.827051226581846, 1.8955715921387744, 1.8359356321019233, 1.8817028376985998, 1.8470802410789158, 1.9262268841266632, 1.9162219807617646, 1.889215887034381, 1.920587386403765, 1.8734469676718992, 1.9077046477972572, 1.875488355755806, 1.8704766701249516, 1.9205512484507774, 1.8967677655043425, 1.9635469179887037, 1.8805847395868862, 1.889375176253142, 1.9352536382097187, 1.900188113326457, 1.9139439907289089], "unit": "ms", "name": "icon-open-DPI2.all.TART", "value": 1.8892955316437616}, {"lowerIsBetter": true, "replicates": [26.965000000001965, 26.044999999998254, 24.74500000000262, 25.599999999998545, 26.090000000003783, 24.429999999993015, 25.50999999999476, 26.220000000001164, 25.220000000001164, 24.68499999999767, 25.144999999989523, 25.625, 25.839999999996508, 25.214999999996508, 26.129999999975553, 25.39000000001397, 24.860000000015134, 27.654999999998836, 26.39999999999418, 25.64000000001397, 26.105000000010477, 25.364999999990687, 26.010000000009313, 24.989999999990687, 25.01500000001397], "unit": "ms", "name": "icon-open-DPI2.error.TART", "value": 25.554999999996653}, {"lowerIsBetter": true, "replicates": [0.9854005671973921, 1.0147139989493186, 1.0126660325025256, 1.0250462441317802, 0.9946964840436804, 1.0091865041799712, 0.9973943778153124, 1.0178523032011184, 0.9933955453593155, 1.0088530433805365, 1.0115477359085752, 1.0160714523834096, 1.0226947074442838, 1.0197552832881962, 1.0359024134065424, 1.0073929859244306, 1.0143737913223736, 1.0278728300971645, 0.9956796410782583, 1.0168428081169463, 1.0197179064286495, 1.0200543219009333, 1.0250564407458347, 1.020951442486417, 1.0288431053715092], "unit": "ms", "name": "icon-close-DPI2.half.TART", "value": 1.016457130250178}, {"lowerIsBetter": true, "replicates": [1.0706748140540943, 1.1066791645505212, 1.1029785842104343, 1.112268017096953, 1.1374846010707145, 1.1139906442542962, 1.0979298422689394, 1.1181274164806714, 1.1116397708519552, 1.1246406496117969, 1.1057738726495079, 1.1038261010096624, 1.1336442273524072, 1.1168576702135338, 1.1320961877357127, 1.1180534915292644, 1.0964214112191992, 1.1292890848916606, 1.1195631585164703, 1.134521497010086, 1.1341516641977196, 1.1200697080257835, 1.127473045106328, 1.1318476930741341, 1.1245032611790053], "unit": "ms", "name": "icon-close-DPI2.all.TART", "value": 1.1188452874985708}, {"lowerIsBetter": true, "replicates": [16.674999999999272, 17.029999999998836, 19.52499999999418, 18.3550000000032, 17.970000000001164, 19.669999999998254, 18.144999999989523, 19.379999999990105, 19.02500000000873, 18.654999999998836, 17.94000000000233, 17.45999999999185, 18.29000000000815, 18.154999999998836, 17.27499999999418, 18.395000000018626, 18.045000000012806, 19.520000000018626, 18.610000000015134, 19.95000000001164, 19.61500000001979, 17.619999999995343, 19.32500000001164, 18.994999999995343, 19.205000000016298], "unit": "ms", "name": "icon-close-DPI2.error.TART", "value": 18.50250000001688}, {"lowerIsBetter": true, "replicates": [0.9726404287234074, 0.9774727573839285, 1.0179032767768454, 1.0113658444923268, 0.9971792050476732, 0.9963185314474434, 0.9958683293441246, 1.002881890276204, 0.9896474755727328, 1.0175164843860425, 1.0023142508838487, 1.00719597702441, 1.0215427226724878, 1.0036899509637252, 0.9784750453496384, 1.0123123526573181, 1.0162364997361835, 1.010106073659763, 1.0067952876505644, 1.009391978644488, 0.9833952601139362, 1.0122685688629485, 1.065529748245522, 1.0173615368834712, 1.5432663790384928], "unit": "ms", "name": "iconFade-close-DPI2.half.TART", "value": 1.008293977834449}, {"lowerIsBetter": true, "replicates": [1.0015206560492516, 0.9999778099326105, 1.0299447139280031, 1.0217505222872685, 1.0120693613653597, 1.009508340131669, 1.0073556469116376, 1.0156454350229955, 1.014773485441916, 1.0232828606067774, 1.0071096268051114, 1.012256348910539, 1.035510388745202, 1.0169231808341748, 1.011877365215965, 1.0194600284622426, 1.0245431023547302, 1.0277562599096979, 1.035075195365482, 1.02938911318779, 1.0111410972864732, 1.0220098879776502, 1.0448095301341582, 1.0221698814839648, 1.2326123061634244], "unit": "ms", "name": "iconFade-close-DPI2.all.TART", "value": 1.0206052753747556}, {"lowerIsBetter": true, "replicates": [2.595000000001164, 2.724999999998545, 2.944999999999709, 2.9650000000037835, 2.7450000000098953, 3.209999999991851, 2.235000000000582, 2.7550000000046566, 2.3849999999947613, 2.3549999999959255, 2.695000000006985, 2.7700000000186265, 3.0099999999802094, 2.794999999983702, 2.8449999999720603, 2.5050000000046566, 2.6049999999813735, 4.929999999993015, 2.8699999999953434, 2.669999999983702, 2.6450000000186265, 3.0650000000023283, 2.9750000000349246, 3.0200000000186265, 2.9699999999720603], "unit": "ms", "name": "iconFade-close-DPI2.error.TART", "value": 2.782500000001164}, {"lowerIsBetter": true, "replicates": [1.5654134283194672, 1.5682829988969338, 1.64693888766425, 1.5913233495738408, 1.6153556903203328, 1.6349540982447879, 1.702165927957086, 1.7591691649321355, 1.5923333037389469, 1.608731801311175, 1.5866628150417381, 1.663302516937256, 1.7027701525127186, 1.6062785072459116, 1.6018145796325471, 1.6151476899782817, 1.627101157752561, 1.5627462155110128, 1.6612015247344971, 1.6036438743273418, 1.6152436948484845, 1.6205354055888217, 1.604593199160364, 1.640546353769974, 1.6059211774004831], "unit": "ms", "name": "iconFade-open-DPI2.half.TART", "value": 1.615195692413383}, {"lowerIsBetter": true, "replicates": [1.5198428630828857, 1.476510032068325, 1.572862385898023, 1.533580711797664, 1.542411967618576, 1.548329538067445, 1.6137850541207526, 1.6179396493567362, 1.5350484824494313, 1.5495536478360494, 1.512020882073935, 1.569559521771766, 1.6008732212732917, 1.5399833996564347, 1.5543561283747356, 1.5383781069203426, 1.5481032188365003, 1.5099338486597136, 1.5743153393268585, 1.5616078560784359, 1.5396985826113365, 1.568901166819886, 1.5443649986721821, 1.5598889061268544, 1.5076772678982129], "unit": "ms", "name": "iconFade-open-DPI2.all.TART", "value": 1.5482163784519727}, {"lowerIsBetter": true, "replicates": [2.484999999996944, 3.3100000000013097, 2.7450000000026193, 3.1149999999979627, 2.8650000000052387, 3.805000000007567, 2.345000000001164, 4.540000000008149, 3.3249999999970896, 2.4649999999965075, 2.875, 2.294999999983702, 3.5350000000034925, 2.6000000000058208, 3.0350000000034925, 3.764999999984866, 3.7449999999953434, 2.5450000000128057, 3.0499999999883585, 3.904999999998836, 2.415000000008149, 3.7100000000209548, 3.25, 2.445000000006985, 2.2000000000116415], "unit": "ms", "name": "iconFade-open-DPI2.error.TART", "value": 3.0424999999959255}, {"lowerIsBetter": true, "replicates": [1.9969801594471108, 1.708473587737364, 1.7122852942522835, 1.6925604343414307, 1.6902131792427837, 1.793281078338623, 1.8095832783728838, 1.746991910717704, 1.7495111497965725, 1.8549506914231084, 1.8405681224096389, 1.8668297298492924, 1.751402016842004, 1.9195919334888458, 3.028398225181981, 1.7734847142146184, 1.742082912530472, 1.7262417291527363, 1.719564829299699, 1.84741905568138, 1.7718955681874202, 1.7158993552712833, 1.8109272606670856, 1.765475975744652, 1.8335708909564548], "unit": "ms", "name": "newtab-open-preload-no.half.TART", "value": 1.768685771966036}, {"lowerIsBetter": true, "replicates": [3.358602705436784, 2.4614821672439575, 2.5192759065952117, 2.5009448314176024, 2.472233994649007, 2.608071094209498, 2.6262871464904474, 2.578714908659458, 2.5557836046313294, 2.641568522672264, 2.6726095906148353, 2.6346178140836893, 2.542457879179775, 2.651232473629037, 3.4995817815264068, 2.5430167370503494, 2.52354394513018, 2.5773862865567208, 2.5156683068649444, 2.712868921458721, 2.5965784324540033, 2.5137408165370716, 2.6387316803542937, 2.555194304131045, 2.6549140568246545], "unit": "ms", "name": "newtab-open-preload-no.all.TART", "value": 2.5780505976080894}, {"lowerIsBetter": true, "replicates": [30.049999999999272, 26.524999999997817, 29.82499999999709, 27.89999999999418, 27.435000000012224, 28.60000000000582, 27.769999999989523, 28.239999999990687, 29.639999999999418, 29.744999999995343, 27.045000000012806, 26.105000000010477, 27.39000000001397, 27.58499999999185, 36.79999999998836, 27.275000000023283, 27.985000000015134, 28.175000000017462, 26.919999999983702, 30.82500000001164, 27.470000000001164, 26.89499999996042, 29.110000000044238, 28.474999999976717, 28.050000000046566], "unit": "ms", "name": "newtab-open-preload-no.error.TART", "value": 27.942500000004657}, {"lowerIsBetter": true, "replicates": [1.7993714418262243, 1.7566906629186687, 1.7540667075099368, 1.828621650499011, 1.7985974494367838, 1.7411831083582407, 1.7978714667260647, 1.7906699125583356, 1.7851108642724844, 1.762153847651048, 1.7606178793040188, 1.7800657749176025, 1.835844388083806, 1.872236599845271, 1.7973434701561928, 1.7977814618498087, 1.8177971448749304, 1.7776200349514302, 1.7679893916303462, 1.7519663752931538, 1.7509772976239522, 1.7611414952711626, 1.7959217786788941, 1.8035893701016903, 1.7468755425828877], "unit": "ms", "name": "newtab-open-preload-yes.half.TART", "value": 1.7825883195950434}, {"lowerIsBetter": true, "replicates": [2.012548630672788, 1.9610893685084123, 1.991389391478151, 2.0220272569205817, 2.002690204884857, 1.9727136095364888, 2.030762345302762, 2.0441549893409485, 2.076372713811936, 1.98478114766668, 1.9933510639870813, 2.0071361463839614, 2.0529082255363464, 2.06123593521695, 2.000971079342009, 2.001943223644048, 2.026553536024619, 2.0225745127426356, 2.0064071607775986, 1.9704292888824757, 1.9902612495329952, 1.9755593321120093, 2.0174193213305136, 2.0044661913998425, 1.9645571190577287], "unit": "ms", "name": "newtab-open-preload-yes.all.TART", "value": 2.0035781981423497}, {"lowerIsBetter": true, "replicates": [33.595000000001164, 35.18000000000029, 35.020000000004075, 36.81500000000233, 36.445000000006985, 34.9600000000064, 39.294999999998254, 37.96499999999651, 37.68499999999767, 36.28500000000349, 37.43499999999767, 35.14499999998952, 37.25500000000466, 36.21499999999651, 34.10499999998137, 36.63000000000466, 37.93499999999767, 37.11999999999534, 37.014999999984866, 37.35000000000582, 37.52000000001863, 35.55999999999767, 36.83499999996275, 37.06500000000233, 35.76999999996042], "unit": "ms", "name": "newtab-open-preload-yes.error.TART", "value": 36.82499999998254}], "name": "tart", "value": 3.7311821164925796}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [3.2369822485207096, 2.0090532544378696, 2.0311242603550297, 2.0160059171597635, 2.002100591715976, 2.0151775147928994, 2.0081952662721894, 2.015739644970414, 2.008136094674556, 1.9976331360946746, 2.0135798816568045, 2.0162721893491122, 2.0179289940828404, 2.014792899408284, 1.9993195266272192, 2.0077218934911247, 2.0217751479289943, 2.022958579881657, 2.015443786982249, 2.0034911242603552, 2.0190532544378694, 2.002396449704142, 2.039497041420119, 2.0047041420118346, 2.016331360946745], "unit": "ms", "name": "tiled.html", "value": 2.014985207100592}, {"lowerIsBetter": true, "replicates": [3.0221301775147924, 3.0095266272189347, 2.9915976331360947, 3.0329289940828397, 2.999792899408284, 3.03008875739645, 2.9886390532544382, 3.0112130177514795, 3.0124260355029584, 3.0109467455621304, 3.01991124260355, 3.0190236686390532, 3.0189644970414204, 3.0046449704142013, 3.3404437869822483, 2.995976331360947, 3.0209467455621306, 3.0154733727810656, 3.014112426035503, 3.016923076923077, 2.982633136094674, 3.0269822485207096, 2.9965976331360946, 3.000473372781065, 2.9877810650887575], "unit": "ms", "name": "tiled-fixed.html", "value": 3.0132692307692306}, {"lowerIsBetter": true, "replicates": [2.323431952662722, 2.2708579881656807, 2.265147928994083, 2.2883431952662727, 2.2845857988165683, 2.257988165680473, 2.2823372781065085, 2.2736390532544384, 2.292899408284024, 2.2662130177514794, 2.2560059171597633, 2.283727810650888, 2.2609467455621304, 2.2879585798816566, 2.2889349112426034, 2.268136094674556, 2.2866863905325445, 2.272366863905326, 2.2879289940828405, 2.2903550295857986, 2.2748224852071006, 2.2912130177514793, 2.2890236686390537, 2.28801775147929, 2.2754142011834317], "unit": "ms", "name": "tiled-downscale.html", "value": 2.283032544378698}, {"lowerIsBetter": true, "replicates": [3.3888461538461545, 3.374378698224852, 3.367485207100591, 3.373727810650888, 3.371242603550296, 3.3771005917159767, 3.358165680473373, 3.3783431952662726, 3.364763313609468, 3.3839940828402364, 3.363994082840237, 3.3764792899408285, 3.3811834319526626, 3.3831065088757395, 3.388491124260355, 3.3623372781065095, 3.3760946745562133, 3.3573372781065087, 3.3825443786982246, 3.362899408284023, 3.3816272189349115, 3.3728994082840242, 3.369378698224852, 3.37008875739645, 3.3686982248520714], "unit": "ms", "name": "tiled-fixed-downscale.html", "value": 3.3744970414201187}, {"lowerIsBetter": true, "replicates": [3.2636046511627907, 3.2074418604651167, 3.237790697674419, 3.2060465116279064, 3.178081395348838, 3.1563372093023254, 3.1712790697674422, 3.171395348837209, 3.1610465116279065, 3.186569767441861, 3.1951744186046507, 3.1418023255813954, 3.1508139534883717, 3.1843604651162787, 3.1820348837209305, 3.2336046511627905, 3.2198837209302327, 3.201395348837209, 3.1899999999999995, 3.186860465116279, 3.218895348837209, 3.2234302325581408, 3.204825581395349, 3.1873837209302334, 3.134186046511628], "unit": "ms", "name": "iframe.svg", "value": 3.1867151162790703}, {"lowerIsBetter": true, "replicates": [2.964219653179191, 2.9887283236994215, 2.95835260115607, 2.9850867052023125, 2.9482947976878617, 2.9589884393063586, 2.966098265895954, 2.967543352601156, 7.692716763005781, 2.954306358381503, 2.9420520231213874, 2.9647687861271677, 2.9441618497109827, 2.961156069364162, 2.959450867052023, 2.9797976878612715, 2.966387283236994, 2.972601156069364, 2.9420520231213874, 2.953005780346821, 2.9708092485549136, 2.964364161849711, 2.962341040462428, 2.937976878612717, 2.934826589595376], "unit": "ms", "name": "reader.htm", "value": 2.961748554913295}], "name": "tscrollx", "value": 2.7724589189025113}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [5.184996593566168, 4.780596182459877, 5.1300488994235085, 5.017014759778976, 4.922820125307355, 5.044930208297003, 4.9018467380886985, 5.0550237610226585, 4.8625879401252385, 4.982119764600482, 4.990348185811724, 8.518968770901362, 5.251683439527239, 5.137875000635783, 4.999874909718831, 5.078761029243469, 5.003568632262094, 5.18075431244714, 4.910898072378976, 5.073382309504917, 5.068737853141058, 4.772879725410824, 5.003550262678237, 4.898847954613822, 4.836787223815918], "unit": "ms", "name": "1-customize-enter.half.TART", "value": 5.003559447470165}, {"lowerIsBetter": true, "replicates": [10.656390070915222, 9.778551121552786, 10.011320701459558, 10.205527895834388, 9.74655148528871, 9.800804444721766, 10.074763780686913, 9.820580130531674, 9.936366877904753, 9.853813841229393, 10.000399987872054, 11.989076402129196, 10.196667729354486, 9.969578805423918, 9.890832730701991, 9.947334596088954, 9.9167524207206, 9.9495381017526, 10.014092771018424, 9.943074146906534, 10.078510008207182, 10.092212019897088, 9.780589878559113, 10.06822644791952, 9.89339681951011], "unit": "ms", "name": "1-customize-enter.all.TART", "value": 9.948436348920776}, {"lowerIsBetter": true, "replicates": [319.3899999999994, 219.0599999999995, 218.9300000000003, 226.86999999999898, 218.0550000000003, 220.0199999999968, 221.63000000000466, 220.875, 215.875, 222.46500000000378, 218.625, 322.65000000000146, 226.74000000000524, 227.3399999999965, 224.2600000000093, 226.2800000000134, 225.27999999999884, 226.69000000000233, 219.13499999999476, 226.17999999999302, 222.1600000000035, 222.14500000000407, 219.2899999999936, 221.15499999999884, 214.02999999999884], "unit": "ms", "name": "1-customize-enter.error.TART", "value": 221.88750000000437}, {"lowerIsBetter": true, "replicates": [8.507266750702492, 8.136535433622507, 8.510072974058298, 8.19505025790288, 8.566874449069683, 8.875457717822147, 8.870547274748484, 8.433480739593506, 8.402347867305462, 8.3156539110037, 8.51317430459536, 8.4302022090325, 8.176854803011967, 8.466208989803608, 8.637588510146507, 8.679620999556322, 8.425653430131765, 8.36140818779285, 8.627220502266518, 8.153608514712406, 8.61321968298692, 8.614991765755873, 8.165364522200365, 8.492704519858727, 8.53142894231356], "unit": "ms", "name": "2-customize-exit.half.TART", "value": 8.479456754831167}, {"lowerIsBetter": true, "replicates": [9.693012859370258, 9.755973130464554, 10.187610622814724, 10.190243700572422, 10.116648530960083, 10.325595915317535, 10.464601853314567, 10.183880410875593, 10.282103170366849, 10.092138842173986, 10.394545579657835, 10.267097405024938, 9.464110931834659, 10.51237531970529, 10.62781063598745, 10.393461360650903, 10.139765109334673, 10.078578271184648, 10.282841151101248, 9.925602505604425, 10.337646820965935, 10.121278384753635, 9.892045617103577, 10.295712358811322, 10.33984905130723], "unit": "ms", "name": "2-customize-exit.all.TART", "value": 10.228670552798679}, {"lowerIsBetter": true, "replicates": [179.14999999999964, 171.68499999999767, 177.13999999999942, 176.85499999999956, 176.48500000000058, 177.65000000000146, 176.61000000000058, 176.70499999999447, 170.625, 173.3050000000003, 174.11000000000058, 179.43999999999505, 170.9300000000003, 180.6600000000035, 181.9499999999971, 173.4200000000128, 175.0500000000029, 173.99000000000524, 180.02000000000407, 177.34999999999127, 171.9100000000035, 175.13499999999476, 175.93499999999767, 170.09500000000116, 172.2899999999936], "unit": "ms", "name": "2-customize-exit.error.TART", "value": 175.53499999999622}, {"lowerIsBetter": true, "replicates": [10.108486080169678, 10.966673946380615, 9.72869500246915, 9.503884142095393, 10.15030279159546, 9.600336334922098, 9.60895876450972, 9.64055096019398, 9.884038058194248, 10.37217445373535, 10.245571613311768, 10.012449169158936, 10.120812034606933, 10.498854160308838, 10.213009071350097, 9.76147408918901, 9.670537341724742, 10.126648902893066, 9.859427452087402, 10.671229076385497, 9.910812811418014, 10.159249973297118, 10.23136396408081, 9.825775666670365, 9.749256220730869], "unit": "ms", "name": "3-customize-enter-css.half.TART", "value": 9.961630990288475}, {"lowerIsBetter": true, "replicates": [13.540162897109985, 14.039241740578099, 12.848125276111421, 12.716963949657622, 13.577294731140137, 12.885774839492072, 12.686555135817756, 12.9475614911034, 13.011944543747674, 13.65593671798706, 13.512687826156617, 13.533730840682983, 13.500073719024659, 14.10571309139854, 13.853597831726074, 12.914775394258045, 12.855823426019578, 13.5821524143219, 13.185102510452271, 14.186715377004523, 13.15787763595581, 13.71559019088745, 13.437252283096313, 12.960397856576103, 12.84819830031622], "unit": "ms", "name": "3-customize-enter-css.all.TART", "value": 13.311177396774292}, {"lowerIsBetter": true, "replicates": [70.55500000000029, 66.47500000000218, 69.41500000000087, 66.89500000000044, 71.40499999999884, 70.62999999999738, 66.31999999999971, 71.62000000000262, 72.99500000000262, 72.84499999999389, 69.96499999999651, 70.33000000000175, 69.79499999999825, 67.83000000000175, 76.75, 71.10499999999593, 69.77999999999884, 71.34500000000116, 63.595000000001164, 69.2100000000064, 62.875, 74.04499999999825, 68.46499999999651, 71.97499999999127, 69.59500000000116], "unit": "ms", "name": "3-customize-enter-css.error.TART", "value": 69.87999999999738}], "name": "cart", "value": 23.395974207204777}]} 20:38:26 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": "496.45"}, "tart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[293,53,25]]", "result": "3.73"}, "cart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[309,53,25]]", "result": "23.39"}, "tsvgx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[281,53,25]]", "result": "380.45"}}} 20:38:26 INFO - Return code: 0 20:38:26 INFO - Validating PERFHERDER_DATA against C:\slave\test-pgo\build\tests\talos\treeherder-schemas\performance-artifact.json 20:38:26 INFO - # TBPL SUCCESS # 20:38:26 INFO - Running post-action listener: _resource_record_post_action 20:38:26 INFO - Running post-run listener: _resource_record_post_run 20:38:27 INFO - Total resource usage - Wall time: 1131s; CPU: 8.0%; Read bytes: 69260800; Write bytes: 507733504; Read time: 820510; Write time: 8929440 20:38:27 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 20480; Read time: 0; Write time: 90 20:38:27 INFO - run-tests - Wall time: 1130s; CPU: 8.0%; Read bytes: 69211648; Write bytes: 480574464; Read time: 819540; Write time: 8753210 20:38:27 INFO - Running post-run listener: _upload_blobber_files 20:38:27 INFO - Blob upload gear active. 20:38:27 INFO - Preparing to upload files from C:\slave\test-pgo\build\blobber_upload_dir. 20:38:27 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 20:38:27 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'] 20:38:27 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 20:38:28 INFO - (blobuploader) - INFO - Open directory for files ... 20:38:28 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log ... 20:38:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:38:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:38:28 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_errorsummary.log: uploaded 20:38:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:38:28 INFO - (blobuploader) - INFO - Done attempting. 20:38:28 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log ... 20:38:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:38:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:38:34 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_raw.log: uploaded 20:38:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:38:34 INFO - (blobuploader) - INFO - Done attempting. 20:38:34 INFO - (blobuploader) - INFO - Iteration through files over. 20:38:34 INFO - Return code: 0 20:38:34 INFO - rmtree: C:\slave\test-pgo\build\uploaded_files.json 20:38:34 INFO - Using _rmtree_windows ... 20:38:34 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build\\uploaded_files.json',), kwargs: {}, attempt #1 20:38:34 INFO - Setting buildbot property blobber_files to {"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83e75c9d3ff063fbe12d046617b0ec51a2cb508c9d14f37f1928d178c10f602ae8b16dcc44387d3a7c408126a79ebcefcf171e608986b0333a7c3aa66c81349f", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7c352f63c46679984a25d7506c1737a1fdd08a724501ca5cabb3c8084e6f8e4818166e7bb1edb48134156fd4ae07cdf3954a9e6a092be755dc1e071d7e170bee"} 20:38:34 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test-pgo\properties\blobber_files 20:38:34 INFO - Writing to file C:\slave\test-pgo\properties\blobber_files 20:38:34 INFO - Contents: 20:38:34 INFO - blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83e75c9d3ff063fbe12d046617b0ec51a2cb508c9d14f37f1928d178c10f602ae8b16dcc44387d3a7c408126a79ebcefcf171e608986b0333a7c3aa66c81349f", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7c352f63c46679984a25d7506c1737a1fdd08a724501ca5cabb3c8084e6f8e4818166e7bb1edb48134156fd4ae07cdf3954a9e6a092be755dc1e071d7e170bee"} 20:38:34 INFO - Running post-run listener: copy_logs_to_upload_dir 20:38:34 INFO - Copying logs to upload dir... 20:38:34 INFO - mkdir: C:\slave\test-pgo\build\upload\logs 20:38:34 INFO - Copying logs to upload dir... 20:38:34 INFO - Using _rmtree_windows ... 20:38:34 INFO - Using _rmtree_windows ... 20:38:34 INFO - Using _rmtree_windows ... 20:38:34 INFO - Using _rmtree_windows ... 20:38:34 INFO - Using _rmtree_windows ... 20:38:34 INFO - Using _rmtree_windows ... 20:38:34 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1413.056000 ========= master_lag: 0.28 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 33 secs) (at 2016-03-13 20:38:34.761003) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-13 20:38:34.761914) ========= '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-178 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-178 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-178 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/83e75c9d3ff063fbe12d046617b0ec51a2cb508c9d14f37f1928d178c10f602ae8b16dcc44387d3a7c408126a79ebcefcf171e608986b0333a7c3aa66c81349f", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7c352f63c46679984a25d7506c1737a1fdd08a724501ca5cabb3c8084e6f8e4818166e7bb1edb48134156fd4ae07cdf3954a9e6a092be755dc1e071d7e170bee"} build_url:https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/artifacts/public/build/firefox-46.0.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/A4MwCuQZQamSvcsjse58GQ/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/83e75c9d3ff063fbe12d046617b0ec51a2cb508c9d14f37f1928d178c10f602ae8b16dcc44387d3a7c408126a79ebcefcf171e608986b0333a7c3aa66c81349f", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7c352f63c46679984a25d7506c1737a1fdd08a724501ca5cabb3c8084e6f8e4818166e7bb1edb48134156fd4ae07cdf3954a9e6a092be755dc1e071d7e170bee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-13 20:38:34.884407) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 20:38:34.884735) ========= '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-178 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-178 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-178 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-13 20:38:35.003387) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-13 20:38:35.003701) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-13 20:38:35.584241) ========= ========= Total master_lag: 0.46 =========