builder: mozilla-beta_win7-ix_test-svgr-pgo slave: t-w732-ix-004 starttime: 1457976991.58 results: success (0) buildid: 20160314064228 builduid: 06c82640c2434c48a27e85c75778ec03 revision: 5cdbd68d927e3b99837a165a730fcc2dc58ab4bb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:31.575712) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:31.576184) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:31.576528) ========= '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-004 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-004 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-004 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.607000 basedir: 'C:\\slave\\test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:32.217314) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:32.217624) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:32.400339) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:32.400757) ========= '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-004 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-004 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-004 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.501000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:32.919230) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:32.919552) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:32.919944) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:32.920250) ========= '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-004 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-004 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-004 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 --10:36:33-- 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% 7.53 MB/s 10:36:33 (7.53 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.836000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:33.774968) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-03-14 10:36:33.775300) ========= '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-004 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-004 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-004 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=12.001000 ========= master_lag: 0.16 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-03-14 10:36:45.933488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-14 10:36:45.933888) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5cdbd68d927e3b99837a165a730fcc2dc58ab4bb --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 5cdbd68d927e3b99837a165a730fcc2dc58ab4bb --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-004 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-004 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-004 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-03-14 10:36:45,915 truncating revision to first 12 chars 2016-03-14 10:36:45,915 Setting DEBUG logging. 2016-03-14 10:36:45,915 attempt 1/10 2016-03-14 10:36:45,915 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5cdbd68d927e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-14 10:36:46,385 unpacking tar archive at: mozilla-beta-5cdbd68d927e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.183000 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-14 10:36:47.186577) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:47.186931) ========= 'echo' u'5cdbd68d927e3b99837a165a730fcc2dc58ab4bb' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'5cdbd68d927e3b99837a165a730fcc2dc58ab4bb'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-004 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-004 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-004 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 5cdbd68d927e3b99837a165a730fcc2dc58ab4bb program finished with exit code 0 elapsedTime=0.101000 script_repo_revision: '5cdbd68d927e3b99837a165a730fcc2dc58ab4bb' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:47.305034) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:47.305408) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-14 10:36:47.321710) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 43 secs) (at 2016-03-14 10:36:47.322098) ========= '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-004 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-004 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-004 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 10:36:47 INFO - MultiFileLogger online at 20160314 10:36:47 in C:\slave\test-pgo 10:36:47 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 10:36:47 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 10:36:47 INFO - {'append_to_log': False, 10:36:47 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 10:36:47 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:36:47 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 10:36:47 INFO - 'branch': 'Mozilla-Beta', 10:36:47 INFO - 'buildbot_json_path': 'buildprops.json', 10:36:47 INFO - 'config_files': ('talos/windows_config.py',), 10:36:47 INFO - 'default_actions': ('clobber', 10:36:47 INFO - 'read-buildbot-config', 10:36:47 INFO - 'download-and-extract', 10:36:47 INFO - 'populate-webroot', 10:36:47 INFO - 'create-virtualenv', 10:36:47 INFO - 'install', 10:36:47 INFO - 'run-tests'), 10:36:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:36:47 INFO - 'download_minidump_stackwalk': True, 10:36:47 INFO - 'download_symbols': 'ondemand', 10:36:47 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 10:36:47 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 10:36:47 INFO - 'hg': 'c:/mozilla-build/hg/hg', 10:36:47 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 10:36:47 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 10:36:47 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 10:36:47 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 10:36:47 INFO - 'C:/mozilla-build/tooltool.py'), 10:36:47 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 10:36:47 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 10:36:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:36:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:36:47 INFO - 'installer_path': 'installer.exe', 10:36:47 INFO - 'log_level': 'info', 10:36:47 INFO - 'log_name': 'talos', 10:36:47 INFO - 'log_to_console': True, 10:36:47 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 10:36:47 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 10:36:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 10:36:47 INFO - 'opt_config_files': (), 10:36:47 INFO - 'pip_index': False, 10:36:47 INFO - 'sps_profile': False, 10:36:47 INFO - 'sps_profile_interval': 0, 10:36:47 INFO - 'suite': 'svgr', 10:36:47 INFO - 'system_bits': '32', 10:36:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:36:47 INFO - 'title': 'T-W732-IX-004', 10:36:47 INFO - 'use_talos_json': True, 10:36:47 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 10:36:47 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 10:36:47 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 10:36:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:36:47 INFO - 'work_dir': 'build'} 10:36:47 INFO - ##### 10:36:47 INFO - ##### Running clobber step. 10:36:47 INFO - ##### 10:36:47 INFO - Running pre-action listener: _resource_record_pre_action 10:36:47 INFO - Running main action method: clobber 10:36:47 INFO - rmtree: C:\slave\test-pgo\build 10:36:47 INFO - Using _rmtree_windows ... 10:36:47 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 10:39:31 INFO - Running post-action listener: _resource_record_post_action 10:39:31 INFO - ##### 10:39:31 INFO - ##### Running read-buildbot-config step. 10:39:31 INFO - ##### 10:39:31 INFO - Running pre-action listener: _resource_record_pre_action 10:39:31 INFO - Running main action method: read_buildbot_config 10:39:31 INFO - Using buildbot properties: 10:39:31 INFO - { 10:39:31 INFO - "project": "", 10:39:31 INFO - "product": "firefox", 10:39:31 INFO - "script_repo_revision": "production", 10:39:31 INFO - "builddir": "mozilla-beta_win7-ix_test-svgr", 10:39:31 INFO - "repository": "", 10:39:31 INFO - "buildername": "Windows 7 32-bit mozilla-beta pgo talos svgr", 10:39:31 INFO - "buildid": "20160314064228", 10:39:31 INFO - "pgo_build": "True", 10:39:31 INFO - "basedir": "C:\\slave\\test-pgo", 10:39:31 INFO - "buildnumber": 8, 10:39:31 INFO - "slavename": "t-w732-ix-004", 10:39:31 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 10:39:31 INFO - "platform": "win7-ix", 10:39:31 INFO - "scheduler": "tests-mozilla-beta-win32-pgo-talos", 10:39:31 INFO - "branch": "mozilla-beta", 10:39:31 INFO - "repo_path": "releases/mozilla-beta", 10:39:31 INFO - "revision": "5cdbd68d927e3b99837a165a730fcc2dc58ab4bb", 10:39:31 INFO - "stage_platform": "win32-pgo", 10:39:31 INFO - "builduid": "06c82640c2434c48a27e85c75778ec03", 10:39:31 INFO - "slavebuilddir": "test" 10:39:31 INFO - } 10:39:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.zip. 10:39:31 INFO - Running post-action listener: _resource_record_post_action 10:39:31 INFO - ##### 10:39:31 INFO - ##### Running download-and-extract step. 10:39:31 INFO - ##### 10:39:31 INFO - Running pre-action listener: _resource_record_pre_action 10:39:31 INFO - Running main action method: download_and_extract 10:39:31 INFO - mkdir: C:\slave\test-pgo\build\tests 10:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:39:31 INFO - https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 10:39:31 INFO - trying https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/test_packages.json 10:39:31 INFO - Downloading https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 10:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 10:39:32 INFO - Downloaded 1267 bytes. 10:39:32 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 10:39:32 INFO - Using the following test package requirements: 10:39:32 INFO - {u'common': [u'firefox-46.0.en-US.win32.common.tests.zip'], 10:39:32 INFO - u'cppunittest': [u'firefox-46.0.en-US.win32.common.tests.zip', 10:39:32 INFO - u'firefox-46.0.en-US.win32.cppunittest.tests.zip'], 10:39:32 INFO - u'gtest': [u'firefox-46.0.en-US.win32.common.tests.zip'], 10:39:32 INFO - u'jittest': [u'firefox-46.0.en-US.win32.common.tests.zip', 10:39:32 INFO - u'jsshell-win32.zip'], 10:39:32 INFO - u'mochitest': [u'firefox-46.0.en-US.win32.common.tests.zip', 10:39:32 INFO - u'firefox-46.0.en-US.win32.mochitest.tests.zip'], 10:39:32 INFO - u'mozbase': [u'firefox-46.0.en-US.win32.common.tests.zip'], 10:39:32 INFO - u'reftest': [u'firefox-46.0.en-US.win32.common.tests.zip', 10:39:32 INFO - u'firefox-46.0.en-US.win32.reftest.tests.zip'], 10:39:32 INFO - u'talos': [u'firefox-46.0.en-US.win32.common.tests.zip', 10:39:32 INFO - u'firefox-46.0.en-US.win32.talos.tests.zip'], 10:39:32 INFO - u'web-platform': [u'firefox-46.0.en-US.win32.common.tests.zip', 10:39:32 INFO - u'firefox-46.0.en-US.win32.web-platform.tests.zip'], 10:39:32 INFO - u'webapprt': [u'firefox-46.0.en-US.win32.common.tests.zip'], 10:39:32 INFO - u'xpcshell': [u'firefox-46.0.en-US.win32.common.tests.zip', 10:39:32 INFO - u'firefox-46.0.en-US.win32.xpcshell.tests.zip']} 10:39:32 INFO - Downloading packages: [u'firefox-46.0.en-US.win32.common.tests.zip'] for test suite category: common 10:39:32 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')]} 10:39:32 INFO - https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 10:39:32 INFO - trying https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip 10:39:32 INFO - Downloading https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/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 10:39:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/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 10:39:34 INFO - Downloaded 17906912 bytes. 10:39:34 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'] 10:39:34 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 10:40:06 INFO - Return code: 0 10:40:06 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 10:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:06 INFO - https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 10:40:06 INFO - trying https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.common.tests.zip 10:40:06 INFO - Downloading https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/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 10:40:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/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 10:40:07 INFO - Downloaded 17906912 bytes. 10:40:07 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'] 10:40:07 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 10:40:29 INFO - Return code: 0 10:40:29 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')]} 10:40:29 INFO - https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 10:40:29 INFO - trying https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.talos.tests.zip 10:40:29 INFO - Downloading https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/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 10:40:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/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 10:40:33 INFO - Downloaded 10801536 bytes. 10:40:33 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'] 10:40:33 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 10:40:36 INFO - Return code: 0 10:40: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')]} 10:40:36 INFO - https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.zip matches https://queue.taskcluster.net 10:40:36 INFO - trying https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.zip 10:40:37 INFO - Downloading https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.zip to C:\slave\test-pgo\build\firefox-46.0.en-US.win32.zip 10:40:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/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 10:40:40 INFO - Downloaded 54969235 bytes. 10:40:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.zip 10:40:40 INFO - mkdir: C:\slave\test-pgo\properties 10:40:40 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 10:40:40 INFO - Writing to file C:\slave\test-pgo\properties\build_url 10:40:40 INFO - Contents: 10:40:40 INFO - build_url:https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.zip 10:40:41 INFO - Running post-action listener: _resource_record_post_action 10:40:41 INFO - Running post-action listener: set_extra_try_arguments 10:40:41 INFO - ##### 10:40:41 INFO - ##### Running populate-webroot step. 10:40:41 INFO - ##### 10:40:41 INFO - Running pre-action listener: _resource_record_pre_action 10:40:41 INFO - Running main action method: populate_webroot 10:40:41 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 10:40:41 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 10:40:41 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 10:40:41 INFO - u'--tppagecycles', 10:40:41 INFO - u'7'], 10:40:41 INFO - u'tests': [u'tsvgm']}}, 10:40:41 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 10:40:41 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 10:40:41 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 10:40:41 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 10:40:41 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 10:40:41 INFO - u'tests': [u'dromaeo_css', 10:40:41 INFO - u'kraken', 10:40:41 INFO - u'v8_7']}, 10:40:41 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:41 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:40:41 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:41 INFO - u'talos_options': [u'--e10s'], 10:40:41 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:40:41 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:41 INFO - u'tests': [u'damp', u'tps']}, 10:40:41 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:41 INFO - u'talos_options': [u'--e10s'], 10:40:41 INFO - u'tests': [u'damp', u'tps']}, 10:40:41 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 10:40:41 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 10:40:41 INFO - u'other': {u'tests': [u'a11yr', 10:40:41 INFO - u'ts_paint', 10:40:41 INFO - u'tpaint', 10:40:41 INFO - u'sessionrestore', 10:40:41 INFO - u'sessionrestore_no_auto_restore']}, 10:40:41 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 10:40:41 INFO - u'tests': [u'a11yr', 10:40:41 INFO - u'ts_paint', 10:40:41 INFO - u'tpaint', 10:40:41 INFO - u'sessionrestore', 10:40:41 INFO - u'sessionrestore_no_auto_restore']}, 10:40:41 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 10:40:41 INFO - u'tests': [u'a11yr', 10:40:41 INFO - u'ts_paint', 10:40:41 INFO - u'tpaint', 10:40:41 INFO - u'sessionrestore', 10:40:41 INFO - u'sessionrestore_no_auto_restore']}, 10:40:41 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 10:40:41 INFO - u'tests': [u'a11yr', 10:40:41 INFO - u'ts_paint', 10:40:41 INFO - u'tpaint', 10:40:41 INFO - u'sessionrestore', 10:40:41 INFO - u'sessionrestore_no_auto_restore']}, 10:40:41 INFO - u'other_l64': {u'tests': [u'a11yr', 10:40:41 INFO - u'ts_paint', 10:40:41 INFO - u'tpaint', 10:40:41 INFO - u'sessionrestore', 10:40:41 INFO - u'sessionrestore_no_auto_restore']}, 10:40:41 INFO - u'other_nol64': {u'tests': [u'a11yr', 10:40:41 INFO - u'ts_paint', 10:40:41 INFO - u'tpaint', 10:40:41 INFO - u'sessionrestore', 10:40:41 INFO - u'sessionrestore_no_auto_restore']}, 10:40:41 INFO - u'svgr': {u'tests': [u'tsvgx', 10:40:41 INFO - u'tsvgr_opacity', 10:40:41 INFO - u'tart', 10:40:41 INFO - u'tscrollx', 10:40:41 INFO - u'cart']}, 10:40:41 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 10:40:41 INFO - u'tests': [u'tsvgx', 10:40:41 INFO - u'tsvgr_opacity', 10:40:41 INFO - u'tart', 10:40:41 INFO - u'tscrollx', 10:40:41 INFO - u'cart']}, 10:40:41 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:41 INFO - u'tests': [u'tp5o']}, 10:40:41 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:41 INFO - u'talos_options': [u'--e10s'], 10:40:41 INFO - u'tests': [u'tp5o']}, 10:40:41 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:41 INFO - u'talos_options': [u'--xperf_path', 10:40:41 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:40:41 INFO - u'tests': [u'tp5n']}, 10:40:41 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:40:41 INFO - u'talos_options': [u'--e10s', 10:40:41 INFO - u'--xperf_path', 10:40:41 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:40:41 INFO - u'tests': [u'tp5n']}}, 10:40:41 INFO - u'talos.zip': {u'path': u'', 10:40:41 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:40:41 INFO - Running post-action listener: _resource_record_post_action 10:40:41 INFO - ##### 10:40:41 INFO - ##### Running create-virtualenv step. 10:40:41 INFO - ##### 10:40:41 INFO - Running pre-action listener: _resource_record_pre_action 10:40:41 INFO - Running main action method: create_virtualenv 10:40:41 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 10:40:41 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 10:40:41 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 10:40:41 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'! 10:40:41 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 10:40:41 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 10:40:47 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 10:40:51 INFO - Installing distribute......................................................................................................................................................................................done. 10:40:53 INFO - Return code: 0 10:40:53 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 10:40:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ADEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-004', 'USERDOMAIN': 'T-W732-IX-004', '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-004', '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 10:40:53 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 10:40:53 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:40:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:40:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:40:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:40:53 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:40:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:40:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:40:53 INFO - 'DCLOCATION': 'SCL3', 10:40:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:40:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:40:53 INFO - 'HOMEDRIVE': 'C:', 10:40:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:40:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:40:53 INFO - 'KTS_VERSION': '1.19c', 10:40:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:40:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:40:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:40:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:40:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:53 INFO - 'MOZ_NO_REMOTE': '1', 10:40:53 INFO - 'NO_EM_RESTART': '1', 10:40:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:40:53 INFO - 'OS': 'Windows_NT', 10:40:53 INFO - 'OURDRIVE': 'C:', 10:40:53 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:40:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:40:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:40:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:40:53 INFO - 'PROCESSOR_LEVEL': '6', 10:40:53 INFO - 'PROCESSOR_REVISION': '1e05', 10:40:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:40:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:40:53 INFO - 'PROMPT': '$P$G', 10:40:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:40:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:40:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:40:53 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:40:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:40:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:40:53 INFO - 'SYSTEMDRIVE': 'C:', 10:40:53 INFO - 'SYSTEMROOT': 'C:\\windows', 10:40:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:40:53 INFO - 'TEST1': 'testie', 10:40:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:40:53 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:40:53 INFO - 'USERNAME': 'cltbld', 10:40:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:40:53 INFO - 'WINDIR': 'C:\\windows', 10:40:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:40:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:40:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:40:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 10:40:54 INFO - Downloading/unpacking pip>=1.5 10:40:58 INFO - Running setup.py egg_info for package pip 10:40:58 INFO - warning: no files found matching 'pip\cacert.pem' 10:40:58 INFO - warning: no files found matching '*.html' under directory 'docs' 10:40:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:40:58 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:40:58 INFO - Installing collected packages: pip 10:40:58 INFO - Found existing installation: pip 0.8.2 10:40:58 INFO - Uninstalling pip: 10:40:58 INFO - Successfully uninstalled pip 10:40:58 INFO - Running setup.py install for pip 10:40:58 INFO - warning: no files found matching 'pip\cacert.pem' 10:40:58 INFO - warning: no files found matching '*.html' under directory 'docs' 10:40:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:40:58 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:40:58 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:40:58 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 10:40:58 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:40:58 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 10:40:58 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:40:58 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 10:40:58 INFO - Successfully installed pip 10:40:58 INFO - Cleaning up... 10:40:58 INFO - Return code: 0 10:40:58 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 10:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:40:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:40:58 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 0x017E76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ADEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-004', 'USERDOMAIN': 'T-W732-IX-004', '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-004', '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 10:40:58 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 10:40:58 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 10:40:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:40:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:40:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:40:58 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:40:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:40:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:40:58 INFO - 'DCLOCATION': 'SCL3', 10:40:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:40:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:40:58 INFO - 'HOMEDRIVE': 'C:', 10:40:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:40:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:40:58 INFO - 'KTS_VERSION': '1.19c', 10:40:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:40:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:40:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:40:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:40:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:58 INFO - 'MOZ_NO_REMOTE': '1', 10:40:58 INFO - 'NO_EM_RESTART': '1', 10:40:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:40:58 INFO - 'OS': 'Windows_NT', 10:40:58 INFO - 'OURDRIVE': 'C:', 10:40:58 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;', 10:40:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:40:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:40:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:40:58 INFO - 'PROCESSOR_LEVEL': '6', 10:40:58 INFO - 'PROCESSOR_REVISION': '1e05', 10:40:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:40:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:40:58 INFO - 'PROMPT': '$P$G', 10:40:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:40:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:40:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:40:58 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:40:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:40:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:40:58 INFO - 'SYSTEMDRIVE': 'C:', 10:40:58 INFO - 'SYSTEMROOT': 'C:\\windows', 10:40:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:40:58 INFO - 'TEST1': 'testie', 10:40:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:40:58 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:40:58 INFO - 'USERNAME': 'cltbld', 10:40:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:40:58 INFO - 'WINDIR': 'C:\\windows', 10:40:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:40:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:40:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:41:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:00 INFO - Downloading/unpacking psutil>=0.7.1 10:41:00 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 10:41:00 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 10:41:01 INFO - Installing collected packages: psutil 10:41:01 INFO - Successfully installed psutil 10:41:01 INFO - Cleaning up... 10:41:01 INFO - Return code: 0 10:41:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 10:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:01 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 0x017E76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ADEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-004', 'USERDOMAIN': 'T-W732-IX-004', '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-004', '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 10:41:01 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 10:41:01 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 10:41:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:41:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:41:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:41:01 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:41:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:41:01 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:41:01 INFO - 'DCLOCATION': 'SCL3', 10:41:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:41:01 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:41:01 INFO - 'HOMEDRIVE': 'C:', 10:41:01 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:41:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:41:01 INFO - 'KTS_VERSION': '1.19c', 10:41:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:41:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:41:01 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:41:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:01 INFO - 'MOZ_NO_REMOTE': '1', 10:41:01 INFO - 'NO_EM_RESTART': '1', 10:41:01 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:41:01 INFO - 'OS': 'Windows_NT', 10:41:01 INFO - 'OURDRIVE': 'C:', 10:41:01 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;', 10:41:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:41:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:41:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:41:01 INFO - 'PROCESSOR_LEVEL': '6', 10:41:01 INFO - 'PROCESSOR_REVISION': '1e05', 10:41:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:41:01 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:41:01 INFO - 'PROMPT': '$P$G', 10:41:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:41:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:41:01 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:41:01 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:41:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:41:01 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:41:01 INFO - 'SYSTEMDRIVE': 'C:', 10:41:01 INFO - 'SYSTEMROOT': 'C:\\windows', 10:41:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:01 INFO - 'TEST1': 'testie', 10:41:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:01 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:41:01 INFO - 'USERNAME': 'cltbld', 10:41:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:41:01 INFO - 'WINDIR': 'C:\\windows', 10:41:01 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:41:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:41:02 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 10:41:02 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 10:41:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:41:03 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 10:41:03 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) 10:41:03 INFO - Installing collected packages: mozsystemmonitor 10:41:03 INFO - Running setup.py install for mozsystemmonitor 10:41:03 INFO - Successfully installed mozsystemmonitor 10:41:03 INFO - Cleaning up... 10:41:03 INFO - Return code: 0 10:41:03 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 10:41:03 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')]} 10:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:03 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')]} 10:41:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:03 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 0x017E76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ADEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-004', 'USERDOMAIN': 'T-W732-IX-004', '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-004', '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 10:41: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', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build 10:41: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 blobuploader==1.2.4 10:41:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:41:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:41:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:41:03 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:41:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:41:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:41:03 INFO - 'DCLOCATION': 'SCL3', 10:41:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:41:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:41:03 INFO - 'HOMEDRIVE': 'C:', 10:41:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:41:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:41:03 INFO - 'KTS_VERSION': '1.19c', 10:41:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:41:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:41:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:41:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:03 INFO - 'MOZ_NO_REMOTE': '1', 10:41:03 INFO - 'NO_EM_RESTART': '1', 10:41:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:41:03 INFO - 'OS': 'Windows_NT', 10:41:03 INFO - 'OURDRIVE': 'C:', 10:41: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;', 10:41:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:41:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:41:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:41:03 INFO - 'PROCESSOR_LEVEL': '6', 10:41:03 INFO - 'PROCESSOR_REVISION': '1e05', 10:41:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:41:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:41:03 INFO - 'PROMPT': '$P$G', 10:41:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:41:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:41:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:41:03 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:41:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:41:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:41:03 INFO - 'SYSTEMDRIVE': 'C:', 10:41:03 INFO - 'SYSTEMROOT': 'C:\\windows', 10:41:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:03 INFO - 'TEST1': 'testie', 10:41:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:03 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:41:03 INFO - 'USERNAME': 'cltbld', 10:41:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:41:03 INFO - 'WINDIR': 'C:\\windows', 10:41:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:41:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:41:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:04 INFO - Downloading/unpacking blobuploader==1.2.4 10:41:04 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 10:41:04 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 10:41:05 INFO - Downloading blobuploader-1.2.4.tar.gz 10:41:05 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 10:41:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:41: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 10:41: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 10:41:05 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 10:41:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:41: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 10:41: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 10:41:05 INFO - Downloading docopt-0.6.1.tar.gz 10:41:05 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 10:41:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:41:06 INFO - Running setup.py install for blobuploader 10:41:06 INFO - Running setup.py install for requests 10:41:07 INFO - Running setup.py install for docopt 10:41:07 INFO - Successfully installed blobuploader requests docopt 10:41:07 INFO - Cleaning up... 10:41:08 INFO - Return code: 0 10:41:08 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 10:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:08 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 0x017E76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ADEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-004', 'USERDOMAIN': 'T-W732-IX-004', '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-004', '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 10:41:08 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 10:41:08 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 10:41:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:41:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:41:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:41:08 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:41:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:41:08 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:41:08 INFO - 'DCLOCATION': 'SCL3', 10:41:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:41:08 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:41:08 INFO - 'HOMEDRIVE': 'C:', 10:41:08 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:41:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:41:08 INFO - 'KTS_VERSION': '1.19c', 10:41:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:41:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:41:08 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:41:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:08 INFO - 'MOZ_NO_REMOTE': '1', 10:41:08 INFO - 'NO_EM_RESTART': '1', 10:41:08 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:41:08 INFO - 'OS': 'Windows_NT', 10:41:08 INFO - 'OURDRIVE': 'C:', 10:41:08 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:41:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:41:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:41:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:41:08 INFO - 'PROCESSOR_LEVEL': '6', 10:41:08 INFO - 'PROCESSOR_REVISION': '1e05', 10:41:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:41:08 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:41:08 INFO - 'PROMPT': '$P$G', 10:41:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:41:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:41:08 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:41:08 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:41:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:41:08 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:41:08 INFO - 'SYSTEMDRIVE': 'C:', 10:41:08 INFO - 'SYSTEMROOT': 'C:\\windows', 10:41:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:08 INFO - 'TEST1': 'testie', 10:41:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:08 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:41:08 INFO - 'USERNAME': 'cltbld', 10:41:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:41:08 INFO - 'WINDIR': 'C:\\windows', 10:41:08 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:41:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 10:41:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zj6dmi-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 10:41:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 10:41:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qpsjj8-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 10:41:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 10:41:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-smjcmr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 10:41:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 10:41:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0yyzad-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 10:41:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 10:41:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-todjqb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 10:41:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 10:41:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1atlnh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 10:41:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 10:41:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b7rsei-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 10:41:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 10:41:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1pqfv_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 10:41:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 10:41:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e0ml9o-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 10:41:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 10:41:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xgb2yg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 10:41:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 10:41:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mk0xua-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 10:41:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 10:41:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-phviio-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 10:41:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 10:41:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n5rinv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 10:41:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 10:41:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jflhr1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 10:41:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 10:41:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vgquj1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:41:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 10:41:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vcocxm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 10:41:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 10:41:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wjcduu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 10:41:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:41:11 INFO - Running setup.py install for manifestparser 10:41:11 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:11 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:11 INFO - Running setup.py install for mozcrash 10:41:12 INFO - Running setup.py install for mozdebug 10:41:13 INFO - Running setup.py install for mozdevice 10:41:13 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:13 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:13 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:13 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:13 INFO - Running setup.py install for mozfile 10:41:14 INFO - Running setup.py install for mozhttpd 10:41:14 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Running setup.py install for mozinfo 10:41:14 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Running setup.py install for mozInstall 10:41:14 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:14 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:15 INFO - Running setup.py install for mozleak 10:41:15 INFO - Running setup.py install for mozlog 10:41:15 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:15 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:16 INFO - Running setup.py install for moznetwork 10:41:16 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:16 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:16 INFO - Running setup.py install for mozprocess 10:41:17 INFO - Running setup.py install for mozprofile 10:41:17 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:17 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:17 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:17 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:17 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:17 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:17 INFO - Running setup.py install for mozrunner 10:41:18 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:18 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:18 INFO - Running setup.py install for mozscreenshot 10:41:18 INFO - Running setup.py install for moztest 10:41:19 INFO - Running setup.py install for mozversion 10:41:19 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:19 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:41:19 INFO - Cleaning up... 10:41:19 INFO - Return code: 0 10:41:19 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 10:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:19 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 0x017E76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ADEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-004', 'USERDOMAIN': 'T-W732-IX-004', '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-004', '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 10:41:19 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 10:41:19 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 10:41:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:41:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:41:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:41:19 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:41:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:41:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:41:19 INFO - 'DCLOCATION': 'SCL3', 10:41:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:41:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:41:19 INFO - 'HOMEDRIVE': 'C:', 10:41:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:41:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:41:19 INFO - 'KTS_VERSION': '1.19c', 10:41:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:41:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:41:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:41:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:19 INFO - 'MOZ_NO_REMOTE': '1', 10:41:19 INFO - 'NO_EM_RESTART': '1', 10:41:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:41:19 INFO - 'OS': 'Windows_NT', 10:41:19 INFO - 'OURDRIVE': 'C:', 10:41:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:41:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:41:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:41:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:41:19 INFO - 'PROCESSOR_LEVEL': '6', 10:41:19 INFO - 'PROCESSOR_REVISION': '1e05', 10:41:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:41:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:41:19 INFO - 'PROMPT': '$P$G', 10:41:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:41:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:41:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:41:19 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:41:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:41:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:41:19 INFO - 'SYSTEMDRIVE': 'C:', 10:41:19 INFO - 'SYSTEMROOT': 'C:\\windows', 10:41:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:19 INFO - 'TEST1': 'testie', 10:41:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:19 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:41:19 INFO - 'USERNAME': 'cltbld', 10:41:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:41:19 INFO - 'WINDIR': 'C:\\windows', 10:41:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:41:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 10:41:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p8l2gv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 10:41:20 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)) 10:41:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 10:41:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9su5m2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 10:41:20 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)) 10:41:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 10:41:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rgg3xx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 10:41:20 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)) 10:41:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 10:41:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kuqiqv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 10:41:20 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)) 10:41:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 10:41:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cq_ccb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 10:41:20 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)) 10:41:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 10:41:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xgzc3r-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 10:41:20 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)) 10:41:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 10:41:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-920q9g-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 10:41:21 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)) 10:41:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 10:41:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yihgv5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 10:41:21 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)) 10:41:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 10:41:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cnesed-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 10:41:21 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)) 10:41:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 10:41:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qljzmj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 10:41:21 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)) 10:41:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 10:41:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1acbi0-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 10:41:21 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)) 10:41:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 10:41:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xa9zqa-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 10:41:21 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)) 10:41:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 10:41:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kvfwxv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 10:41:22 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)) 10:41:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 10:41:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w1dslk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 10:41:22 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)) 10:41:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 10:41:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3zhzub-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:41:22 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)) 10:41:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 10:41:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4aigir-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 10:41:22 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)) 10:41:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 10:41:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nw_mo9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 10:41:22 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)) 10:41:22 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)) 10:41:22 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)) 10:41:22 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)) 10:41:22 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)) 10:41:22 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)) 10:41:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 10:41:22 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 10:41:22 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 10:41:23 INFO - Downloading blessings-1.6.tar.gz 10:41:23 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 10:41:24 INFO - Installing collected packages: blessings 10:41:24 INFO - Running setup.py install for blessings 10:41:24 INFO - Successfully installed blessings 10:41:24 INFO - Cleaning up... 10:41:27 INFO - Return code: 0 10:41:27 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 10:41:27 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 10:41:27 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 10:41:27 INFO - Reading from file tmpfile_stdout 10:41:27 INFO - Using _rmtree_windows ... 10:41:27 INFO - Using _rmtree_windows ... 10:41:27 INFO - Current package versions: 10:41:27 INFO - blessings == 1.6 10:41:27 INFO - blobuploader == 1.2.4 10:41:27 INFO - docopt == 0.6.1 10:41:27 INFO - manifestparser == 1.1 10:41:27 INFO - mozInstall == 1.12 10:41:27 INFO - mozcrash == 0.16 10:41:27 INFO - mozdebug == 0.1 10:41:27 INFO - mozdevice == 0.48 10:41:27 INFO - mozfile == 1.2 10:41:27 INFO - mozhttpd == 0.7 10:41:27 INFO - mozinfo == 0.9 10:41:27 INFO - mozleak == 0.1 10:41:27 INFO - mozlog == 3.1 10:41:27 INFO - moznetwork == 0.27 10:41:27 INFO - mozprocess == 0.22 10:41:27 INFO - mozprofile == 0.28 10:41:27 INFO - mozrunner == 6.11 10:41:27 INFO - mozscreenshot == 0.1 10:41:27 INFO - mozsystemmonitor == 0.0 10:41:27 INFO - moztest == 0.7 10:41:27 INFO - mozversion == 1.4 10:41:27 INFO - psutil == 3.1.1 10:41:27 INFO - requests == 1.2.3 10:41:27 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 10:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:27 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 0x017E76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ADEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-004', 'USERDOMAIN': 'T-W732-IX-004', '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-004', '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 10:41:27 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 10:41:27 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 10:41:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:41:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:41:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:41:27 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:41:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:41:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:41:27 INFO - 'DCLOCATION': 'SCL3', 10:41:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:41:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:41:27 INFO - 'HOMEDRIVE': 'C:', 10:41:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:41:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:41:27 INFO - 'KTS_VERSION': '1.19c', 10:41:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:41:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:41:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:41:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:27 INFO - 'MOZ_NO_REMOTE': '1', 10:41:27 INFO - 'NO_EM_RESTART': '1', 10:41:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:41:27 INFO - 'OS': 'Windows_NT', 10:41:27 INFO - 'OURDRIVE': 'C:', 10:41:27 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;', 10:41:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:41:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:41:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:41:27 INFO - 'PROCESSOR_LEVEL': '6', 10:41:27 INFO - 'PROCESSOR_REVISION': '1e05', 10:41:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:41:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:41:27 INFO - 'PROMPT': '$P$G', 10:41:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:41:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:41:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:41:27 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:41:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:41:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:41:27 INFO - 'SYSTEMDRIVE': 'C:', 10:41:27 INFO - 'SYSTEMROOT': 'C:\\windows', 10:41:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:27 INFO - 'TEST1': 'testie', 10:41:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:27 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:41:27 INFO - 'USERNAME': 'cltbld', 10:41:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:41:27 INFO - 'WINDIR': 'C:\\windows', 10:41:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:41:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41: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)) 10:41: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)) 10:41: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)) 10:41: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)) 10:41: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)) 10:41: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)) 10:41: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)) 10:41: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)) 10:41: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)) 10:41: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)) 10:41: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)) 10:41:28 INFO - Cleaning up... 10:41:28 INFO - Return code: 0 10:41:28 INFO - Installing jsonschema into virtualenv C:\slave\test-pgo\build/venv 10:41: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')]} 10:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41: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')]} 10:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41: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 0x017E76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ADEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-004', 'USERDOMAIN': 'T-W732-IX-004', '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-004', '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 10:41: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 10:41: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 10:41:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:41:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:41:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:41:28 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:41:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:41:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:41:28 INFO - 'DCLOCATION': 'SCL3', 10:41:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:41:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:41:28 INFO - 'HOMEDRIVE': 'C:', 10:41:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:41:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:41:28 INFO - 'KTS_VERSION': '1.19c', 10:41:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:41:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:41:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:41:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:28 INFO - 'MOZ_NO_REMOTE': '1', 10:41:28 INFO - 'NO_EM_RESTART': '1', 10:41:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:41:28 INFO - 'OS': 'Windows_NT', 10:41:28 INFO - 'OURDRIVE': 'C:', 10:41: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;', 10:41:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:41:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:41:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:41:28 INFO - 'PROCESSOR_LEVEL': '6', 10:41:28 INFO - 'PROCESSOR_REVISION': '1e05', 10:41:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:41:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:41:28 INFO - 'PROMPT': '$P$G', 10:41:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:41:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:41:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:41:28 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:41:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:41:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:41:28 INFO - 'SYSTEMDRIVE': 'C:', 10:41:28 INFO - 'SYSTEMROOT': 'C:\\windows', 10:41:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:28 INFO - 'TEST1': 'testie', 10:41:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:28 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:41:28 INFO - 'USERNAME': 'cltbld', 10:41:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:41:28 INFO - 'WINDIR': 'C:\\windows', 10:41:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:41:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:28 INFO - Downloading/unpacking jsonschema 10:41: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 10:41: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 10:41:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:41:29 INFO - Downloading/unpacking functools32 (from jsonschema) 10:41: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 10:41: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 10:41:29 INFO - Downloading functools32-3.2.3-2.tar.gz 10:41:29 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\functools32\setup.py) egg_info for package functools32 10:41:29 INFO - warning: no files found matching '*.txt' 10:41:29 INFO - no previously-included directories found matching 'build' 10:41:29 INFO - no previously-included directories found matching 'dist' 10:41:29 INFO - no previously-included directories found matching '.git*' 10:41:29 INFO - Installing collected packages: jsonschema, functools32 10:41:29 INFO - Running setup.py install for functools32 10:41:29 INFO - warning: no files found matching '*.txt' 10:41:29 INFO - no previously-included directories found matching 'build' 10:41:29 INFO - no previously-included directories found matching 'dist' 10:41:29 INFO - no previously-included directories found matching '.git*' 10:41:29 INFO - Successfully installed jsonschema functools32 10:41:29 INFO - Cleaning up... 10:41:30 INFO - Return code: 0 10:41:30 INFO - Installing flake8 into virtualenv C:\slave\test-pgo\build/venv 10:41: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')]} 10:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41: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')]} 10:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41: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 0x017E76B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ADEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB6E0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-004', 'USERDOMAIN': 'T-W732-IX-004', '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-004', '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 10:41: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 10:41: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 10:41:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:41:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:41:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:41:30 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:41:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:41:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:41:30 INFO - 'DCLOCATION': 'SCL3', 10:41:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:41:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:41:30 INFO - 'HOMEDRIVE': 'C:', 10:41:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:41:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:41:30 INFO - 'KTS_VERSION': '1.19c', 10:41:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:41:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:41:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:41:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:30 INFO - 'MOZ_NO_REMOTE': '1', 10:41:30 INFO - 'NO_EM_RESTART': '1', 10:41:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:41:30 INFO - 'OS': 'Windows_NT', 10:41:30 INFO - 'OURDRIVE': 'C:', 10:41:30 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;', 10:41:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:41:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:41:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:41:30 INFO - 'PROCESSOR_LEVEL': '6', 10:41:30 INFO - 'PROCESSOR_REVISION': '1e05', 10:41:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:41:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:41:30 INFO - 'PROMPT': '$P$G', 10:41:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:41:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:41:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:41:30 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:41:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:41:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:41:30 INFO - 'SYSTEMDRIVE': 'C:', 10:41:30 INFO - 'SYSTEMROOT': 'C:\\windows', 10:41:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:30 INFO - 'TEST1': 'testie', 10:41:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:30 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:41:30 INFO - 'USERNAME': 'cltbld', 10:41:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:41:30 INFO - 'WINDIR': 'C:\\windows', 10:41:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:41:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:30 INFO - Downloading/unpacking flake8 10:41:30 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 10:41:30 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 10:41:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:41:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:41: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 10:41: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 10:41:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:41:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:41: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 10:41: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 10:41:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:41: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 10:41: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 10:41:32 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\pep8\setup.py) egg_info for package pep8 10:41:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:41:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:41:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:41:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:41:32 INFO - no previously-included directories found matching 'docs\_build' 10:41:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:41:32 INFO - Running setup.py install for pep8 10:41:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:41:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:41:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:41:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:41:32 INFO - no previously-included directories found matching 'docs\_build' 10:41:32 INFO - Installing pep8-script.py script to C:\slave\test-pgo\build\venv\Scripts 10:41:32 INFO - Installing pep8.exe script to C:\slave\test-pgo\build\venv\Scripts 10:41:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:41:32 INFO - Cleaning up... 10:41:33 INFO - Return code: 0 10:41:33 INFO - Running post-action listener: _resource_record_post_action 10:41:33 INFO - Running post-action listener: _start_resource_monitoring 10:41:33 INFO - Starting resource monitoring. 10:41:33 INFO - ##### 10:41:33 INFO - ##### Running install step. 10:41:33 INFO - ##### 10:41:33 INFO - Running pre-action listener: _resource_record_pre_action 10:41:33 INFO - Running main action method: install 10:41:33 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 10:41:33 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 10:41:33 INFO - Reading from file tmpfile_stdout 10:41:33 INFO - Using _rmtree_windows ... 10:41:33 INFO - Using _rmtree_windows ... 10:41:33 INFO - Detecting whether we're running mozinstall >=1.0... 10:41:33 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 10:41:33 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 10:41:34 INFO - Reading from file tmpfile_stdout 10:41:34 INFO - Output received: 10:41:34 INFO - Usage: mozinstall-script.py [options] installer 10:41:34 INFO - Options: 10:41:34 INFO - -h, --help show this help message and exit 10:41:34 INFO - -d DEST, --destination=DEST 10:41:34 INFO - Directory to install application into. [default: 10:41:34 INFO - "C:\slave\test-pgo"] 10:41:34 INFO - --app=APP Application being installed. [default: firefox] 10:41:34 INFO - Using _rmtree_windows ... 10:41:34 INFO - Using _rmtree_windows ... 10:41:34 INFO - mkdir: C:\slave\test-pgo\build\application 10:41: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'] 10:41: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 10:41:35 INFO - Reading from file tmpfile_stdout 10:41:35 INFO - Output received: 10:41:35 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 10:41:35 INFO - Using _rmtree_windows ... 10:41:35 INFO - Using _rmtree_windows ... 10:41:35 INFO - Running post-action listener: _resource_record_post_action 10:41:35 INFO - ##### 10:41:35 INFO - ##### Running run-tests step. 10:41:35 INFO - ##### 10:41:35 INFO - Running pre-action listener: _resource_record_pre_action 10:41:35 INFO - Running main action method: run_tests 10:41:35 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 10:41:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 10:41:35 INFO - Python 2.7.3 10:41:35 INFO - Return code: 0 10:41:35 INFO - grabbing minidump binary from tooltool 10:41: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')]} 10:41: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 0x01228BF0>, '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 0x0124A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019295C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 10:41: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 10:41: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 10:41:35 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 10:41:36 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmphwm5sl 10:41:36 INFO - INFO - File integrity verified, renaming tmphwm5sl to win32-minidump_stackwalk.exe 10:41:36 INFO - Return code: 0 10:41:36 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 10:41:36 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 10:41:36 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 10:41:36 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 10:41:36 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 10:41:36 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\flake8', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos'] 10:41:36 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\flake8 C:\slave\test-pgo\build\tests\talos\talos 10:41:38 INFO - Return code: 0 10:41: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/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-004', '--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 10:41: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/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-004 --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 10:41:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:41:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:41:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:41:38 INFO - 'COMPUTERNAME': 'T-W732-IX-004', 10:41:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:41:38 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:41:38 INFO - 'DCLOCATION': 'SCL3', 10:41:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:41:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:41:38 INFO - 'HOMEDRIVE': 'C:', 10:41:38 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:41:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:41:38 INFO - 'KTS_VERSION': '1.19c', 10:41:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:41:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-004', 10:41:38 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 10:41:38 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 10:41:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:41:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:38 INFO - 'MOZ_NO_REMOTE': '1', 10:41:38 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 10:41:38 INFO - 'NO_EM_RESTART': '1', 10:41:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:41:38 INFO - 'OS': 'Windows_NT', 10:41:38 INFO - 'OURDRIVE': 'C:', 10:41: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;', 10:41:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:41:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:41:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:41:38 INFO - 'PROCESSOR_LEVEL': '6', 10:41:38 INFO - 'PROCESSOR_REVISION': '1e05', 10:41:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:41:38 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:41:38 INFO - 'PROMPT': '$P$G', 10:41:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 10:41:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:41:38 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:41:38 INFO - 'PWD': 'C:\\slave\\test-pgo', 10:41:38 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 10:41:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:41:38 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:41:38 INFO - 'SYSTEMDRIVE': 'C:', 10:41:38 INFO - 'SYSTEMROOT': 'C:\\windows', 10:41:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:38 INFO - 'TEST1': 'testie', 10:41:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:41:38 INFO - 'USERDOMAIN': 'T-W732-IX-004', 10:41:38 INFO - 'USERNAME': 'cltbld', 10:41:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:41:38 INFO - 'WINDIR': 'C:\\windows', 10:41:38 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:41:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:41:38 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/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-004', '--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 10:41:39 INFO - mozversion application_buildid: 20160314064228 10:41:39 INFO - mozversion application_changeset: 5cdbd68d927e3b99837a165a730fcc2dc58ab4bb 10:41:39 INFO - mozversion application_display_name: Firefox 10:41:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:41:39 INFO - mozversion application_name: Firefox 10:41:39 INFO - mozversion application_remotingname: firefox 10:41:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:41:39 INFO - mozversion application_vendor: Mozilla 10:41:39 INFO - mozversion application_version: 46.0 10:41:39 INFO - mozversion platform_buildid: 20160314064228 10:41:39 INFO - mozversion platform_changeset: 5cdbd68d927e3b99837a165a730fcc2dc58ab4bb 10:41:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:41:39 INFO - mozversion platform_version: 46.0 10:41:39 INFO - using testdate: 1457977299 10:41:39 INFO - actual date: 1457977299 10:41:39 INFO - starting webserver on 'localhost:49660' 10:41:39 INFO - SUITE-START | Running 5 tests 10:41:39 INFO - TEST-START | tsvgx 10:41:39 INFO - operating with platform_type : w7_ 10:41:39 INFO - Initialising browser for tsvgx test... 10:41:39 INFO - TEST-INFO | started process 1480 (C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpy2_nhl\profile http://localhost:49660/getInfo.html) 10:41:47 INFO - PROCESS | 1480 | __metrics Screen width/height:1600/1200 10:41:47 INFO - PROCESS | 1480 | colorDepth:24 10:41:47 INFO - PROCESS | 1480 | Browser inner width/height: 1010/674 10:41:47 INFO - PROCESS | 1480 | __metrics 10:41:47 INFO - PROCESS | 1480 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:41:48 INFO - PROCESS | 1480 | console.error: 10:41:48 INFO - PROCESS | 1480 | Message: Error: Connection closed before committing the transaction. 10:41:48 INFO - PROCESS | 1480 | Stack: 10:41:48 INFO - PROCESS | 1480 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:933:23 10:41:48 INFO - PROCESS | 1480 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7 10:41:48 INFO - PROCESS | 1480 | this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:746:11 10:41:48 INFO - PROCESS | 1480 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 10:41:48 INFO - PROCESS | 1480 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 10:41:48 INFO - PROCESS | 1480 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5 10:41:48 INFO - PROCESS | 1480 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 10:41:48 INFO - PROCESS | 1480 | Barrier/this.client.addBlocker/promisetsvgx: 381.89 11:00:25 INFO - RETURN: tsvgr_opacity: 495.21 11:00:25 INFO - RETURN: tart: 3.70 11:00:25 INFO - RETURN: tscrollx: 2.77 11:00:25 INFO - RETURN: cart: 23.25 11:00:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [247.0, 215.0, 209.0, 211.0, 210.0, 212.0, 209.0, 212.0, 211.0, 211.0, 209.0, 211.0, 212.0, 211.0, 213.0, 216.0, 209.0, 210.0, 207.0, 210.0, 207.0, 211.0, 208.0, 213.0, 207.0], "unit": "ms", "name": "gearflowers.svg", "value": 211.0}, {"lowerIsBetter": true, "replicates": [65.0, 41.0, 40.0, 41.0, 40.0, 40.0, 40.0, 41.0, 41.0, 41.0, 41.0, 40.0, 40.0, 40.0, 41.0, 41.0, 41.0, 40.0, 41.0, 40.0, 41.0, 40.0, 41.0, 41.0, 40.0], "unit": "ms", "name": "composite-scale.svg", "value": 41.0}, {"lowerIsBetter": true, "replicates": [127.0, 123.0, 125.0, 122.0, 122.0, 122.0, 122.0, 122.0, 121.0, 123.0, 121.0, 123.0, 121.0, 129.0, 122.0, 125.0, 121.0, 125.0, 121.0, 121.0, 120.0, 122.0, 121.0, 121.0, 121.0], "unit": "ms", "name": "composite-scale-opacity.svg", "value": 121.5}, {"lowerIsBetter": true, "replicates": [176.0, 166.0, 166.0, 167.0, 165.0, 166.0, 173.0, 166.0, 166.0, 171.0, 167.0, 166.0, 173.0, 170.0, 167.0, 166.0, 167.0, 167.0, 171.0, 173.0, 184.0, 170.0, 167.0, 165.0, 169.0], "unit": "ms", "name": "composite-scale-rotate.svg", "value": 167.0}, {"lowerIsBetter": true, "replicates": [245.0, 239.0, 240.0, 238.0, 237.0, 238.0, 237.0, 239.0, 243.0, 237.0, 237.0, 238.0, 246.0, 238.0, 238.0, 239.0, 238.0, 246.0, 245.0, 239.0, 239.0, 239.0, 238.0, 247.0, 239.0], "unit": "ms", "name": "composite-scale-rotate-opacity.svg", "value": 239.0}, {"lowerIsBetter": true, "replicates": [460.0, 433.0, 425.0, 432.0, 432.0, 435.0, 433.0, 433.0, 432.0, 431.0, 434.0, 432.0, 430.0, 433.0, 425.0, 430.0, 432.0, 432.0, 432.0, 434.0, 434.0, 438.0, 431.0, 426.0, 433.0], "unit": "ms", "name": "hixie-001.xml", "value": 432.0}, {"lowerIsBetter": true, "replicates": [434.0, 429.0, 433.0, 432.0, 427.0, 437.0, 438.0, 436.0, 433.0, 428.0, 431.0, 435.0, 425.0, 435.0, 435.0, 433.0, 434.0, 439.0, 429.0, 432.0, 434.0, 430.0, 428.0, 434.0, 425.0], "unit": "ms", "name": "hixie-002.xml", "value": 433.5}, {"lowerIsBetter": true, "replicates": [267.0, 213.0, 212.0, 2717.0, 213.0, 218.0, 212.0, 213.0, 214.0, 211.0, 214.0, 211.0, 212.0, 215.0, 216.0, 208.0, 214.0, 212.0, 210.0, 217.0, 247.0, 212.0, 215.0, 207.0, 210.0], "unit": "ms", "name": "hixie-003.xml", "value": 212.5}, {"lowerIsBetter": true, "replicates": [1115.0, 1097.0, 1119.0, 1110.0, 1109.0, 1119.0, 1118.0, 1083.0, 1106.0, 1095.0, 1120.0, 1094.0, 1115.0, 1108.0, 1115.0, 1096.0, 1117.0, 1123.0, 1092.0, 1112.0, 1121.0, 1108.0, 1111.0, 1125.0, 1948.0], "unit": "ms", "name": "hixie-004.xml", "value": 1113.5}, {"lowerIsBetter": true, "replicates": [2462.0, 2459.0, 2467.0, 2463.0, 2459.0, 2454.0, 2449.0, 2452.0, 2467.0, 2455.0, 2450.0, 2450.0, 2463.0, 2462.0, 2456.0, 2456.0, 2455.0, 2448.0, 2464.0, 2461.0, 2463.0, 2460.0, 2454.0, 2490.0, 2449.0], "unit": "ms", "name": "hixie-005.xml", "value": 2455.5}, {"lowerIsBetter": true, "replicates": [3730.0, 3729.0, 3770.0, 3740.0, 3725.0, 3727.0, 3732.0, 3716.0, 3715.0, 3733.0, 3766.0, 3816.0, 3732.0, 3731.0, 3718.0, 3730.0, 3727.0, 3738.0, 3732.0, 3748.0, 3738.0, 3733.0, 3750.0, 3748.0, 3721.0], "unit": "ms", "name": "hixie-006.xml", "value": 3732.0}, {"lowerIsBetter": true, "replicates": [690.0, 563.0, 553.0, 550.0, 544.0, 553.0, 544.0, 564.0, 545.0, 550.0, 545.0, 549.0, 553.0, 549.0, 549.0, 549.0, 544.0, 545.0, 549.0, 550.0, 542.0, 548.0, 546.0, 545.0, 559.0], "unit": "ms", "name": "hixie-007.xml", "value": 549.0}], "name": "tsvgx", "value": 381.89342026588076}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [752.0, 729.0, 67.0, 709.0, 706.0, 67.0, 702.0, 65.0, 712.0, 65.0, 703.0, 706.0, 67.0, 719.0, 66.0, 720.0, 707.0, 65.0, 721.0, 65.0, 703.0, 67.0, 704.0, 65.0, 703.0], "unit": "ms", "name": "big-optimizable-group-opacity-2500.svg", "value": 702.5}, {"lowerIsBetter": true, "replicates": [317.0, 345.0, 347.0, 357.0, 346.0, 386.0, 353.0, 348.0, 345.0, 347.0, 391.0, 352.0, 353.0, 348.0, 348.0, 350.0, 354.0, 354.0, 350.0, 348.0, 346.0, 347.0, 348.0, 347.0, 354.0], "unit": "ms", "name": "small-group-opacity-2500.svg", "value": 349.0}], "name": "tsvgr_opacity", "value": 495.21064075652356}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1.5951801816078082, 1.5722693253207851, 1.559154632886251, 1.4997914007731847, 1.5719268579740782, 1.5295144570501227, 1.5367087411880493, 1.524957023168865, 1.5483156792322794, 1.502000624483282, 1.5384444014231364, 1.5459912125269573, 1.6168234662877188, 1.5466312074661255, 1.5404411872227988, 1.552611322402954, 1.5720565770123456, 1.5619221732423112, 1.5439381170272828, 1.5659852672267605, 1.508747992577491, 1.5440917205810547, 1.513809770732731, 1.554320071194623, 1.5671372655275706], "unit": "ms", "name": "simple-open-DPI1.half.TART", "value": 1.5463112099965413}, {"lowerIsBetter": true, "replicates": [1.7318669354834524, 1.7087234930704105, 1.7415321643660668, 1.6948744344711304, 1.7063075356386803, 1.680336525898106, 1.708027655646305, 1.7196695732103813, 1.714854047105119, 1.8172562553527507, 1.713761731579497, 1.7259685433640772, 1.7574622713857226, 1.6949691335360209, 1.714841080678476, 1.7259633216728159, 1.7234921532423317, 1.7473703959892537, 1.7373825719911757, 1.7605662362443075, 1.694720827738444, 1.7132298430880986, 1.702046075923331, 1.7665155140890016, 1.7233066875107435], "unit": "ms", "name": "simple-open-DPI1.all.TART", "value": 1.7172618101577501}, {"lowerIsBetter": true, "replicates": [24.88500000000022, 24.834999999999127, 26.31500000000233, 24.525000000001455, 22.860000000000582, 24.179999999993015, 24.75999999999476, 24.785000000003492, 24.07499999999709, 36.50500000000466, 23.954999999987194, 24.05999999999767, 23.41500000000815, 24.60999999998603, 24.199999999982538, 24.70999999999185, 23.75, 23.744999999995343, 23.97500000000582, 24.445000000006985, 24.5, 24.010000000009313, 23.824999999953434, 24.68499999999767, 23.73499999998603], "unit": "ms", "name": "simple-open-DPI1.error.TART", "value": 24.189999999987776}, {"lowerIsBetter": true, "replicates": [1.1459298198766048, 1.0853413337806486, 1.0789410293659316, 1.1177734067139116, 1.1203532641373792, 1.1396033769786948, 1.1451161981809257, 1.1522666007280349, 1.1470742048603473, 1.0879785947079927, 1.186264791439489, 1.1480398968894883, 1.1387745496070032, 1.15608352124691, 1.105073036466326, 1.1137455283449247, 1.097111507824489, 1.14892575174275, 1.1457815465360586, 1.1277424097061157, 1.1180604696273804, 1.125483165666895, 1.1387593380295404, 1.1313220595612246, 1.1394550995071335], "unit": "ms", "name": "simple-close-DPI1.half.TART", "value": 1.1350406987953825}, {"lowerIsBetter": true, "replicates": [1.2102811996319993, 1.1684078267560556, 1.1690872004972055, 1.2743021196719864, 1.284432600046459, 1.2280484872247706, 1.2962001934253349, 1.2995612843239561, 1.3149017518566501, 1.2056456177399075, 1.3331631252674456, 1.3191137903445476, 1.3111030738199911, 1.2963953538143889, 1.2897550634881283, 1.2090336876129038, 1.2052135874729346, 1.292027017269185, 1.3049045574474842, 1.3073855796281029, 1.3041312151554367, 1.2701835144369096, 1.300366037069483, 1.2903406840783578, 1.2959316949895088], "unit": "ms", "name": "simple-close-DPI1.all.TART", "value": 1.293979356129347}, {"lowerIsBetter": true, "replicates": [15.694999999999709, 15.674999999999272, 15.875, 15.819999999999709, 16.659999999996217, 17.09000000001106, 17.664999999993597, 17.19000000000233, 17.29000000000815, 17.0, 16.464999999996508, 16.739999999990687, 16.489999999990687, 16.364999999990687, 15.020000000018626, 15.644999999989523, 15.934999999997672, 16.894999999989523, 17.919999999983702, 17.07500000001164, 16.679999999993015, 17.854999999981374, 17.130000000004657, 16.60999999998603, 16.294999999983702], "unit": "ms", "name": "simple-close-DPI1.error.TART", "value": 16.669999999994616}, {"lowerIsBetter": true, "replicates": [1.5727259710028365, 1.677319872206536, 1.5655908955110085, 1.5801254135288605, 1.5672721814464878, 1.5968055970048252, 1.5826766229655644, 1.8121824618428946, 1.5722537588428807, 1.553200117746989, 1.621033982491829, 1.577816174454885, 1.5764537657776925, 1.5800044209989783, 1.6166688038243189, 1.6964805073597853, 1.5755540503037941, 1.5922607526387254, 1.5716570019721985, 1.5717659769831478, 1.5668985650346086, 1.5883418844170767, 1.5769271948566175, 1.5608168759861507, 1.5807566381480596], "unit": "ms", "name": "icon-open-DPI1.half.TART", "value": 1.5789102977269316}, {"lowerIsBetter": true, "replicates": [1.8044359371171776, 1.8556402582321725, 1.7498862291204518, 1.7857334160468947, 1.7736593059726529, 1.8339213115705861, 1.817716989346913, 1.9131639828359275, 1.8066200854930472, 1.771630137330956, 1.8398089201554009, 1.802924449562181, 1.8108708662646158, 1.8003263405874266, 1.8837785250949164, 1.8578517384772752, 1.7848058787869736, 1.8149330139160156, 1.7853196758619496, 1.7857887661540426, 1.7535911412074647, 1.7919368601181138, 1.7847680206030188, 1.773866067399512, 1.8103347754647545], "unit": "ms", "name": "icon-open-DPI1.all.TART", "value": 1.8016253950748038}, {"lowerIsBetter": true, "replicates": [24.875, 24.794999999998254, 24.255000000004657, 23.994999999995343, 24.260000000002037, 25.244999999995343, 24.985000000000582, 24.729999999995925, 25.02499999999418, 25.39999999999418, 24.179999999993015, 24.494999999995343, 23.920000000012806, 24.295000000012806, 28.510000000009313, 24.904999999998836, 23.85999999998603, 24.56500000000233, 23.964999999996508, 25.714999999996508, 24.54999999998836, 24.854999999981374, 23.880000000004657, 24.09000000002561, 25.554999999993015], "unit": "ms", "name": "icon-open-DPI1.error.TART", "value": 24.557499999995343}, {"lowerIsBetter": true, "replicates": [1.0722076931485423, 1.1010026750110444, 1.0875475063638866, 1.096376425311679, 1.0824580064443785, 1.0893914154115714, 1.1099941776348994, 1.0853051115881722, 1.092945193907, 1.1125529150550182, 1.096365453515734, 1.070877919042552, 1.0823575191408674, 1.0819196884877214, 1.100607702845619, 1.102659342970167, 1.0930756071828447, 1.1014781032289778, 1.0962228275480725, 1.104198982602074, 1.0880908926702895, 1.0975211012931096, 1.0880655427024049, 1.0910867947452474, 1.0943362780337065], "unit": "ms", "name": "icon-close-DPI1.half.TART", "value": 1.0937059426082756}, {"lowerIsBetter": true, "replicates": [1.1831400072058806, 1.2139091159573836, 1.1927368585656328, 1.203037089021335, 1.1889209151268005, 1.196754767109708, 1.2130665070939772, 1.1997148900055419, 1.214412397382283, 1.2028000356227744, 1.2103538184177758, 1.1941454635887612, 1.1956627143592369, 1.1863531859024712, 1.2141651035244785, 1.2204217765461747, 1.2217304238039464, 1.2105108431994622, 1.2137527589750763, 1.2280214628577233, 1.2031369758587258, 1.2136868590791825, 1.19957761473772, 1.1979254891232747, 1.2140159988638215], "unit": "ms", "name": "icon-close-DPI1.all.TART", "value": 1.206745397138251}, {"lowerIsBetter": true, "replicates": [19.48999999999978, 16.720000000001164, 17.455000000001746, 16.9800000000032, 17.80000000000291, 18.30000000000291, 17.905000000013388, 17.610000000000582, 18.604999999995925, 18.125, 17.35000000000582, 17.579999999987194, 17.904999999998836, 18.330000000016298, 17.51500000001397, 18.06500000000233, 18.535000000003492, 18.645000000018626, 18.130000000004657, 18.775000000023283, 18.30500000002212, 19.289999999979045, 18.65999999997439, 18.474999999976717, 19.32500000001164], "unit": "ms", "name": "icon-close-DPI1.error.TART", "value": 18.12750000000233}, {"lowerIsBetter": true, "replicates": [1.585822236048032, 1.5997836126221552, 1.6042742315265868, 1.600519609120157, 1.5768325002226111, 1.6259610451443094, 1.6310773802475191, 1.6286111532802312, 1.6527766857828412, 1.648157753263201, 1.6385842494561638, 1.6322293751676318, 1.6362748632968311, 1.6432535648345947, 1.6107755187484953, 1.7257041255039955, 1.6526998826435635, 1.635858416557312, 1.6716879243436067, 1.6340736469752353, 1.722758245112291, 1.6349065387752695, 1.6363614042040329, 1.6384057763596656, 1.6222400480592754], "unit": "ms", "name": "icon-open-DPI2.half.TART", "value": 1.6353824776662909}, {"lowerIsBetter": true, "replicates": [1.8183115551657711, 1.842860973996224, 1.8132106539205457, 1.8267079898289271, 1.800706577972627, 1.852464120457138, 1.8580311284448108, 1.838501636930507, 1.8497371781563414, 1.8438993217288584, 1.8480537075927292, 1.8604752460535425, 1.855488776296809, 1.8596680260052647, 1.8397586071233956, 1.9184735278437908, 1.8742907424183453, 1.885036983313384, 1.8792827129364014, 1.8692987832952948, 1.8991560144210928, 1.8750643949298298, 1.8798445910215378, 1.8504077757614246, 1.8226321339607239], "unit": "ms", "name": "icon-open-DPI2.all.TART", "value": 1.8539764483769736}, {"lowerIsBetter": true, "replicates": [26.760000000002037, 26.450000000000728, 25.979999999995925, 26.080000000001746, 25.985000000000582, 25.919999999998254, 25.06500000000233, 25.77500000000873, 25.570000000006985, 24.97500000000582, 25.270000000018626, 25.355000000010477, 26.41500000000815, 25.25, 26.119999999995343, 25.44000000000233, 25.33499999999185, 25.489999999990687, 25.919999999983702, 24.650000000023283, 24.929999999993015, 25.255000000004657, 25.960000000020955, 25.645000000018626, 25.470000000030268], "unit": "ms", "name": "icon-open-DPI2.error.TART", "value": 25.529999999998836}, {"lowerIsBetter": true, "replicates": [0.9930717631660658, 1.037372288940189, 1.020582404053002, 1.018325287683875, 0.9949950560413557, 1.0049961800160616, 1.0153748711995911, 1.0185087834839273, 1.0000710072724714, 1.0144283567604266, 1.0143239304684757, 1.030852874474866, 1.011740376551946, 1.038756061781634, 1.0001478091530178, 1.015576975910287, 1.0257605467222433, 1.019834083265963, 1.013899507752636, 1.021573967638269, 1.015081822349314, 1.0184476196238426, 1.017419485669387, 1.0153310832224394, 1.0146877200979936], "unit": "ms", "name": "icon-close-DPI2.half.TART", "value": 1.015475923554939}, {"lowerIsBetter": true, "replicates": [1.087370675589357, 1.1103431304840192, 1.1204618762392518, 1.1132134619640977, 1.1234133361671377, 1.1159398145296358, 1.1343298419963481, 1.127223218091622, 1.0961940251290798, 1.1271205849361858, 1.1104364399205555, 1.1189029927647443, 1.1231463354661924, 1.1295689838352028, 1.115816926874152, 1.122838078425565, 1.1226931159397424, 1.125103270677927, 1.1324098206335498, 1.1278476527798067, 1.1040285915673316, 1.1230465904490587, 1.1197852425651462, 1.1254749725991433, 1.1393557390501332], "unit": "ms", "name": "icon-close-DPI2.all.TART", "value": 1.1229423344373117}, {"lowerIsBetter": true, "replicates": [17.600000000002183, 16.57499999999709, 17.735000000000582, 17.294999999998254, 17.37999999999738, 19.004999999990105, 17.29000000000815, 18.05000000000291, 19.035000000003492, 18.539999999993597, 17.775000000023283, 17.375, 18.614999999990687, 18.36500000001979, 16.66500000000815, 18.169999999983702, 17.14999999999418, 17.60999999998603, 19.14999999999418, 19.25, 18.605000000010477, 19.414999999979045, 18.744999999995343, 18.804999999993015, 18.974999999976717], "unit": "ms", "name": "icon-close-DPI2.error.TART", "value": 18.267500000001746}, {"lowerIsBetter": true, "replicates": [0.9766110176757231, 1.0163954972175129, 1.0040345171223517, 1.0019542574882507, 0.9876789144980602, 0.9893986954648271, 1.0064520291660144, 1.0134953082653515, 0.9969944861428491, 1.019772917823454, 1.0030461456464685, 0.9961205587304872, 1.0149470886640382, 1.0220055342775531, 0.9785570325487751, 1.0013331874557163, 1.000271349367888, 1.0112991160468052, 1.0117378286693408, 0.999934043863724, 1.0274414808622427, 1.0130968430767888, 1.0182471248955853, 1.0114136395747202, 1.009385868645551], "unit": "ms", "name": "iconFade-close-DPI2.half.TART", "value": 1.0079189489057827}, {"lowerIsBetter": true, "replicates": [0.9933174336567904, 1.0029497452337166, 1.0114556758300117, 1.0069125072780625, 1.006471920373111, 0.9986040372193628, 1.0101033392159835, 1.016950720002037, 1.0051903422776756, 1.0189386300350491, 1.0092896303057155, 1.0040421480762547, 1.0238604030945229, 1.0190632594259161, 1.0003839373076935, 1.0154885126513684, 1.0068061160834836, 1.0157881578673487, 1.0199760956722392, 1.0179702402722888, 1.0309784895023413, 1.0148647307829044, 1.0328746796709247, 1.0142946027251831, 1.0188796826099094], "unit": "ms", "name": "iconFade-close-DPI2.all.TART", "value": 1.0145796667540439}, {"lowerIsBetter": true, "replicates": [2.6200000000026193, 2.7550000000010186, 2.834999999999127, 2.6899999999950523, 3.6849999999976717, 2.709999999991851, 2.3000000000029104, 2.8949999999895226, 2.220000000001164, 2.5749999999970896, 3.235000000015134, 3.0, 2.419999999983702, 2.3949999999895226, 2.9249999999883585, 2.625, 2.4400000000023283, 3.195000000006985, 2.570000000006985, 3.150000000023283, 2.9800000000104774, 2.5050000000046566, 3.300000000046566, 2.3150000000023283, 2.375], "unit": "ms", "name": "iconFade-close-DPI2.error.TART", "value": 2.6999999999934516}, {"lowerIsBetter": true, "replicates": [1.5553060141769615, 1.562674599724847, 1.6954527549883898, 1.5980289611551497, 1.535771787961324, 1.611484847135014, 1.608690200580491, 1.674832242122595, 1.6015435937378142, 1.620996120949866, 1.6183381196525362, 1.6030155817667644, 1.5889994101981595, 1.620839433537589, 1.6119968460665808, 1.5981101303884429, 1.6073355476061504, 1.6383138340963443, 1.6123328357934952, 1.6128448330693774, 1.569078000816139, 1.6028395858075883, 1.585475059404765, 1.6134101649125416, 1.6199523042625106], "unit": "ms", "name": "iconFade-open-DPI2.half.TART", "value": 1.6100875238577526}, {"lowerIsBetter": true, "replicates": [1.4501814512732607, 1.4578098975121976, 1.5881424631391252, 1.536577980769308, 1.4580834977328778, 1.5191261106067233, 1.544849266279612, 1.5435243513410455, 1.5289334129346044, 1.5581767145792644, 1.5440329621169742, 1.550184464454651, 1.5220098472109027, 1.5462352147954979, 1.5457876470704741, 1.5300475092310655, 1.5350243072760732, 1.5529313206672668, 1.5446737933632553, 1.5569786469141642, 1.511353693225167, 1.5375278686222278, 1.5247856729170854, 1.5433717705556098, 1.531217183721693], "unit": "ms", "name": "iconFade-open-DPI2.all.TART", "value": 1.5404498195889187}, {"lowerIsBetter": true, "replicates": [3.4200000000018917, 3.2600000000020373, 3.3700000000026193, 3.4799999999959255, 3.3099999999976717, 2.5, 3.2700000000040745, 3.014999999999418, 2.2400000000052387, 3.789999999993597, 3.125, 2.5850000000209548, 2.8500000000058208, 3.4250000000174623, 3.4200000000128057, 2.470000000001164, 3.2699999999895226, 2.8550000000104774, 3.179999999993015, 3.459999999991851, 2.6550000000279397, 3.64000000001397, 3.7600000000093132, 3.0949999999720603, 2.875], "unit": "ms", "name": "iconFade-open-DPI2.error.TART", "value": 3.219999999997526}, {"lowerIsBetter": true, "replicates": [1.8284843200817704, 1.6979939113644993, 1.7685782873269282, 1.7534162366028987, 1.675193985303243, 1.719365313871583, 1.7398489606914236, 1.7550045739520679, 1.7061030198546017, 1.754603138475707, 1.7625565149567344, 1.737160981591068, 1.7668561212944263, 1.7417173581336862, 1.7658437689145405, 1.7788763804869219, 1.7314984638299515, 1.7273891274608784, 1.7168664718741802, 1.7187692620860997, 1.7202135502402462, 1.7031835089711582, 1.7294982404851202, 1.7696399652040922, 1.730615847146333], "unit": "ms", "name": "newtab-open-preload-no.half.TART", "value": 1.73432972271051}, {"lowerIsBetter": true, "replicates": [3.1347617928574727, 2.461245359423069, 2.5318145284465716, 2.5358728538541233, 2.425076864238055, 2.535956074695776, 2.4636231718155055, 2.537187884939779, 2.5178104986264866, 2.5465216973040365, 2.51048011581103, 2.588690766096115, 2.535746966258134, 2.5043556910116695, 2.440547763733637, 2.53034252278945, 2.4660933246979346, 2.5478827711379175, 2.5292206704616547, 2.493626156478252, 2.521785439229479, 2.533459694362154, 2.5171887635015975, 2.5535096667780737, 2.5236414098272135], "unit": "ms", "name": "newtab-open-preload-no.all.TART", "value": 2.526431040144434}, {"lowerIsBetter": true, "replicates": [30.155000000002474, 26.460000000002765, 29.160000000003492, 29.06500000000233, 27.454999999987194, 26.55999999999767, 28.770000000004075, 26.64999999999418, 29.735000000000582, 27.570000000006985, 26.554999999993015, 29.345000000001164, 26.470000000001164, 28.304999999993015, 26.595000000001164, 28.51500000001397, 27.010000000009313, 27.570000000006985, 28.255000000004657, 27.110000000015134, 27.645000000018626, 28.785000000032596, 27.09000000002561, 28.85500000003958, 27.785000000032596], "unit": "ms", "name": "newtab-open-preload-no.error.TART", "value": 27.607500000012806}, {"lowerIsBetter": true, "replicates": [1.7593390959681887, 1.7499195355357546, 1.7828258184286265, 1.8172104079276323, 1.7540213230884436, 1.7043693942182205, 1.7238987755419604, 1.7649535934130351, 1.7003261254114264, 1.7469697432084517, 1.7611892566536411, 1.738639655397899, 1.730094301166819, 1.8566550439403904, 1.7379461758172334, 1.7442177660537488, 1.7395910451661294, 1.7982565723359585, 1.7192965336699984, 1.7203568326893137, 1.757832194819595, 1.7324613172616532, 1.7448984872211108, 1.7493668216647524, 1.7382499264247382], "unit": "ms", "name": "newtab-open-preload-yes.half.TART", "value": 1.74455812663743}, {"lowerIsBetter": true, "replicates": [1.932853330557163, 1.9548770203040196, 1.9682331739007966, 2.000816351316107, 1.9830423464146696, 1.929554589708945, 1.96040871489139, 1.9572986368914598, 1.9851201001063798, 1.9386655503150187, 1.9592427709927926, 1.9597094687131735, 1.9420458976969575, 2.027999372709365, 1.9049643533570426, 1.9622940632013175, 1.95926934664066, 2.006835780572146, 1.9321126270114928, 1.9663467065646099, 2.005356793757528, 1.9610096186626957, 1.96798016337248, 1.9889511299687763, 1.9654723671766428], "unit": "ms", "name": "newtab-open-preload-yes.all.TART", "value": 1.9616518409320065}, {"lowerIsBetter": true, "replicates": [36.25499999999738, 34.66000000000349, 35.08499999999913, 34.5199999999968, 36.76499999999942, 36.86999999999534, 37.29000000000815, 37.80500000000757, 36.279999999998836, 36.23500000000058, 35.02000000001863, 35.19000000000233, 36.80999999999767, 35.789999999979045, 32.2949999999837, 36.20999999999185, 35.79000000000815, 37.17500000001746, 37.58999999999651, 36.329999999987194, 37.26000000000931, 37.23999999999069, 36.21500000002561, 36.92499999998836, 35.97000000003027], "unit": "ms", "name": "newtab-open-preload-yes.error.TART", "value": 36.25749999999971}], "name": "tart", "value": 3.695791238914153}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [3.2110355029585795, 2.0132840236686387, 2.0248520710059172, 2.001923076923077, 2.0208284023668637, 2.028313609467456, 2.0174556213017754, 2.0231656804733724, 2.0123076923076924, 2.018491124260355, 2.001627218934911, 2.031775147928994, 2.013994082840237, 2.0080177514792896, 2.0216568047337278, 2.01801775147929, 2.0244970414201178, 2.0238757396449705, 2.0173076923076922, 2.032544378698225, 2.012899408284024, 2.001745562130177, 2.058905325443787, 2.0191715976331364, 2.009852071005917], "unit": "ms", "name": "tiled.html", "value": 2.0182544378698224}, {"lowerIsBetter": true, "replicates": [3.025325443786982, 3.0063905325443785, 3.019615384615385, 3.0265680473372782, 2.9827218934911244, 3.0200591715976333, 2.9967751479289944, 3.0263905325443785, 3.012869822485207, 3.0211538461538465, 2.991745562130178, 3.008550295857988, 3.0154733727810656, 3.009260355029586, 3.319585798816568, 3.0119230769230767, 3.0104733727810653, 3.0336094674556215, 3.009585798816568, 3.03698224852071, 3.014467455621302, 3.0182544378698224, 2.9942307692307693, 3.0073964497041423, 3.008934911242604], "unit": "ms", "name": "tiled-fixed.html", "value": 3.0123964497041418}, {"lowerIsBetter": true, "replicates": [2.329733727810651, 2.2778698224852074, 2.257278106508876, 2.289378698224852, 2.2731360946745562, 2.282721893491124, 2.286479289940828, 2.2631065088757394, 2.2850591715976334, 2.26008875739645, 2.282662721893491, 2.272159763313609, 2.280266272189349, 2.285769230769231, 2.2882248520710062, 2.2739349112426037, 2.294792899408284, 2.287692307692308, 2.284970414201183, 2.2845562130177512, 2.2683136094674556, 2.2831656804733726, 2.2655621301775146, 2.296863905325444, 2.294674556213018], "unit": "ms", "name": "tiled-downscale.html", "value": 2.2838609467455617}, {"lowerIsBetter": true, "replicates": [3.36414201183432, 3.3568639053254437, 3.368165680473372, 3.3753846153846156, 3.3760650887573966, 3.3835207100591713, 3.3760355029585805, 3.3883136094674557, 3.3689940828402376, 3.376065088757396, 3.375384615384615, 3.370059171597633, 3.3880177514792904, 3.385147928994083, 3.37301775147929, 3.3749408284023676, 3.3837573964497034, 3.370739644970414, 3.383224852071006, 3.382692307692308, 3.370443786982248, 3.3757100591715985, 3.366538461538461, 3.38396449704142, 3.36491124260355], "unit": "ms", "name": "tiled-fixed-downscale.html", "value": 3.3758727810650893}, {"lowerIsBetter": true, "replicates": [3.256918604651163, 3.2383720930232567, 3.2352325581395345, 3.139883720930232, 3.1739534883720935, 3.193372093023256, 3.2118604651162794, 3.1825, 3.1690116279069778, 3.238081395348837, 3.1481976744186047, 3.1475581395348837, 3.1744767441860473, 3.1673837209302325, 3.193837209302326, 3.205639534883721, 3.2267441860465116, 3.1612790697674424, 3.166511627906976, 3.1665116279069765, 3.1961627906976737, 3.1845348837209304, 3.221162790697675, 3.143837209302325, 3.2182558139534883], "unit": "ms", "name": "iframe.svg", "value": 3.1835174418604653}, {"lowerIsBetter": true, "replicates": [2.9647976878612723, 2.963526011560693, 2.9641040462427743, 2.980809248554913, 2.9689017341040462, 2.96364161849711, 2.963728323699422, 2.9719075144508675, 8.049421965317919, 2.95078034682081, 2.958843930635838, 2.9666184971098275, 2.936184971098266, 2.967052023121387, 2.963699421965318, 2.95621387283237, 2.954017341040463, 2.956387283236994, 2.934942196531792, 2.962630057803468, 2.968699421965318, 2.9658092485549137, 2.9832947976878614, 2.948526011560694, 2.963121387283237], "unit": "ms", "name": "reader.htm", "value": 2.9633815028901735}], "name": "tscrollx", "value": 2.773138661152803}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [5.119578468799591, 4.8389802149363925, 5.0272300470443, 4.858728567759196, 4.817256950196766, 5.0142837933131625, 4.967417978105091, 4.96739969934736, 5.035001385779608, 4.977164211727324, 4.942567924658458, 5.40173926949501, 5.131274779637654, 5.082324459439232, 4.921905188333421, 5.166986536412012, 4.8956654753003805, 5.095983760697501, 5.028656295367649, 4.8825547354561945, 5.005122820536296, 4.931687990824382, 5.017569732666016, 5.1263011665571305, 5.1000430811019175], "unit": "ms", "name": "1-customize-enter.half.TART", "value": 5.009703306924729}, {"lowerIsBetter": true, "replicates": [10.789096972216731, 9.595890197643014, 9.6927809544972, 9.607396523157755, 9.753434101740519, 9.827326082047962, 9.749603333927336, 9.64422018028969, 10.051413579684931, 9.696940938631693, 10.00063233695379, 10.043013234933218, 9.85377593835195, 10.125938222521828, 9.822114552770342, 10.010794130109606, 9.681379902930487, 10.230253024799067, 10.119184200356646, 10.021705301796517, 10.524660526177822, 10.134316396713256, 9.883819216773624, 9.894141488132023, 10.153678341609675], "unit": "ms", "name": "1-customize-enter.all.TART", "value": 9.888980352452823}, {"lowerIsBetter": true, "replicates": [325.8600000000006, 221.46500000000196, 215.48500000000058, 211.64000000000306, 217.84500000000116, 221.22000000000116, 218.00500000000466, 223.01499999999942, 220.5899999999965, 215.74500000000262, 218.5500000000029, 241.32999999999447, 222.23999999999796, 233.80499999999302, 220.85000000000582, 229.58000000000175, 215.02999999999884, 228.22000000000116, 223.3399999999965, 219.20000000001164, 219.27999999999884, 213.83499999999185, 223.44000000000233, 223.97499999999127, 224.6600000000035], "unit": "ms", "name": "1-customize-enter.error.TART", "value": 221.0350000000035}, {"lowerIsBetter": true, "replicates": [8.317043707920956, 8.009551112468426, 8.380314570206862, 8.402940961030813, 8.39778408408165, 8.818164438009262, 8.579382300376892, 8.221221749599163, 8.600876542238089, 8.466004903499897, 8.22568195599776, 8.490935279772831, 8.627106382296635, 8.758315003835238, 8.486002463560839, 8.5091011432501, 8.478765331781828, 8.490433014356173, 8.509248935259306, 8.37674053815695, 8.409202933311462, 8.64228892326355, 8.481423919017498, 8.411477354856638, 8.1988908969439], "unit": "ms", "name": "2-customize-exit.half.TART", "value": 8.480094625399662}, {"lowerIsBetter": true, "replicates": [9.917952219645182, 9.379700812133583, 10.221133989446303, 10.030943383773169, 10.641084082198866, 10.34749346120017, 10.486895247868128, 10.157217465128218, 10.468529571505155, 9.867904722690582, 10.238063703565036, 10.51186463762732, 10.379668162149542, 10.620906696175084, 10.116459117616927, 10.247533549581256, 10.239848182481879, 10.479394309660968, 10.383372636402354, 10.268241331857794, 10.206083948271615, 10.450131556567024, 10.416328552891226, 10.032561244283404, 10.146333970342363], "unit": "ms", "name": "2-customize-exit.all.TART", "value": 10.257887440719525}, {"lowerIsBetter": true, "replicates": [177.78000000000065, 168.0, 168.10000000000218, 182.71999999999753, 172.15499999999884, 182.11500000000524, 188.99000000000524, 176.02499999999418, 177.11499999999796, 175.6949999999997, 169.83499999999913, 178.1600000000035, 174.43499999999767, 171.85500000001048, 175.55999999999767, 179.44499999999243, 169.38999999999942, 177.74499999999534, 173.27500000000873, 169.31500000000233, 177.55999999999767, 175.60499999999593, 174.38499999999476, 171.3299999999872, 175.58000000000175], "unit": "ms", "name": "2-customize-exit.error.TART", "value": 175.5699999999997}, {"lowerIsBetter": true, "replicates": [9.92104348269376, 11.21803707546658, 9.507374503395774, 9.565567363392223, 9.91608663038774, 9.46775297685103, 10.37839412689209, 9.671341029080478, 10.045545768737792, 10.103414249420165, 9.88791526447643, 9.401984734968705, 10.206978130340577, 9.904531825672496, 10.175413608551025, 9.680766365744851, 9.925093043934215, 10.428812980651855, 10.222261142730712, 10.409920120239258, 9.900412559509277, 9.908127351240678, 10.4606463432312, 9.804692615162242, 9.721505078402432], "unit": "ms", "name": "3-customize-enter-css.half.TART", "value": 9.912106990814209}, {"lowerIsBetter": true, "replicates": [13.46918363571167, 14.148621157595986, 12.668746221633185, 12.675749415443057, 13.47452130317688, 12.664503824143182, 13.854540674309982, 12.956524167742048, 13.396070861816407, 13.503417110443115, 13.366253328323364, 12.621989976792108, 13.548133277893067, 13.372877407073975, 14.116486850537752, 13.319981670379638, 13.230549001693726, 13.906743902909128, 13.564107608795165, 13.992375122873407, 13.31189866065979, 13.346400594711303, 13.994800467240182, 13.042137282235283, 13.1565336227417], "unit": "ms", "name": "3-customize-enter-css.all.TART", "value": 13.36956536769867}, {"lowerIsBetter": true, "replicates": [69.13000000000102, 68.6150000000016, 65.81999999999971, 66.01499999999942, 69.24499999999898, 65.99000000000524, 63.86000000000058, 71.85499999999593, 67.67000000000553, 69.62999999999738, 67.05500000000029, 64.91999999999825, 70.625, 67.26499999999942, 67.94499999999243, 66.15499999999884, 64.43999999998778, 63.919999999998254, 71.05000000000291, 65.5850000000064, 66.02000000000407, 66.69000000000233, 65.56500000000233, 73.83999999999651, 62.94000000000233], "unit": "ms", "name": "3-customize-enter-css.error.TART", "value": 66.42250000000058}], "name": "cart", "value": 23.24503461289772}]} 11:00:25 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": "495.21"}, "tart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[293,53,25]]", "result": "3.70"}, "cart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[309,53,25]]", "result": "23.25"}, "tsvgx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[281,53,25]]", "result": "381.89"}}} 11:00:25 INFO - Return code: 0 11:00:25 INFO - Validating PERFHERDER_DATA against C:\slave\test-pgo\build\tests\talos\treeherder-schemas\performance-artifact.json 11:00:25 INFO - # TBPL SUCCESS # 11:00:25 INFO - Running post-action listener: _resource_record_post_action 11:00:25 INFO - Running post-run listener: _resource_record_post_run 11:00:26 INFO - Total resource usage - Wall time: 1132s; CPU: 8.0%; Read bytes: 102662656; Write bytes: 509168640; Read time: 1723850; Write time: 9493280 11:00:26 INFO - install - Wall time: 2s; CPU: 14.0%; Read bytes: 5443072; Write bytes: 251904; Read time: 113960; Write time: 840 11:00:26 INFO - run-tests - Wall time: 1130s; CPU: 8.0%; Read bytes: 97125376; Write bytes: 485250048; Read time: 1546650; Write time: 9429160 11:00:26 INFO - Running post-run listener: _upload_blobber_files 11:00:26 INFO - Blob upload gear active. 11:00:26 INFO - Preparing to upload files from C:\slave\test-pgo\build\blobber_upload_dir. 11:00:26 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 11:00:26 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'] 11:00:26 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 11:00:27 INFO - (blobuploader) - INFO - Open directory for files ... 11:00:27 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log ... 11:00:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:00:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:00:28 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_errorsummary.log: uploaded 11:00:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:00:28 INFO - (blobuploader) - INFO - Done attempting. 11:00:28 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log ... 11:00:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:00:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:00:30 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_raw.log: uploaded 11:00:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:00:30 INFO - (blobuploader) - INFO - Done attempting. 11:00:30 INFO - (blobuploader) - INFO - Iteration through files over. 11:00:30 INFO - Return code: 0 11:00:30 INFO - rmtree: C:\slave\test-pgo\build\uploaded_files.json 11:00:30 INFO - Using _rmtree_windows ... 11:00:30 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build\\uploaded_files.json',), kwargs: {}, attempt #1 11:00:30 INFO - Setting buildbot property blobber_files to {"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a89a969dc6f4df50e4554fb99fd22641c6ad2cf1f8bfffefb5bb07cb34289173ab6ca9f3746be30a251eb62864b900d633752a923e50533190bd11be1600771", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7245887f6f20c34f0db91927ac28949fcea95fb26554e42dbf524054b7bc1867af8d374d9086d79096238f64b495ec8a0f75613b1a5a6934079884d2c4d28fc"} 11:00:30 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test-pgo\properties\blobber_files 11:00:30 INFO - Writing to file C:\slave\test-pgo\properties\blobber_files 11:00:30 INFO - Contents: 11:00:30 INFO - blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a89a969dc6f4df50e4554fb99fd22641c6ad2cf1f8bfffefb5bb07cb34289173ab6ca9f3746be30a251eb62864b900d633752a923e50533190bd11be1600771", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7245887f6f20c34f0db91927ac28949fcea95fb26554e42dbf524054b7bc1867af8d374d9086d79096238f64b495ec8a0f75613b1a5a6934079884d2c4d28fc"} 11:00:30 INFO - Running post-run listener: copy_logs_to_upload_dir 11:00:30 INFO - Copying logs to upload dir... 11:00:30 INFO - mkdir: C:\slave\test-pgo\build\upload\logs 11:00:30 INFO - Copying logs to upload dir... 11:00:30 INFO - Using _rmtree_windows ... 11:00:30 INFO - Using _rmtree_windows ... 11:00:30 INFO - Using _rmtree_windows ... 11:00:30 INFO - Using _rmtree_windows ... 11:00:30 INFO - Using _rmtree_windows ... 11:00:30 INFO - Using _rmtree_windows ... 11:00:30 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1423.638000 ========= master_lag: -0.16 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 43 secs) (at 2016-03-14 11:00:30.801586) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-14 11:00:30.802656) ========= '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-004 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-004 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-004 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/0a89a969dc6f4df50e4554fb99fd22641c6ad2cf1f8bfffefb5bb07cb34289173ab6ca9f3746be30a251eb62864b900d633752a923e50533190bd11be1600771", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7245887f6f20c34f0db91927ac28949fcea95fb26554e42dbf524054b7bc1867af8d374d9086d79096238f64b495ec8a0f75613b1a5a6934079884d2c4d28fc"} build_url:https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/artifacts/public/build/firefox-46.0.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/ZEvbqXMvQ5eO4hxPTfyE2g/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/0a89a969dc6f4df50e4554fb99fd22641c6ad2cf1f8bfffefb5bb07cb34289173ab6ca9f3746be30a251eb62864b900d633752a923e50533190bd11be1600771", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7245887f6f20c34f0db91927ac28949fcea95fb26554e42dbf524054b7bc1867af8d374d9086d79096238f64b495ec8a0f75613b1a5a6934079884d2c4d28fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-14 11:00:30.923911) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 11:00:30.924290) ========= '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-004 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-004 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-004 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-14 11:00:31.043555) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-14 11:00:31.043873) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-14 11:00:31.710776) ========= ========= Total master_lag: 0.19 =========