builder: mozilla-aurora_win7-ix_test-svgr-pgo slave: t-w732-ix-144 starttime: 1456778140.48 results: success (0) buildid: 20160229085316 builduid: e1e60dbf7fa6400691bb1c4cf3a0c501 revision: 6aaf9904a81dd8635e056cacb1a4834fe13f9cd3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:40.483333) ========= master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:40.483772) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:40.484077) ========= '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-144 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-144 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-144 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.597000 basedir: 'C:\\slave\\test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:41.122323) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:41.122853) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:41.275925) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:41.276525) ========= '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-144 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-144 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-144 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-02-29 12:35:41.795383) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:41.795709) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:41.796094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-29 12:35:41.796428) ========= '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-144 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-144 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-144 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 --12:35:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 8.28 MB/s 12:35:42 (8.28 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.070000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-29 12:35:42.886124) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2016-02-29 12:35:42.886558) ========= '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-144 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-144 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-144 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=13.501000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2016-02-29 12:35:56.408640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-29 12:35:56.408991) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6aaf9904a81dd8635e056cacb1a4834fe13f9cd3 --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-aurora --rev 6aaf9904a81dd8635e056cacb1a4834fe13f9cd3 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-144 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-144 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-144 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-02-29 12:35:56,914 truncating revision to first 12 chars 2016-02-29 12:35:56,914 Setting DEBUG logging. 2016-02-29 12:35:56,914 attempt 1/10 2016-02-29 12:35:56,914 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6aaf9904a81d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-29 12:35:57,773 unpacking tar archive at: mozilla-aurora-6aaf9904a81d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.676000 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-29 12:35:58.182498) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:58.182836) ========= 'echo' u'6aaf9904a81dd8635e056cacb1a4834fe13f9cd3' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'6aaf9904a81dd8635e056cacb1a4834fe13f9cd3'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-144 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-144 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-144 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 6aaf9904a81dd8635e056cacb1a4834fe13f9cd3 program finished with exit code 0 elapsedTime=0.101000 script_repo_revision: '6aaf9904a81dd8635e056cacb1a4834fe13f9cd3' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:58.300919) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:58.301247) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-29 12:35:58.317265) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 35 secs) (at 2016-02-29 12:35:58.317634) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'svgr' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Aurora' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Aurora' 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-Aurora', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-144 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-144 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-144 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 12:35:58 INFO - MultiFileLogger online at 20160229 12:35:58 in C:\slave\test-pgo 12:35:58 INFO - Run as scripts/scripts/talos_script.py --suite svgr --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:35:58 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 12:35:58 INFO - {'append_to_log': False, 12:35:58 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 12:35:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:35:58 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 12:35:58 INFO - 'branch': 'Mozilla-Aurora', 12:35:58 INFO - 'buildbot_json_path': 'buildprops.json', 12:35:58 INFO - 'config_files': ('talos/windows_config.py',), 12:35:58 INFO - 'default_actions': ('clobber', 12:35:58 INFO - 'read-buildbot-config', 12:35:58 INFO - 'download-and-extract', 12:35:58 INFO - 'populate-webroot', 12:35:58 INFO - 'create-virtualenv', 12:35:58 INFO - 'install', 12:35:58 INFO - 'run-tests'), 12:35:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:35:58 INFO - 'download_minidump_stackwalk': True, 12:35:58 INFO - 'download_symbols': 'ondemand', 12:35:58 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 12:35:58 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 12:35:58 INFO - 'hg': 'c:/mozilla-build/hg/hg', 12:35:58 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 12:35:58 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 12:35:58 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 12:35:58 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 12:35:58 INFO - 'C:/mozilla-build/tooltool.py'), 12:35:58 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 12:35:58 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 12:35:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:35:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:35:58 INFO - 'installer_path': 'installer.exe', 12:35:58 INFO - 'log_level': 'info', 12:35:58 INFO - 'log_name': 'talos', 12:35:58 INFO - 'log_to_console': True, 12:35:58 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 12:35:58 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 12:35:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 12:35:58 INFO - 'opt_config_files': (), 12:35:58 INFO - 'pip_index': False, 12:35:58 INFO - 'sps_profile': False, 12:35:58 INFO - 'sps_profile_interval': 0, 12:35:58 INFO - 'suite': 'svgr', 12:35:58 INFO - 'system_bits': '32', 12:35:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:35:58 INFO - 'title': 'T-W732-IX-144', 12:35:58 INFO - 'use_talos_json': True, 12:35:58 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 12:35:58 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 12:35:58 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 12:35:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:35:58 INFO - 'work_dir': 'build'} 12:35:58 INFO - ##### 12:35:58 INFO - ##### Running clobber step. 12:35:58 INFO - ##### 12:35:58 INFO - Running pre-action listener: _resource_record_pre_action 12:35:58 INFO - Running main action method: clobber 12:35:58 INFO - rmtree: C:\slave\test-pgo\build 12:35:59 INFO - Using _rmtree_windows ... 12:35:59 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 12:37:31 INFO - Running post-action listener: _resource_record_post_action 12:37:31 INFO - ##### 12:37:31 INFO - ##### Running read-buildbot-config step. 12:37:31 INFO - ##### 12:37:31 INFO - Running pre-action listener: _resource_record_pre_action 12:37:31 INFO - Running main action method: read_buildbot_config 12:37:31 INFO - Using buildbot properties: 12:37:31 INFO - { 12:37:31 INFO - "project": "", 12:37:31 INFO - "product": "firefox", 12:37:31 INFO - "script_repo_revision": "production", 12:37:31 INFO - "builddir": "mozilla-aurora_win7-ix_test-svgr", 12:37:31 INFO - "repository": "", 12:37:31 INFO - "buildername": "Windows 7 32-bit mozilla-aurora pgo talos svgr", 12:37:31 INFO - "buildid": "20160229085316", 12:37:31 INFO - "pgo_build": "True", 12:37:31 INFO - "basedir": "C:\\slave\\test-pgo", 12:37:31 INFO - "buildnumber": 99, 12:37:31 INFO - "slavename": "t-w732-ix-144", 12:37:31 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/", 12:37:31 INFO - "platform": "win7-ix", 12:37:31 INFO - "scheduler": "tests-mozilla-aurora-win32-pgo-talos", 12:37:31 INFO - "branch": "mozilla-aurora", 12:37:31 INFO - "repo_path": "releases/mozilla-aurora", 12:37:31 INFO - "revision": "6aaf9904a81dd8635e056cacb1a4834fe13f9cd3", 12:37:31 INFO - "stage_platform": "win32-pgo", 12:37:31 INFO - "builduid": "e1e60dbf7fa6400691bb1c4cf3a0c501", 12:37:31 INFO - "slavebuilddir": "test" 12:37:31 INFO - } 12:37:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.zip. 12:37:31 INFO - Running post-action listener: _resource_record_post_action 12:37:31 INFO - ##### 12:37:31 INFO - ##### Running download-and-extract step. 12:37:31 INFO - ##### 12:37:31 INFO - Running pre-action listener: _resource_record_pre_action 12:37:31 INFO - Running main action method: download_and_extract 12:37:31 INFO - mkdir: C:\slave\test-pgo\build\tests 12:37: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')]} 12:37:31 INFO - https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:37:31 INFO - trying https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/test_packages.json 12:37:31 INFO - Downloading https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 12:37:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 12:37:32 INFO - Downloaded 1301 bytes. 12:37:32 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 12:37:32 INFO - Using the following test package requirements: 12:37:32 INFO - {u'common': [u'firefox-46.0a2.en-US.win32.common.tests.zip'], 12:37:32 INFO - u'cppunittest': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 12:37:32 INFO - u'firefox-46.0a2.en-US.win32.cppunittest.tests.zip'], 12:37:32 INFO - u'gtest': [u'firefox-46.0a2.en-US.win32.common.tests.zip'], 12:37:32 INFO - u'jittest': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 12:37:32 INFO - u'jsshell-win32.zip'], 12:37:32 INFO - u'mochitest': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 12:37:32 INFO - u'firefox-46.0a2.en-US.win32.mochitest.tests.zip'], 12:37:32 INFO - u'mozbase': [u'firefox-46.0a2.en-US.win32.common.tests.zip'], 12:37:32 INFO - u'reftest': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 12:37:32 INFO - u'firefox-46.0a2.en-US.win32.reftest.tests.zip'], 12:37:32 INFO - u'talos': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 12:37:32 INFO - u'firefox-46.0a2.en-US.win32.talos.tests.zip'], 12:37:32 INFO - u'web-platform': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 12:37:32 INFO - u'firefox-46.0a2.en-US.win32.web-platform.tests.zip'], 12:37:32 INFO - u'webapprt': [u'firefox-46.0a2.en-US.win32.common.tests.zip'], 12:37:32 INFO - u'xpcshell': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 12:37:32 INFO - u'firefox-46.0a2.en-US.win32.xpcshell.tests.zip']} 12:37:32 INFO - Downloading packages: [u'firefox-46.0a2.en-US.win32.common.tests.zip'] for test suite category: common 12:37: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')]} 12:37:32 INFO - https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 12:37:32 INFO - trying https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip 12:37:32 INFO - Downloading https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.common.tests.zip 12:37:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.common.tests.zip'}, attempt #1 12:37:34 INFO - Downloaded 17888088 bytes. 12:37:34 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.common.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 12:37:34 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.common.tests.zip -d C:\slave\test-pgo\build\tests 12:38:02 INFO - Return code: 0 12:38:02 INFO - Downloading packages: [u'firefox-46.0a2.en-US.win32.common.tests.zip', u'firefox-46.0a2.en-US.win32.talos.tests.zip'] for test suite category: talos 12:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:02 INFO - https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 12:38:02 INFO - trying https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip 12:38:02 INFO - Downloading https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.common.tests.zip 12:38:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.common.tests.zip'}, attempt #1 12:38:04 INFO - Downloaded 17888088 bytes. 12:38:04 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.common.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 12:38:04 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.common.tests.zip -d C:\slave\test-pgo\build\tests 12:38:26 INFO - Return code: 0 12:38:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:26 INFO - https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 12:38:26 INFO - trying https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.talos.tests.zip 12:38:26 INFO - Downloading https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.talos.tests.zip to C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.talos.tests.zip 12:38:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.talos.tests.zip'}, attempt #1 12:38:28 INFO - Downloaded 10706649 bytes. 12:38:28 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.talos.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 12:38:28 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.talos.tests.zip -d C:\slave\test-pgo\build\tests 12:38:30 INFO - Return code: 0 12:38: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')]} 12:38:30 INFO - https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.zip matches https://queue.taskcluster.net 12:38:30 INFO - trying https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.zip 12:38:30 INFO - Downloading https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.zip to C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.zip 12:38:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.zip'}, attempt #1 12:38:34 INFO - Downloaded 55268356 bytes. 12:38:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.zip 12:38:34 INFO - mkdir: C:\slave\test-pgo\properties 12:38:34 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 12:38:34 INFO - Writing to file C:\slave\test-pgo\properties\build_url 12:38:34 INFO - Contents: 12:38:34 INFO - build_url:https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.zip 12:38:35 INFO - Running post-action listener: _resource_record_post_action 12:38:35 INFO - Running post-action listener: set_extra_try_arguments 12:38:35 INFO - ##### 12:38:35 INFO - ##### Running populate-webroot step. 12:38:35 INFO - ##### 12:38:35 INFO - Running pre-action listener: _resource_record_pre_action 12:38:35 INFO - Running main action method: populate_webroot 12:38:35 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 12:38:35 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 12:38:35 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 12:38:35 INFO - u'--tppagecycles', 12:38:35 INFO - u'7'], 12:38:35 INFO - u'tests': [u'tsvgm']}}, 12:38:35 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 12:38:35 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 12:38:35 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 12:38:35 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 12:38:35 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 12:38:35 INFO - u'tests': [u'dromaeo_css', 12:38:35 INFO - u'kraken', 12:38:35 INFO - u'v8_7']}, 12:38:35 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:35 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 12:38:35 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:35 INFO - u'talos_options': [u'--e10s'], 12:38:35 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 12:38:35 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:35 INFO - u'tests': [u'damp', u'tps']}, 12:38:35 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:35 INFO - u'talos_options': [u'--e10s'], 12:38:35 INFO - u'tests': [u'damp', u'tps']}, 12:38:35 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 12:38:35 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 12:38:35 INFO - u'other': {u'tests': [u'a11yr', 12:38:35 INFO - u'ts_paint', 12:38:35 INFO - u'tpaint', 12:38:35 INFO - u'sessionrestore', 12:38:35 INFO - u'sessionrestore_no_auto_restore']}, 12:38:35 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 12:38:35 INFO - u'tests': [u'a11yr', 12:38:35 INFO - u'ts_paint', 12:38:35 INFO - u'tpaint', 12:38:35 INFO - u'sessionrestore', 12:38:35 INFO - u'sessionrestore_no_auto_restore']}, 12:38:35 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 12:38:35 INFO - u'tests': [u'a11yr', 12:38:35 INFO - u'ts_paint', 12:38:35 INFO - u'tpaint', 12:38:35 INFO - u'sessionrestore', 12:38:35 INFO - u'sessionrestore_no_auto_restore']}, 12:38:35 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 12:38:35 INFO - u'tests': [u'a11yr', 12:38:35 INFO - u'ts_paint', 12:38:35 INFO - u'tpaint', 12:38:35 INFO - u'sessionrestore', 12:38:35 INFO - u'sessionrestore_no_auto_restore']}, 12:38:35 INFO - u'other_l64': {u'tests': [u'a11yr', 12:38:35 INFO - u'ts_paint', 12:38:35 INFO - u'tpaint', 12:38:35 INFO - u'sessionrestore', 12:38:35 INFO - u'sessionrestore_no_auto_restore']}, 12:38:35 INFO - u'other_nol64': {u'tests': [u'a11yr', 12:38:35 INFO - u'ts_paint', 12:38:35 INFO - u'tpaint', 12:38:35 INFO - u'sessionrestore', 12:38:35 INFO - u'sessionrestore_no_auto_restore']}, 12:38:35 INFO - u'svgr': {u'tests': [u'tsvgx', 12:38:35 INFO - u'tsvgr_opacity', 12:38:35 INFO - u'tart', 12:38:35 INFO - u'tscrollx', 12:38:35 INFO - u'cart']}, 12:38:35 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 12:38:35 INFO - u'tests': [u'tsvgx', 12:38:35 INFO - u'tsvgr_opacity', 12:38:35 INFO - u'tart', 12:38:35 INFO - u'tscrollx', 12:38:35 INFO - u'cart']}, 12:38:35 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:35 INFO - u'tests': [u'tp5o']}, 12:38:35 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:35 INFO - u'talos_options': [u'--e10s'], 12:38:35 INFO - u'tests': [u'tp5o']}, 12:38:35 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:35 INFO - u'talos_options': [u'--xperf_path', 12:38:35 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:38:35 INFO - u'tests': [u'tp5n']}, 12:38:35 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:35 INFO - u'talos_options': [u'--e10s', 12:38:35 INFO - u'--xperf_path', 12:38:35 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:38:35 INFO - u'tests': [u'tp5n']}}, 12:38:35 INFO - u'talos.zip': {u'path': u'', 12:38:35 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:38:35 INFO - Running post-action listener: _resource_record_post_action 12:38:35 INFO - ##### 12:38:35 INFO - ##### Running create-virtualenv step. 12:38:35 INFO - ##### 12:38:35 INFO - Running pre-action listener: _resource_record_pre_action 12:38:35 INFO - Running main action method: create_virtualenv 12:38:35 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 12:38:35 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 12:38:35 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 12:38:35 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'! 12:38:35 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 12:38:35 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 12:38:40 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 12:38:45 INFO - Installing distribute......................................................................................................................................................................................done. 12:38:46 INFO - Return code: 0 12:38:46 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 12:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:46 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 12:38:46 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 12:38:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:38:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:38:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:38:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:38:46 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:38:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:38:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:38:46 INFO - 'DCLOCATION': 'SCL3', 12:38:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:38:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:38:46 INFO - 'HOMEDRIVE': 'C:', 12:38:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:38:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:38:46 INFO - 'KTS_VERSION': '1.19c', 12:38:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:38:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:38:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:38:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:38:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:46 INFO - 'MOZ_NO_REMOTE': '1', 12:38:46 INFO - 'NO_EM_RESTART': '1', 12:38:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:38:46 INFO - 'OS': 'Windows_NT', 12:38:46 INFO - 'OURDRIVE': 'C:', 12:38:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 12:38:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:38:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:38:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:38:46 INFO - 'PROCESSOR_LEVEL': '6', 12:38:46 INFO - 'PROCESSOR_REVISION': '1e05', 12:38:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:38:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:38:46 INFO - 'PROMPT': '$P$G', 12:38:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:38:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:38:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:38:46 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:38:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:38:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:38:46 INFO - 'SYSTEMDRIVE': 'C:', 12:38:46 INFO - 'SYSTEMROOT': 'C:\\windows', 12:38:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:38:46 INFO - 'TEST1': 'testie', 12:38:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:38:46 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:38:46 INFO - 'USERNAME': 'cltbld', 12:38:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:38:46 INFO - 'WINDIR': 'C:\\windows', 12:38:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:38:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:38:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:38:47 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:38:47 INFO - Downloading/unpacking pip>=1.5 12:38:51 INFO - Running setup.py egg_info for package pip 12:38:51 INFO - warning: no files found matching 'pip\cacert.pem' 12:38:51 INFO - warning: no files found matching '*.html' under directory 'docs' 12:38:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 12:38:51 INFO - no previously-included directories found matching 'docs\_build\_sources' 12:38:51 INFO - Installing collected packages: pip 12:38:51 INFO - Found existing installation: pip 0.8.2 12:38:51 INFO - Uninstalling pip: 12:38:51 INFO - Successfully uninstalled pip 12:38:51 INFO - Running setup.py install for pip 12:38:51 INFO - warning: no files found matching 'pip\cacert.pem' 12:38:51 INFO - warning: no files found matching '*.html' under directory 'docs' 12:38:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 12:38:51 INFO - no previously-included directories found matching 'docs\_build\_sources' 12:38:51 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:38:51 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 12:38:51 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:38:51 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 12:38:51 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:38:51 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 12:38:51 INFO - Successfully installed pip 12:38:51 INFO - Cleaning up... 12:38:51 INFO - Return code: 0 12:38:51 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 12:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 12:38:52 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 12:38:52 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 12:38:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:38:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:38:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:38:52 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:38:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:38:52 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:38:52 INFO - 'DCLOCATION': 'SCL3', 12:38:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:38:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:38:52 INFO - 'HOMEDRIVE': 'C:', 12:38:52 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:38:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:38:52 INFO - 'KTS_VERSION': '1.19c', 12:38:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:38:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:38:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:38:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:38:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:52 INFO - 'MOZ_NO_REMOTE': '1', 12:38:52 INFO - 'NO_EM_RESTART': '1', 12:38:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:38:52 INFO - 'OS': 'Windows_NT', 12:38:52 INFO - 'OURDRIVE': 'C:', 12:38:52 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;', 12:38:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:38:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:38:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:38:52 INFO - 'PROCESSOR_LEVEL': '6', 12:38:52 INFO - 'PROCESSOR_REVISION': '1e05', 12:38:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:38:52 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:38:52 INFO - 'PROMPT': '$P$G', 12:38:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:38:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:38:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:38:52 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:38:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:38:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:38:52 INFO - 'SYSTEMDRIVE': 'C:', 12:38:52 INFO - 'SYSTEMROOT': 'C:\\windows', 12:38:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:38:52 INFO - 'TEST1': 'testie', 12:38:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:38:52 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:38:52 INFO - 'USERNAME': 'cltbld', 12:38:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:38:52 INFO - 'WINDIR': 'C:\\windows', 12:38:52 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:38:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:38:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:38:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:54 INFO - Downloading/unpacking psutil>=0.7.1 12:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:55 INFO - Installing collected packages: psutil 12:38:55 INFO - Successfully installed psutil 12:38:55 INFO - Cleaning up... 12:38:55 INFO - Return code: 0 12:38:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 12:38:55 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')]} 12:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:55 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')]} 12:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:55 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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 12:38:55 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 12:38:55 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 12:38:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:38:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:38:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:38:55 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:38:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:38:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:38:55 INFO - 'DCLOCATION': 'SCL3', 12:38:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:38:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:38:55 INFO - 'HOMEDRIVE': 'C:', 12:38:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:38:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:38:55 INFO - 'KTS_VERSION': '1.19c', 12:38:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:38:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:38:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:38:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:55 INFO - 'MOZ_NO_REMOTE': '1', 12:38:55 INFO - 'NO_EM_RESTART': '1', 12:38:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:38:55 INFO - 'OS': 'Windows_NT', 12:38:55 INFO - 'OURDRIVE': 'C:', 12:38:55 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 12:38:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:38:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:38:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:38:55 INFO - 'PROCESSOR_LEVEL': '6', 12:38:55 INFO - 'PROCESSOR_REVISION': '1e05', 12:38:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:38:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:38:55 INFO - 'PROMPT': '$P$G', 12:38:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:38:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:38:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:38:55 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:38:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:38:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:38:55 INFO - 'SYSTEMDRIVE': 'C:', 12:38:55 INFO - 'SYSTEMROOT': 'C:\\windows', 12:38:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:38:55 INFO - 'TEST1': 'testie', 12:38:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:38:55 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:38:55 INFO - 'USERNAME': 'cltbld', 12:38:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:38:55 INFO - 'WINDIR': 'C:\\windows', 12:38:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:38:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:38:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:38:56 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 12:38:56 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) 12:38:56 INFO - Installing collected packages: mozsystemmonitor 12:38:56 INFO - Running setup.py install for mozsystemmonitor 12:38:57 INFO - Successfully installed mozsystemmonitor 12:38:57 INFO - Cleaning up... 12:38:57 INFO - Return code: 0 12:38:57 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 12:38:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:57 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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 12:38:57 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 12:38:57 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 12:38:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:38:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:38:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:38:57 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:38:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:38:57 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:38:57 INFO - 'DCLOCATION': 'SCL3', 12:38:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:38:57 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:38:57 INFO - 'HOMEDRIVE': 'C:', 12:38:57 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:38:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:38:57 INFO - 'KTS_VERSION': '1.19c', 12:38:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:38:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:38:57 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:38:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:38:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:57 INFO - 'MOZ_NO_REMOTE': '1', 12:38:57 INFO - 'NO_EM_RESTART': '1', 12:38:57 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:38:57 INFO - 'OS': 'Windows_NT', 12:38:57 INFO - 'OURDRIVE': 'C:', 12:38:57 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 12:38:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:38:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:38:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:38:57 INFO - 'PROCESSOR_LEVEL': '6', 12:38:57 INFO - 'PROCESSOR_REVISION': '1e05', 12:38:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:38:57 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:38:57 INFO - 'PROMPT': '$P$G', 12:38:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:38:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:38:57 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:38:57 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:38:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:38:57 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:38:57 INFO - 'SYSTEMDRIVE': 'C:', 12:38:57 INFO - 'SYSTEMROOT': 'C:\\windows', 12:38:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:38:57 INFO - 'TEST1': 'testie', 12:38:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:38:57 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:38:57 INFO - 'USERNAME': 'cltbld', 12:38:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:38:57 INFO - 'WINDIR': 'C:\\windows', 12:38:57 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:38:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:38:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:57 INFO - Downloading/unpacking blobuploader==1.2.4 12:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:58 INFO - Downloading blobuploader-1.2.4.tar.gz 12:38:58 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 12:38:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:59 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 12:38:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:38:59 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 12:38:59 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 12:38:59 INFO - Downloading docopt-0.6.1.tar.gz 12:38:59 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 12:38:59 INFO - Installing collected packages: blobuploader, requests, docopt 12:38:59 INFO - Running setup.py install for blobuploader 12:38:59 INFO - Running setup.py install for requests 12:39:00 INFO - Running setup.py install for docopt 12:39:00 INFO - Successfully installed blobuploader requests docopt 12:39:00 INFO - Cleaning up... 12:39:01 INFO - Return code: 0 12:39:01 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 12:39: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')]} 12:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39: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')]} 12:39:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:01 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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 12:39:01 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 12:39:01 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 12:39:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:39:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:39:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:39:01 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:39:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:39:01 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:39:01 INFO - 'DCLOCATION': 'SCL3', 12:39:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:39:01 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:39:01 INFO - 'HOMEDRIVE': 'C:', 12:39:01 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:39:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:39:01 INFO - 'KTS_VERSION': '1.19c', 12:39:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:39:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:39:01 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:39:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:39:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:01 INFO - 'MOZ_NO_REMOTE': '1', 12:39:01 INFO - 'NO_EM_RESTART': '1', 12:39:01 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:39:01 INFO - 'OS': 'Windows_NT', 12:39:01 INFO - 'OURDRIVE': 'C:', 12:39: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;', 12:39:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:39:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:39:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:39:01 INFO - 'PROCESSOR_LEVEL': '6', 12:39:01 INFO - 'PROCESSOR_REVISION': '1e05', 12:39:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:39:01 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:39:01 INFO - 'PROMPT': '$P$G', 12:39:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:39:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:39:01 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:39:01 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:39:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:39:01 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:39:01 INFO - 'SYSTEMDRIVE': 'C:', 12:39:01 INFO - 'SYSTEMROOT': 'C:\\windows', 12:39:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:01 INFO - 'TEST1': 'testie', 12:39:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:01 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:39:01 INFO - 'USERNAME': 'cltbld', 12:39:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:39:01 INFO - 'WINDIR': 'C:\\windows', 12:39:01 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:39:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:39:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:02 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 12:39:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qwzvat-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 12:39:02 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 12:39:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-50imea-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 12:39:02 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 12:39:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6ayq57-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 12:39:02 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 12:39:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ff1cui-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 12:39:02 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 12:39:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eqz7p6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 12:39:02 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 12:39:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gkgbk2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 12:39:03 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 12:39:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eozrnm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 12:39:03 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 12:39:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2jzbpd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 12:39:03 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 12:39:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xx5t1l-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 12:39:03 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 12:39:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_oa1p_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 12:39:03 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 12:39:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ncbmue-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 12:39:03 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 12:39:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3hsass-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 12:39:03 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 12:39:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qa85w2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 12:39:04 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 12:39:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vpacoe-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 12:39:04 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 12:39:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jgd8ri-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:39:04 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 12:39:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oh28cg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 12:39:04 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 12:39:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ongnaa-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 12:39:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:39:04 INFO - Running setup.py install for manifestparser 12:39:04 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:04 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:05 INFO - Running setup.py install for mozcrash 12:39:05 INFO - Running setup.py install for mozdebug 12:39:06 INFO - Running setup.py install for mozdevice 12:39:06 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:06 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:06 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:06 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:06 INFO - Running setup.py install for mozfile 12:39:07 INFO - Running setup.py install for mozhttpd 12:39:07 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:07 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:07 INFO - Running setup.py install for mozinfo 12:39:07 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:07 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:07 INFO - Running setup.py install for mozInstall 12:39:08 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:08 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:08 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:08 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:08 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:08 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:08 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:08 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:08 INFO - Running setup.py install for mozleak 12:39:08 INFO - Running setup.py install for mozlog 12:39:08 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:08 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:09 INFO - Running setup.py install for moznetwork 12:39:09 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:09 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:09 INFO - Running setup.py install for mozprocess 12:39:10 INFO - Running setup.py install for mozprofile 12:39:10 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:10 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:10 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:10 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:10 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:10 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:11 INFO - Running setup.py install for mozrunner 12:39:11 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:11 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:11 INFO - Running setup.py install for mozscreenshot 12:39:12 INFO - Running setup.py install for moztest 12:39:12 INFO - Running setup.py install for mozversion 12:39:12 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:12 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:39:12 INFO - Cleaning up... 12:39:12 INFO - Return code: 0 12:39:12 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 12:39:12 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')]} 12:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:12 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')]} 12:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:12 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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 12:39:12 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 12:39:12 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 12:39:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:39:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:39:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:39:12 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:39:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:39:12 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:39:12 INFO - 'DCLOCATION': 'SCL3', 12:39:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:39:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:39:12 INFO - 'HOMEDRIVE': 'C:', 12:39:12 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:39:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:39:12 INFO - 'KTS_VERSION': '1.19c', 12:39:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:39:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:39:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:39:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:12 INFO - 'MOZ_NO_REMOTE': '1', 12:39:12 INFO - 'NO_EM_RESTART': '1', 12:39:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:39:12 INFO - 'OS': 'Windows_NT', 12:39:12 INFO - 'OURDRIVE': 'C:', 12:39:12 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;', 12:39:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:39:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:39:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:39:12 INFO - 'PROCESSOR_LEVEL': '6', 12:39:12 INFO - 'PROCESSOR_REVISION': '1e05', 12:39:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:39:12 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:39:12 INFO - 'PROMPT': '$P$G', 12:39:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:39:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:39:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:39:12 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:39:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:39:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:39:12 INFO - 'SYSTEMDRIVE': 'C:', 12:39:12 INFO - 'SYSTEMROOT': 'C:\\windows', 12:39:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:12 INFO - 'TEST1': 'testie', 12:39:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:12 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:39:12 INFO - 'USERNAME': 'cltbld', 12:39:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:39:12 INFO - 'WINDIR': 'C:\\windows', 12:39:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:39:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:13 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 12:39:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-blhyzi-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 12:39:13 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)) 12:39:13 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 12:39:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ap0uzm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 12:39:13 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)) 12:39:13 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 12:39:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-etgprj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 12:39:13 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)) 12:39:13 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 12:39:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ggwq90-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 12:39:13 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)) 12:39:13 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 12:39:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ul26hs-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 12:39:13 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)) 12:39:13 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 12:39:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9gntfa-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 12:39:14 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)) 12:39:14 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 12:39:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e9npm5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 12:39:14 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)) 12:39:14 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 12:39:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hlrnip-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 12:39:14 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)) 12:39:14 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 12:39:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tpm8oj-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 12:39:14 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)) 12:39:14 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 12:39:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xes8m5-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 12:39:14 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)) 12:39:14 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 12:39:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xjyilp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 12:39:14 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)) 12:39:14 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 12:39:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pn9oio-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 12:39:15 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)) 12:39:15 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 12:39:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r9nd8k-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 12:39:15 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)) 12:39:15 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 12:39:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6sf1ui-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 12:39:15 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)) 12:39:15 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 12:39:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8jeukr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 12:39:15 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)) 12:39:15 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 12:39:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kyoa_y-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 12:39:15 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)) 12:39:15 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 12:39:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bmschm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 12:39:15 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)) 12:39:15 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)) 12:39:15 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)) 12:39:15 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)) 12:39:15 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)) 12:39:15 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)) 12:39:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 12:39:15 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 12:39:15 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 12:39:16 INFO - Downloading blessings-1.6.tar.gz 12:39:16 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 12:39:17 INFO - Installing collected packages: blessings 12:39:17 INFO - Running setup.py install for blessings 12:39:17 INFO - Successfully installed blessings 12:39:17 INFO - Cleaning up... 12:39:20 INFO - Return code: 0 12:39:20 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 12:39:20 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 12:39:20 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 12:39:20 INFO - Reading from file tmpfile_stdout 12:39:20 INFO - Using _rmtree_windows ... 12:39:20 INFO - Using _rmtree_windows ... 12:39:20 INFO - Current package versions: 12:39:20 INFO - blessings == 1.6 12:39:20 INFO - blobuploader == 1.2.4 12:39:20 INFO - docopt == 0.6.1 12:39:20 INFO - manifestparser == 1.1 12:39:20 INFO - mozInstall == 1.12 12:39:20 INFO - mozcrash == 0.16 12:39:20 INFO - mozdebug == 0.1 12:39:20 INFO - mozdevice == 0.48 12:39:20 INFO - mozfile == 1.2 12:39:20 INFO - mozhttpd == 0.7 12:39:20 INFO - mozinfo == 0.9 12:39:20 INFO - mozleak == 0.1 12:39:20 INFO - mozlog == 3.1 12:39:20 INFO - moznetwork == 0.27 12:39:20 INFO - mozprocess == 0.22 12:39:20 INFO - mozprofile == 0.28 12:39:20 INFO - mozrunner == 6.11 12:39:20 INFO - mozscreenshot == 0.1 12:39:20 INFO - mozsystemmonitor == 0.0 12:39:20 INFO - moztest == 0.7 12:39:20 INFO - mozversion == 1.4 12:39:20 INFO - psutil == 3.1.1 12:39:20 INFO - requests == 1.2.3 12:39:20 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 12:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:20 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 12:39:20 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 12:39:20 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 12:39:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:39:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:39:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:39:20 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:39:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:39:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:39:20 INFO - 'DCLOCATION': 'SCL3', 12:39:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:39:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:39:20 INFO - 'HOMEDRIVE': 'C:', 12:39:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:39:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:39:20 INFO - 'KTS_VERSION': '1.19c', 12:39:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:39:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:39:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:39:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:39:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:20 INFO - 'MOZ_NO_REMOTE': '1', 12:39:20 INFO - 'NO_EM_RESTART': '1', 12:39:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:39:20 INFO - 'OS': 'Windows_NT', 12:39:20 INFO - 'OURDRIVE': 'C:', 12:39:20 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 12:39:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:39:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:39:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:39:20 INFO - 'PROCESSOR_LEVEL': '6', 12:39:20 INFO - 'PROCESSOR_REVISION': '1e05', 12:39:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:39:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:39:20 INFO - 'PROMPT': '$P$G', 12:39:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:39:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:39:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:39:20 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:39:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:39:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:39:20 INFO - 'SYSTEMDRIVE': 'C:', 12:39:20 INFO - 'SYSTEMROOT': 'C:\\windows', 12:39:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:20 INFO - 'TEST1': 'testie', 12:39:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:20 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:39:20 INFO - 'USERNAME': 'cltbld', 12:39:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:39:20 INFO - 'WINDIR': 'C:\\windows', 12:39:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:39:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:39:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:21 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)) 12:39:21 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)) 12:39:21 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)) 12:39:21 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)) 12:39:21 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)) 12:39:21 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)) 12:39:21 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)) 12:39:21 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)) 12:39:21 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)) 12:39:21 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)) 12:39:21 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)) 12:39:21 INFO - Cleaning up... 12:39:21 INFO - Return code: 0 12:39:21 INFO - Installing jsonschema into virtualenv C:\slave\test-pgo\build/venv 12:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:21 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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 12:39:21 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 12:39:21 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 12:39:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:39:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:39:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:39:21 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:39:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:39:21 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:39:21 INFO - 'DCLOCATION': 'SCL3', 12:39:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:39:21 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:39:21 INFO - 'HOMEDRIVE': 'C:', 12:39:21 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:39:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:39:21 INFO - 'KTS_VERSION': '1.19c', 12:39:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:39:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:39:21 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:39:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:21 INFO - 'MOZ_NO_REMOTE': '1', 12:39:21 INFO - 'NO_EM_RESTART': '1', 12:39:21 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:39:21 INFO - 'OS': 'Windows_NT', 12:39:21 INFO - 'OURDRIVE': 'C:', 12:39:21 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;', 12:39:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:39:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:39:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:39:21 INFO - 'PROCESSOR_LEVEL': '6', 12:39:21 INFO - 'PROCESSOR_REVISION': '1e05', 12:39:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:39:21 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:39:21 INFO - 'PROMPT': '$P$G', 12:39:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:39:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:39:21 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:39:21 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:39:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:39:21 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:39:21 INFO - 'SYSTEMDRIVE': 'C:', 12:39:21 INFO - 'SYSTEMROOT': 'C:\\windows', 12:39:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:21 INFO - 'TEST1': 'testie', 12:39:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:21 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:39:21 INFO - 'USERNAME': 'cltbld', 12:39:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:39:21 INFO - 'WINDIR': 'C:\\windows', 12:39:21 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:39:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:21 INFO - Downloading/unpacking jsonschema 12:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:39:22 INFO - Downloading/unpacking functools32 (from jsonschema) 12:39: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 12:39: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 12:39:22 INFO - Downloading functools32-3.2.3-2.tar.gz 12:39:22 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\functools32\setup.py) egg_info for package functools32 12:39:22 INFO - warning: no files found matching '*.txt' 12:39:22 INFO - no previously-included directories found matching 'build' 12:39:22 INFO - no previously-included directories found matching 'dist' 12:39:22 INFO - no previously-included directories found matching '.git*' 12:39:22 INFO - Installing collected packages: jsonschema, functools32 12:39:22 INFO - Running setup.py install for functools32 12:39:22 INFO - warning: no files found matching '*.txt' 12:39:22 INFO - no previously-included directories found matching 'build' 12:39:22 INFO - no previously-included directories found matching 'dist' 12:39:22 INFO - no previously-included directories found matching '.git*' 12:39:23 INFO - Successfully installed jsonschema functools32 12:39:23 INFO - Cleaning up... 12:39:23 INFO - Return code: 0 12:39:23 INFO - Installing flake8 into virtualenv C:\slave\test-pgo\build/venv 12:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:23 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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 12:39:23 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 12:39:23 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 12:39:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:39:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:39:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:39:23 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:39:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:39:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:39:23 INFO - 'DCLOCATION': 'SCL3', 12:39:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:39:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:39:23 INFO - 'HOMEDRIVE': 'C:', 12:39:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:39:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:39:23 INFO - 'KTS_VERSION': '1.19c', 12:39:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:39:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:39:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:39:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:23 INFO - 'MOZ_NO_REMOTE': '1', 12:39:23 INFO - 'NO_EM_RESTART': '1', 12:39:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:39:23 INFO - 'OS': 'Windows_NT', 12:39:23 INFO - 'OURDRIVE': 'C:', 12:39:23 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;', 12:39:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:39:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:39:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:39:23 INFO - 'PROCESSOR_LEVEL': '6', 12:39:23 INFO - 'PROCESSOR_REVISION': '1e05', 12:39:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:39:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:39:23 INFO - 'PROMPT': '$P$G', 12:39:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:39:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:39:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:39:23 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:39:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:39:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:39:23 INFO - 'SYSTEMDRIVE': 'C:', 12:39:23 INFO - 'SYSTEMROOT': 'C:\\windows', 12:39:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:23 INFO - 'TEST1': 'testie', 12:39:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:23 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:39:23 INFO - 'USERNAME': 'cltbld', 12:39:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:39:23 INFO - 'WINDIR': 'C:\\windows', 12:39:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:39:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:23 INFO - Downloading/unpacking flake8 12:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:39:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:39:24 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 12:39:24 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 12:39:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:39:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:39:25 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 12:39:25 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 12:39:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:39:25 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 12:39:25 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 12:39:25 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\pep8\setup.py) egg_info for package pep8 12:39:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:39:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:39:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:39:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:39:25 INFO - no previously-included directories found matching 'docs\_build' 12:39:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:39:25 INFO - Running setup.py install for pep8 12:39:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:39:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:39:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:39:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:39:25 INFO - no previously-included directories found matching 'docs\_build' 12:39:25 INFO - Installing pep8-script.py script to C:\slave\test-pgo\build\venv\Scripts 12:39:25 INFO - Installing pep8.exe script to C:\slave\test-pgo\build\venv\Scripts 12:39:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:39:25 INFO - Cleaning up... 12:39:26 INFO - Return code: 0 12:39:26 INFO - Running post-action listener: _resource_record_post_action 12:39:26 INFO - Running post-action listener: _start_resource_monitoring 12:39:26 INFO - Starting resource monitoring. 12:39:26 INFO - ##### 12:39:26 INFO - ##### Running install step. 12:39:26 INFO - ##### 12:39:26 INFO - Running pre-action listener: _resource_record_pre_action 12:39:26 INFO - Running main action method: install 12:39:26 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 12:39:26 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 12:39:27 INFO - Reading from file tmpfile_stdout 12:39:27 INFO - Using _rmtree_windows ... 12:39:27 INFO - Using _rmtree_windows ... 12:39:27 INFO - Detecting whether we're running mozinstall >=1.0... 12:39:27 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 12:39:27 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 12:39:27 INFO - Reading from file tmpfile_stdout 12:39:27 INFO - Output received: 12:39:27 INFO - Usage: mozinstall-script.py [options] installer 12:39:27 INFO - Options: 12:39:27 INFO - -h, --help show this help message and exit 12:39:27 INFO - -d DEST, --destination=DEST 12:39:27 INFO - Directory to install application into. [default: 12:39:27 INFO - "C:\slave\test-pgo"] 12:39:27 INFO - --app=APP Application being installed. [default: firefox] 12:39:27 INFO - Using _rmtree_windows ... 12:39:27 INFO - Using _rmtree_windows ... 12:39:27 INFO - mkdir: C:\slave\test-pgo\build\application 12:39:27 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.0a2.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 12:39:27 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.0a2.en-US.win32.zip --destination C:\slave\test-pgo\build\application 12:39:28 INFO - Reading from file tmpfile_stdout 12:39:28 INFO - Output received: 12:39:28 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 12:39:28 INFO - Using _rmtree_windows ... 12:39:28 INFO - Using _rmtree_windows ... 12:39:28 INFO - Running post-action listener: _resource_record_post_action 12:39:28 INFO - ##### 12:39:28 INFO - ##### Running run-tests step. 12:39:28 INFO - ##### 12:39:28 INFO - Running pre-action listener: _resource_record_pre_action 12:39:28 INFO - Running main action method: run_tests 12:39:28 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 12:39:28 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 12:39:28 INFO - Python 2.7.3 12:39:28 INFO - Return code: 0 12:39:28 INFO - grabbing minidump binary from tooltool 12:39: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')]} 12:39:28 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 0x01288BF0>, '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 0x012AA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x020025C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 12:39:28 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 12:39:28 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 12:39:28 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 12:39:28 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpjmhvqw 12:39:29 INFO - INFO - File integrity verified, renaming tmpjmhvqw to win32-minidump_stackwalk.exe 12:39:29 INFO - Return code: 0 12:39:29 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 12:39:29 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 12:39:29 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 12:39:29 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 12:39:29 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 12:39:29 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\flake8', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos'] 12:39:29 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\flake8 C:\slave\test-pgo\build\tests\talos\talos 12:39:30 INFO - Return code: 0 12:39:30 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-144', '--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 12:39:30 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --branchName Mozilla-Aurora --suite svgr --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-144 --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 12:39:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:39:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:39:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 12:39:30 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 12:39:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 12:39:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 12:39:30 INFO - 'DCLOCATION': 'SCL3', 12:39:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 12:39:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:39:30 INFO - 'HOMEDRIVE': 'C:', 12:39:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 12:39:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 12:39:30 INFO - 'KTS_VERSION': '1.19c', 12:39:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:39:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 12:39:30 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 12:39:30 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 12:39:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 12:39:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 12:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:30 INFO - 'MOZ_NO_REMOTE': '1', 12:39:30 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 12:39:30 INFO - 'NO_EM_RESTART': '1', 12:39:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:39:30 INFO - 'OS': 'Windows_NT', 12:39:30 INFO - 'OURDRIVE': 'C:', 12:39: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;', 12:39:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:39:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:39:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 12:39:30 INFO - 'PROCESSOR_LEVEL': '6', 12:39:30 INFO - 'PROCESSOR_REVISION': '1e05', 12:39:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:39:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 12:39:30 INFO - 'PROMPT': '$P$G', 12:39:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 12:39:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 12:39:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:39:30 INFO - 'PWD': 'C:\\slave\\test-pgo', 12:39:30 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 12:39:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 12:39:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 12:39:30 INFO - 'SYSTEMDRIVE': 'C:', 12:39:30 INFO - 'SYSTEMROOT': 'C:\\windows', 12:39:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:30 INFO - 'TEST1': 'testie', 12:39:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 12:39:30 INFO - 'USERDOMAIN': 'T-W732-IX-144', 12:39:30 INFO - 'USERNAME': 'cltbld', 12:39:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:39:30 INFO - 'WINDIR': 'C:\\windows', 12:39:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:39:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 12:39:30 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-144', '--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 12:39:31 INFO - mozversion application_buildid: 20160229085316 12:39:31 INFO - mozversion application_changeset: 6aaf9904a81dd8635e056cacb1a4834fe13f9cd3 12:39:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:39:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:39:31 INFO - mozversion application_name: Firefox 12:39:31 INFO - mozversion application_remotingname: firefox-dev 12:39:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:39:31 INFO - mozversion application_vendor: Mozilla 12:39:31 INFO - mozversion application_version: 46.0a2 12:39:31 INFO - mozversion platform_buildid: 20160229085316 12:39:31 INFO - mozversion platform_changeset: 6aaf9904a81dd8635e056cacb1a4834fe13f9cd3 12:39:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:39:31 INFO - mozversion platform_version: 46.0a2 12:39:31 INFO - using testdate: 1456778371 12:39:31 INFO - actual date: 1456778371 12:39:31 INFO - starting webserver on 'localhost:49590' 12:39:31 INFO - SUITE-START | Running 5 tests 12:39:31 INFO - TEST-START | tsvgx 12:39:31 INFO - operating with platform_type : w7_ 12:39:31 INFO - Initialising browser for tsvgx test... 12:39:32 INFO - TEST-INFO | started process 1300 (C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpkdadpq\profile http://localhost:49590/getInfo.html) 12:39:40 INFO - PROCESS | 1300 | __metrics Screen width/height:1600/1200 12:39:40 INFO - PROCESS | 1300 | colorDepth:24 12:39:40 INFO - PROCESS | 1300 | Browser inner width/height: 1010/674 12:39:40 INFO - PROCESS | 1300 | __metrics 12:39:41 INFO - PROCESS | 1300 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:39:41 INFO - PROCESS | 1300 | console.error: 12:39:41 INFO - PROCESS | 1300 | Message: Error: Connection closed before committing the transaction. 12:39:41 INFO - PROCESS | 1300 | Stack: 12:39:41 INFO - PROCESS | 1300 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:933:23 12:39:41 INFO - PROCESS | 1300 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7 12:39:41 INFO - PROCESS | 1300 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:743:11 12:39:41 INFO - PROCESS | 1300 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:775:7 12:39:41 INFO - PROCESS | 1300 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:710:7 12:39:41 INFO - PROCESS | 1300 | TaskImpl_run@resource://gre/modules/Task.jsm:320:13 12:39:41 INFO - PROCESS | 1300 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 12:39:41 INFO - PROCESS | 1300 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 12:39:41 INFO - PROCESS | 1300 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5 12:39:41 INFO - PROCESS | 1300 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 12:39:41 INFO - PROCESS | 1300 | Barrier/this.client.addBlocker/promisetsvgx: 384.80 12:58:28 INFO - RETURN: tsvgr_opacity: 500.33 12:58:28 INFO - RETURN: tart: 3.72 12:58:28 INFO - RETURN: tscrollx: 2.90 12:58:28 INFO - RETURN: cart: 23.14 12:58:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [252.0, 219.0, 226.0, 215.0, 213.0, 228.0, 212.0, 215.0, 213.0, 214.0, 212.0, 214.0, 212.0, 222.0, 213.0, 215.0, 212.0, 214.0, 212.0, 213.0, 213.0, 215.0, 225.0, 215.0, 213.0], "unit": "ms", "name": "gearflowers.svg", "value": 213.5}, {"lowerIsBetter": true, "replicates": [69.0, 41.0, 41.0, 43.0, 41.0, 43.0, 41.0, 41.0, 41.0, 41.0, 41.0, 42.0, 41.0, 43.0, 41.0, 41.0, 42.0, 43.0, 41.0, 43.0, 41.0, 40.0, 41.0, 41.0, 41.0], "unit": "ms", "name": "composite-scale.svg", "value": 41.0}, {"lowerIsBetter": true, "replicates": [132.0, 126.0, 125.0, 126.0, 126.0, 133.0, 125.0, 130.0, 125.0, 133.0, 131.0, 124.0, 125.0, 127.0, 125.0, 125.0, 125.0, 126.0, 124.0, 125.0, 131.0, 125.0, 125.0, 132.0, 125.0], "unit": "ms", "name": "composite-scale-opacity.svg", "value": 125.0}, {"lowerIsBetter": true, "replicates": [173.0, 168.0, 187.0, 171.0, 173.0, 173.0, 171.0, 174.0, 173.0, 175.0, 175.0, 179.0, 169.0, 171.0, 181.0, 180.0, 170.0, 176.0, 169.0, 169.0, 180.0, 182.0, 169.0, 168.0, 170.0], "unit": "ms", "name": "composite-scale-rotate.svg", "value": 173.0}, {"lowerIsBetter": true, "replicates": [251.0, 242.0, 240.0, 244.0, 248.0, 240.0, 238.0, 239.0, 238.0, 250.0, 240.0, 239.0, 240.0, 240.0, 253.0, 255.0, 239.0, 240.0, 240.0, 253.0, 245.0, 240.0, 241.0, 241.0, 243.0], "unit": "ms", "name": "composite-scale-rotate-opacity.svg", "value": 240.0}, {"lowerIsBetter": true, "replicates": [478.0, 431.0, 427.0, 433.0, 439.0, 433.0, 428.0, 434.0, 435.0, 437.0, 426.0, 434.0, 430.0, 442.0, 448.0, 435.0, 434.0, 425.0, 437.0, 439.0, 439.0, 440.0, 429.0, 431.0, 433.0], "unit": "ms", "name": "hixie-001.xml", "value": 434.0}, {"lowerIsBetter": true, "replicates": [432.0, 432.0, 436.0, 431.0, 447.0, 448.0, 431.0, 428.0, 422.0, 447.0, 434.0, 436.0, 433.0, 428.0, 457.0, 430.0, 432.0, 431.0, 433.0, 460.0, 424.0, 439.0, 438.0, 433.0, 442.0], "unit": "ms", "name": "hixie-002.xml", "value": 433.0}, {"lowerIsBetter": true, "replicates": [299.0, 2672.0, 223.0, 214.0, 216.0, 222.0, 212.0, 219.0, 216.0, 214.0, 219.0, 221.0, 212.0, 218.0, 220.0, 217.0, 214.0, 220.0, 216.0, 214.0, 218.0, 223.0, 216.0, 213.0, 214.0], "unit": "ms", "name": "hixie-003.xml", "value": 216.5}, {"lowerIsBetter": true, "replicates": [1139.0, 1123.0, 1114.0, 1121.0, 1117.0, 1129.0, 1091.0, 1102.0, 1118.0, 1116.0, 1116.0, 1074.0, 1095.0, 1093.0, 1108.0, 1112.0, 1087.0, 1139.0, 1110.0, 1164.0, 1083.0, 1112.0, 1108.0, 2151.0, 1086.0], "unit": "ms", "name": "hixie-004.xml", "value": 1109.0}, {"lowerIsBetter": true, "replicates": [2454.0, 2461.0, 2449.0, 2452.0, 2445.0, 2454.0, 2451.0, 2452.0, 2445.0, 2453.0, 2454.0, 2448.0, 2536.0, 2454.0, 2442.0, 2459.0, 2452.0, 2455.0, 2468.0, 2450.0, 2458.0, 2463.0, 2459.0, 2451.0, 2446.0], "unit": "ms", "name": "hixie-005.xml", "value": 2453.5}, {"lowerIsBetter": true, "replicates": [3728.0, 3741.0, 3729.0, 3723.0, 3715.0, 3727.0, 3729.0, 3718.0, 3741.0, 3732.0, 3749.0, 3737.0, 3747.0, 3738.0, 3727.0, 3774.0, 3730.0, 3715.0, 3736.0, 3734.0, 3726.0, 3731.0, 3769.0, 3716.0, 3722.0], "unit": "ms", "name": "hixie-006.xml", "value": 3731.5}, {"lowerIsBetter": true, "replicates": [668.0, 548.0, 542.0, 547.0, 550.0, 544.0, 544.0, 548.0, 545.0, 546.0, 543.0, 548.0, 543.0, 552.0, 546.0, 555.0, 544.0, 555.0, 545.0, 553.0, 545.0, 564.0, 547.0, 548.0, 546.0], "unit": "ms", "name": "hixie-007.xml", "value": 546.0}], "name": "tsvgx", "value": 384.7983824084907}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [733.0, 717.0, 704.0, 711.0, 740.0, 70.0, 707.0, 754.0, 744.0, 717.0, 71.0, 708.0, 710.0, 736.0, 70.0, 708.0, 713.0, 706.0, 734.0, 750.0, 724.0, 712.0, 704.0, 717.0, 70.0], "unit": "ms", "name": "big-optimizable-group-opacity-2500.svg", "value": 711.0}, {"lowerIsBetter": true, "replicates": [319.0, 348.0, 354.0, 365.0, 351.0, 350.0, 350.0, 349.0, 353.0, 356.0, 353.0, 351.0, 351.0, 351.0, 352.0, 353.0, 355.0, 351.0, 352.0, 353.0, 353.0, 356.0, 352.0, 370.0, 351.0], "unit": "ms", "name": "small-group-opacity-2500.svg", "value": 352.0}], "name": "tsvgr_opacity", "value": 500.33421985737215}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1.6389358026880614, 1.5434926843643189, 1.511151724047475, 1.5291153067036678, 1.621423387191665, 1.5222185238411552, 1.5704116176914524, 1.5342255640029907, 1.5967003939902946, 3.5806540703773497, 1.5586685779932383, 1.560853207433546, 1.5480443302790323, 1.5342153199513753, 1.5409583028157552, 1.637232159225034, 1.5530567614237467, 1.5467080148061116, 1.5735821804484806, 1.5486740843454996, 1.690481500349183, 1.5674278526692778, 1.5292365676478337, 1.6486295172146388, 1.5498414325714112], "unit": "ms", "name": "simple-open-DPI1.half.TART", "value": 1.551449096997579}, {"lowerIsBetter": true, "replicates": [1.7789251612616586, 1.7368013311738837, 1.6769237944621913, 1.7150927508199536, 1.7586625502027313, 1.6921941256523132, 1.7887756513877653, 1.701510024550777, 1.7974286696589585, 3.1977085709571837, 1.7246258607527025, 1.7576649362842243, 1.7144934003417556, 1.712350285536534, 1.7000436198791402, 1.823089291723512, 1.725216224890988, 1.7358114021975979, 1.7802275310863147, 1.7053809173955212, 1.830702912035606, 1.7180687334086444, 1.759329915046692, 1.8159121896539416, 1.7128432634714488], "unit": "ms", "name": "simple-open-DPI1.all.TART", "value": 1.730513813544293}, {"lowerIsBetter": true, "replicates": [24.779999999998836, 23.81500000000233, 24.510000000002037, 24.044999999998254, 25.485000000000582, 24.22500000000582, 24.470000000001164, 23.989999999990687, 23.895000000004075, 26.11500000000524, 23.93499999999767, 23.445000000006985, 24.035000000003492, 23.88499999998021, 23.755000000004657, 24.735000000015134, 23.97500000000582, 25.514999999984866, 25.010000000009313, 24.41500000000815, 24.70999999999185, 24.554999999993015, 25.35999999998603, 24.755000000004657, 23.790000000037253], "unit": "ms", "name": "simple-open-DPI1.error.TART", "value": 24.320000000006985}, {"lowerIsBetter": true, "replicates": [1.1838265858134445, 1.1178106845003888, 1.0992947998501006, 1.14104431926614, 1.0758798183681808, 1.0702414921036474, 1.1184183700570782, 1.1118070746843631, 1.1048938433329265, 1.1462491821534564, 1.167117265137759, 1.1443406012978885, 1.1598697298765182, 1.109835408627987, 1.164630986223317, 1.1276816281617856, 1.1144359793800573, 1.1326321690690284, 1.100816167536236, 1.0850986197309673, 1.1048974922725132, 1.0962484285944984, 1.1096175582363055, 1.1288637285139047, 1.1129295407579496], "unit": "ms", "name": "simple-close-DPI1.half.TART", "value": 1.1136827600690036}, {"lowerIsBetter": true, "replicates": [1.2428876275614815, 1.2011398044713024, 1.1936330564466178, 1.3092925119143661, 1.171458197267432, 1.2027457912860833, 1.2186202343127028, 1.2093139817514043, 1.2190156952658695, 1.3078743016974812, 1.3130519643265715, 1.3097652876248924, 1.3062253721895065, 1.2122088276331697, 1.2149560246894608, 1.2140886970420381, 1.2115936682591983, 1.316152839570917, 1.2057137213317044, 1.198760544844702, 1.190084083277045, 1.181795310570998, 1.2097569087354263, 1.3130643507165294, 1.2049419471783003], "unit": "ms", "name": "simple-close-DPI1.all.TART", "value": 1.2119012479461841}, {"lowerIsBetter": true, "replicates": [16.25, 16.614999999997963, 16.07499999999709, 16.464999999996508, 17.360000000000582, 16.979999999995925, 17.414999999993597, 17.55999999999767, 17.929999999993015, 17.195000000006985, 16.779999999998836, 16.33499999999185, 17.120000000024447, 16.630000000004657, 16.51500000001397, 16.705000000016298, 16.114999999990687, 17.65999999997439, 17.630000000004657, 17.285000000003492, 18.01500000001397, 17.244999999995343, 16.92499999998836, 16.790000000037253, 17.110000000044238], "unit": "ms", "name": "simple-close-DPI1.error.TART", "value": 17.04500000002008}, {"lowerIsBetter": true, "replicates": [1.5776951851910108, 1.5792942928941283, 1.6175861325528886, 1.6101728578408558, 1.506623549894853, 1.6884613486303799, 1.5592549539901115, 1.6050742417573929, 1.591855721930935, 1.5852541270321363, 1.5480750385920208, 1.5807461134374958, 1.5910456409193066, 1.592287055433613, 2.0914752291094874, 1.5882577226586538, 1.6255716404444736, 1.5940071572996166, 1.5917347294010529, 1.5820611715316772, 1.5527700519561767, 1.5980182935794194, 1.538413683573405, 1.6073782063192792, 1.5973303020000458], "unit": "ms", "name": "icon-open-DPI1.half.TART", "value": 1.591795225665994}, {"lowerIsBetter": true, "replicates": [1.7899087071418762, 1.8106561256638656, 1.8404210866361423, 1.8586859746255737, 1.73383917046242, 1.8686267996535582, 1.7831061578297114, 1.9616146958791292, 1.848426583884419, 1.834844008624125, 1.7583950716873695, 1.8294929188790081, 1.8279900748095066, 1.8501017050466675, 2.966048624626426, 1.813654851913452, 1.8748441680389292, 1.8605173069195156, 1.8226732841559818, 1.8217242658138275, 1.7968233538345553, 1.8244862948145186, 1.8088968332777633, 1.8774530764888315, 1.8146486054075526], "unit": "ms", "name": "icon-open-DPI1.all.TART", "value": 1.8287414968442572}, {"lowerIsBetter": true, "replicates": [24.780000000000655, 25.54500000000189, 24.68499999999767, 26.770000000004075, 25.160000000003492, 24.605000000010477, 25.270000000004075, 25.364999999990687, 25.375, 25.28499999998894, 25.23499999998603, 24.70999999999185, 24.339999999996508, 25.529999999998836, 25.43499999999767, 24.329999999987194, 25.24000000001979, 25.139999999984866, 25.460000000020955, 25.320000000006985, 25.385000000009313, 26.239999999990687, 25.39499999996042, 25.544999999983702, 26.114999999990687], "unit": "ms", "name": "icon-open-DPI1.error.TART", "value": 25.342499999998836}, {"lowerIsBetter": true, "replicates": [1.1075314214596381, 1.1100532498497229, 1.0848014633232188, 1.115362744491834, 1.123510984541143, 1.12571803458686, 1.119014871930613, 1.1383544732542599, 1.0771861288035027, 1.13292581485767, 1.107693884235162, 1.12360791963281, 1.113874753507284, 1.1239322620688132, 1.1115375424806888, 1.1277606837889727, 1.1254421541991744, 1.1327488784696542, 1.1239285335957425, 1.1023740859258742, 1.1202936160911634, 1.1129553799445813, 1.1058666268984476, 1.1295639615432889, 1.0850986219801992], "unit": "ms", "name": "icon-close-DPI1.half.TART", "value": 1.119654244010888}, {"lowerIsBetter": true, "replicates": [1.215275808508173, 1.2206682195117817, 1.2083558974289659, 1.246690377745093, 1.234970071207938, 1.2497545064103848, 1.2359380083854752, 1.248108202733364, 1.2199384415920693, 1.242749786921564, 1.233520920552201, 1.229922159233285, 1.2234557496011258, 1.2318923092966703, 1.237040570932417, 1.2459133828654507, 1.2308966238894055, 1.2397643976440333, 1.2447340896892063, 1.2169631217500847, 1.2492621453280377, 1.2196136597377152, 1.2469893415567233, 1.258896778180049, 1.2368456709325013], "unit": "ms", "name": "icon-close-DPI1.all.TART", "value": 1.2363918396589884}, {"lowerIsBetter": true, "replicates": [20.18499999999949, 18.340000000000146, 16.820000000006985, 17.290000000000873, 18.720000000001164, 17.805000000007567, 17.494999999995343, 18.80000000000291, 18.020000000004075, 17.660000000003492, 18.135000000009313, 17.535000000003492, 17.55999999999767, 17.910000000003492, 19.57500000001164, 18.31500000000233, 18.110000000015134, 18.589999999996508, 18.01500000001397, 19.869999999995343, 18.97500000000582, 18.01999999996042, 18.53499999997439, 18.31500000000233, 19.01500000001397], "unit": "ms", "name": "icon-close-DPI1.error.TART", "value": 18.122500000012224}, {"lowerIsBetter": true, "replicates": [1.6983214353813845, 1.61333549188243, 1.760141994013931, 1.624500764927394, 1.617954123351309, 1.722494598644883, 1.658931609562465, 1.7128737814286177, 1.703974089201759, 1.6628593393734523, 1.7339399910684843, 1.6544059327670506, 1.6017142683267593, 1.6849107707756152, 1.6270210843690684, 1.6634847044944763, 1.6863076289494832, 1.65695127589362, 1.6840815561405127, 1.747202475865682, 1.670502541721731, 1.6581416709082468, 1.6052928980853822, 1.641028859245945, 1.6496772800173078], "unit": "ms", "name": "icon-open-DPI2.half.TART", "value": 1.6608954744679587}, {"lowerIsBetter": true, "replicates": [1.903201452008, 1.8086980167010152, 1.9856916176256283, 1.8478338813436204, 1.8689652220176085, 1.949094000663466, 1.8922276938403095, 1.9518787105574862, 1.9360386738271425, 1.868449503487914, 1.9372459306861416, 1.8824930331286263, 1.8484683226847993, 1.9473205832124667, 1.8673563673548454, 1.8570192826055263, 1.9471134796287075, 1.9296619964368416, 1.922700373749984, 1.9623029369574327, 1.8779415418119991, 1.8741326349623062, 1.847018591735674, 1.8581656722256736, 1.9112404415856545], "unit": "ms", "name": "icon-open-DPI2.all.TART", "value": 1.887360363484468}, {"lowerIsBetter": true, "replicates": [27.24999999999818, 25.330000000001746, 26.599999999998545, 25.2699999999968, 26.334999999999127, 25.669999999998254, 25.81500000000233, 26.044999999998254, 25.879999999990105, 26.320000000006985, 26.0, 27.46999999997206, 25.510000000009313, 25.394999999989523, 26.29000000000815, 24.720000000001164, 27.27499999999418, 25.14999999999418, 26.020000000018626, 25.604999999981374, 25.75, 25.25, 25.29500000004191, 24.960000000020955, 26.46500000002561], "unit": "ms", "name": "icon-open-DPI2.error.TART", "value": 25.782500000001164}, {"lowerIsBetter": true, "replicates": [1.0504299711097371, 1.0494455462152308, 1.0615148620867947, 1.060729250995391, 1.0440143996530824, 1.052126544713974, 1.0607327714972539, 1.0811732294403504, 1.0758618818265255, 1.0608188314871354, 1.0720512480647475, 1.0651642012375373, 1.0684957300071363, 1.0479339903051204, 1.0460314685648138, 1.0504683738405054, 1.0550749865146951, 1.0702023815225672, 1.057443144646558, 1.0624061549475434, 1.0584111787857267, 1.0629733423574255, 1.0444537460267007, 1.0483843191103501, 1.055867639703488], "unit": "ms", "name": "icon-close-DPI2.half.TART", "value": 1.0595702148905588}, {"lowerIsBetter": true, "replicates": [1.1575844583070674, 1.1379483419795369, 1.1678528452203387, 1.1561959765429766, 1.1614535501782928, 1.1637011130839163, 1.148087048754446, 1.1776297112401022, 1.167532845054354, 1.1613243328169058, 1.1633243944972613, 1.1461815025325113, 1.1599652931656477, 1.1659383430367425, 1.1336062801656899, 1.1649131594111004, 1.1582705593222125, 1.1574216991941506, 1.1623254296071124, 1.149375715684668, 1.1635462484269772, 1.1618775757850628, 1.1553337960591856, 1.174335417564976, 1.1683454618069797], "unit": "ms", "name": "icon-close-DPI2.all.TART", "value": 1.161665562981678}, {"lowerIsBetter": true, "replicates": [17.715000000000146, 18.910000000003492, 18.679999999993015, 18.6449999999968, 19.159999999996217, 19.52500000000873, 18.160000000003492, 19.560000000012224, 18.68499999999767, 18.544999999998254, 19.035000000003492, 17.869999999995343, 19.43499999999767, 18.480000000010477, 18.389999999984866, 19.230000000010477, 17.94499999997788, 20.99000000001979, 19.919999999983702, 19.45999999999185, 20.24000000001979, 19.320000000006985, 18.474999999976717, 19.53000000002794, 19.945000000006985], "unit": "ms", "name": "icon-close-DPI2.error.TART", "value": 19.097499999999854}, {"lowerIsBetter": true, "replicates": [1.0316414370068483, 1.032515721661704, 1.0304894553763526, 1.0306197330355644, 1.0419560337925817, 1.036362145935093, 1.0533274043690075, 1.0358894014997142, 1.016129390189522, 1.0175407492277915, 1.037164726772824, 1.0191238601650812, 1.0351796889943736, 1.0380987734407991, 1.0330642791731017, 1.030028689751583, 1.027396903506347, 1.0302937500244749, 1.050576589866118, 1.0315077251621656, 1.0300094501248427, 1.0229638245253436, 1.0769779810281557, 1.0299545957573824, 1.4648161953008627], "unit": "ms", "name": "iconFade-close-DPI2.half.TART", "value": 1.0320117234119348}, {"lowerIsBetter": true, "replicates": [1.0381128122763974, 1.039285374273147, 1.0454396860556558, 1.0466470243157566, 1.0467213993673925, 1.0444899874952343, 1.065845106562523, 1.0483490647496403, 1.04110764312957, 1.0411838266347022, 1.0404356502528702, 1.0352363483111064, 1.051153625837818, 1.0450565090628483, 1.0506566878897032, 1.048278143814018, 1.04128025465482, 1.0405367892235518, 1.0526992968610815, 1.043658276577167, 1.0407527868768998, 1.040934500151447, 1.0717383496772308, 1.0416246438774828, 1.2782849482782594], "unit": "ms", "name": "iconFade-close-DPI2.all.TART", "value": 1.0447732482790413}, {"lowerIsBetter": true, "replicates": [2.5450000000018917, 2.899999999997817, 2.2999999999956344, 2.305000000000291, 2.429999999993015, 2.8950000000040745, 3.5, 2.7549999999901047, 3.095000000001164, 2.2149999999965075, 3.1000000000058208, 2.8800000000046566, 2.345000000001164, 3.1350000000093132, 2.4549999999871943, 2.7749999999941792, 2.2149999999965075, 3.165000000008149, 3.540000000008149, 2.7399999999906868, 2.709999999991851, 3.205000000016298, 2.5100000000093132, 2.3399999999674037, 2.6049999999813735], "unit": "ms", "name": "iconFade-close-DPI2.error.TART", "value": 2.7474999999903957}, {"lowerIsBetter": true, "replicates": [1.5804725836401117, 1.650373956135341, 1.6568415641784668, 1.5977463010284636, 1.5955641808575147, 1.6068022184901767, 1.6087488730748494, 1.6248036488680773, 1.6116395162211523, 1.6230260812573962, 1.6024289263619318, 1.7112079122487236, 1.6015755997763739, 1.6248955860943861, 1.5857117682287138, 1.5791943465193656, 1.6455124247242028, 1.5965162825911012, 1.6281568567517777, 1.6323699951171875, 1.5950802433980655, 1.5985996226469676, 1.6715710577757463, 1.6135915004544787, 1.5985462897353702], "unit": "ms", "name": "iconFade-open-DPI2.half.TART", "value": 1.6101941946480007}, {"lowerIsBetter": true, "replicates": [1.540345568530607, 1.5588256072678022, 1.558455757300059, 1.5408923215423989, 1.521879341867235, 1.5581972010930378, 1.4987713544599472, 1.613554163939423, 1.5676059962919093, 1.5736210990596462, 1.5609131123395574, 1.5987071688861063, 1.5607816764972353, 1.5685286141882007, 1.5471405296136211, 1.531454655292787, 1.5943858835795155, 1.5399539470672607, 1.5705465324021675, 1.5642737198196002, 1.5370882906411822, 1.5656860074740928, 1.5846864941979752, 1.569381572104789, 1.5477788314124605], "unit": "ms", "name": "iconFade-open-DPI2.all.TART", "value": 1.5608473944183965}, {"lowerIsBetter": true, "replicates": [2.6850000000013097, 2.25, 3.819999999999709, 2.680000000000291, 2.8249999999970896, 3.695000000006985, 2.3150000000023283, 2.3500000000058208, 3.5749999999970896, 2.789999999993597, 2.570000000006985, 3.3949999999895226, 2.35999999998603, 3.6849999999976717, 3.554999999993015, 2.8299999999871943, 2.8550000000104774, 4.2000000000116415, 2.4349999999976717, 3.7950000000128057, 3.625, 3.205000000016298, 2.89000000001397, 2.235000000044238, 3.7850000000325963], "unit": "ms", "name": "iconFade-open-DPI2.error.TART", "value": 2.8725000000122236}, {"lowerIsBetter": true, "replicates": [2.3616580987463194, 1.7168040976804846, 1.7710518891994769, 1.7205287627319792, 1.744974130933935, 1.7656168648690889, 1.7193022752875713, 1.7412130032012711, 1.7226665464799795, 1.7473828250711614, 1.7368629476917323, 1.735418673771531, 1.7856661460616372, 1.833492842931596, 1.78759327851809, 1.8101013953869158, 1.7340660913666683, 1.750344258366209, 1.734914308163657, 1.7313437194966559, 1.7952200522789588, 1.7050922022146338, 1.7508911707184531, 1.7518744360316882, 1.7518569736769705], "unit": "ms", "name": "newtab-open-preload-no.half.TART", "value": 1.7461784780025482}, {"lowerIsBetter": true, "replicates": [4.012192627740284, 2.5171953306614774, 2.5994211092139734, 2.5417540356664374, 2.5671358498016206, 2.5972528596117037, 2.538001525520098, 2.641560372041196, 2.570208990573883, 2.5912563198744647, 2.564065038561821, 2.553061055664969, 2.6365488138125865, 2.71346473445495, 2.588759872317314, 2.616596664701189, 2.5551369013172565, 2.6070195939203704, 2.5607447556339866, 2.570055401325226, 2.6641411694387593, 2.4975742298422507, 2.5873813289403915, 2.5444952603613977, 2.5929224133491515], "unit": "ms", "name": "newtab-open-preload-no.all.TART", "value": 2.5787951597571372}, {"lowerIsBetter": true, "replicates": [34.06500000000233, 29.95499999999811, 27.660000000003492, 27.775000000001455, 30.795000000012806, 27.44999999999709, 26.80000000000291, 29.169999999998254, 27.394999999989523, 26.84000000001106, 26.894999999989523, 28.269999999989523, 28.739999999990687, 30.795000000012806, 29.074999999982538, 26.869999999995343, 28.845000000001164, 28.369999999995343, 29.0, 27.404999999998836, 26.20000000001164, 27.81500000000233, 28.96500000002561, 27.274999999965075, 29.779999999969732], "unit": "ms", "name": "newtab-open-preload-no.error.TART", "value": 28.042499999995925}, {"lowerIsBetter": true, "replicates": [1.7737989462338961, 1.7272573122337682, 1.7999965641647577, 1.7244031215781597, 1.774691000351539, 1.7519791595863574, 1.7765637141007644, 1.763155789086313, 1.754905680815379, 1.7510657093741677, 1.7793816603147066, 1.759990728262699, 1.7913387335263766, 1.7684968330643394, 1.7837296834358802, 1.7584431008859114, 1.7683921058972676, 1.7522933374751697, 1.7693230112393696, 1.7467021183534102, 1.7674379312630855, 1.7776743595416729, 1.7568547400561245, 1.7509667963692637, 1.7563194849274375], "unit": "ms", "name": "newtab-open-preload-yes.half.TART", "value": 1.761573258674506}, {"lowerIsBetter": true, "replicates": [2.0004548523575068, 1.944327752326281, 1.9834233649479327, 2.0182594727343464, 2.008072995764064, 2.001972840167582, 2.0118554957269685, 2.031605638387635, 1.960013719705435, 2.0030858288519084, 1.9943468924611807, 1.975555908310321, 2.063561433792114, 2.0133521829064436, 1.9987208601087332, 1.9828131298224132, 1.9964438797906041, 1.9873823916265208, 1.999701856635511, 1.9978574773137883, 2.0159017061814666, 2.0105227530002594, 1.996875150259151, 1.9776421083853795, 1.99350848655368], "unit": "ms", "name": "newtab-open-preload-yes.all.TART", "value": 1.9982891687112607}, {"lowerIsBetter": true, "replicates": [35.68000000000029, 34.90500000000611, 36.00499999999738, 36.33999999999651, 35.23000000001048, 36.59999999999127, 36.129999999990105, 39.279999999998836, 35.07499999999709, 37.0399999999936, 35.59999999997672, 35.45999999999185, 38.29000000000815, 36.0449999999837, 35.63500000000931, 36.04999999998836, 35.829999999987194, 36.85999999998603, 36.085000000020955, 38.14499999998952, 38.21500000002561, 37.80999999999767, 37.945000000006985, 37.20000000001164, 37.65999999997439], "unit": "ms", "name": "newtab-open-preload-yes.error.TART", "value": 36.234999999993306}], "name": "tart", "value": 3.7245432955129525}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [3.1241715976331363, 2.088343195266272, 2.1371301775147935, 2.1118047337278107, 2.094260355029586, 2.103491124260355, 2.1166863905325446, 2.1134023668639053, 2.110739644970414, 2.115532544378698, 2.1116863905325447, 2.109260355029586, 2.0946745562130182, 2.1113905325443785, 2.1055917159763315, 2.1038165680473377, 2.118224852071006, 2.0975443786982244, 2.1151775147928995, 2.118491124260355, 2.1183136094674557, 2.100207100591716, 2.113076923076923, 2.1099112426035505, 2.1175443786982244], "unit": "ms", "name": "tiled.html", "value": 2.1115384615384616}, {"lowerIsBetter": true, "replicates": [3.1444970414201188, 3.156715976331361, 3.1304142011834326, 3.1450591715976333, 3.1116568047337276, 3.1612721893491122, 3.13905325443787, 3.143224852071006, 3.1313017751479295, 3.146568047337278, 3.137100591715977, 3.133224852071006, 3.131686390532545, 3.1291420118343196, 3.138550295857988, 3.1305621301775153, 3.1401479289940832, 3.1392307692307697, 3.1394674556213014, 3.1478402366863913, 3.1400887573964495, 3.131390532544379, 3.13112426035503, 3.1487869822485215, 3.12189349112426], "unit": "ms", "name": "tiled-fixed.html", "value": 3.138801775147929}, {"lowerIsBetter": true, "replicates": [2.4438165680473376, 2.367899408284024, 2.4023076923076925, 2.395473372781065, 2.3921893491124266, 2.371153846153846, 2.380828402366864, 2.3955325443786983, 2.395207100591716, 2.395059171597633, 2.383609467455621, 2.373668639053254, 2.390769230769231, 2.4002958579881657, 2.373165680473373, 2.3834023668639053, 2.381420118343195, 2.378698224852071, 2.3693786982248524, 2.381183431952663, 2.395414201183432, 2.39594674556213, 2.384644970414201, 2.3727514792899407, 2.3800887573964498], "unit": "ms", "name": "tiled-downscale.html", "value": 2.38241124260355}, {"lowerIsBetter": true, "replicates": [3.5373964497041417, 3.5353846153846162, 3.525532544378698, 3.5353254437869817, 3.5316863905325446, 3.526183431952663, 3.5228994082840237, 3.5398816568047344, 3.5241420118343196, 3.507218934911242, 3.513786982248521, 3.538136094674556, 3.5558284023668643, 3.5141124260355037, 3.5398520710059165, 3.538786982248521, 3.5528994082840235, 3.5125147928994083, 3.543195266272189, 3.559674556213017, 3.5113905325443793, 3.5314201183431955, 3.524437869822485, 3.5371893491124258, 3.5273076923076925], "unit": "ms", "name": "tiled-fixed-downscale.html", "value": 3.529363905325444}, {"lowerIsBetter": true, "replicates": [3.354883720930233, 3.2545930232558145, 3.369767441860466, 3.3475581395348835, 3.3577325581395354, 3.3143023255813957, 3.330290697674419, 3.3362209302325576, 3.3470930232558143, 3.333837209302325, 3.3088953488372095, 3.341162790697674, 3.3448255813953494, 3.3213372093023255, 3.3256395348837207, 3.3123837209302325, 3.343546511627907, 3.346337209302326, 3.36656976744186, 3.2873255813953492, 3.3070348837209305, 3.3298255813953483, 3.3218604651162793, 3.3159302325581397, 3.3249418604651164], "unit": "ms", "name": "iframe.svg", "value": 3.3277325581395347}, {"lowerIsBetter": true, "replicates": [3.1122254335260116, 3.125231213872832, 3.097167630057804, 3.10635838150289, 3.1176300578034684, 3.089277456647399, 8.246589595375722, 3.0996531791907507, 3.0979479768786122, 3.0908092485549137, 3.0956936416184977, 3.1139017341040467, 3.0973410404624278, 3.079653179190751, 3.1040173410404632, 3.10393063583815, 3.093323699421966, 3.0851156069364163, 3.112658959537573, 3.0856647398843933, 3.092052023121387, 3.1045664739884393, 3.102543352601156, 3.096763005780346, 3.102514450867052], "unit": "ms", "name": "reader.htm", "value": 3.0976445086705198}], "name": "tscrollx", "value": 2.896290709395704}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [5.033870381116867, 6.261415373195302, 5.0367750979605175, 4.938069633075169, 4.949443260828654, 4.793229727517991, 4.952058088211786, 6.208581030368805, 4.914005818821135, 4.938910768145607, 4.805389642715454, 7.766422060819773, 4.873997049672263, 4.998649710700626, 4.840625887825375, 5.0329168410528276, 4.948766708374023, 4.86123373962584, 4.918760152090163, 5.030795730295635, 4.915633229982285, 4.9034002394903276, 4.9728121643974665, 4.91656586102077, 4.791510933921451], "unit": "ms", "name": "1-customize-enter.half.TART", "value": 4.938490200610388}, {"lowerIsBetter": true, "replicates": [10.734995258891065, 9.74324901898702, 10.180417576941048, 9.904895279465652, 9.536629626917284, 9.806638921179422, 9.65962915193467, 9.902441661532333, 9.908257783913031, 10.085008580510209, 10.12907479405403, 12.645990625023842, 10.011543388773756, 10.242522111753138, 9.852642280714852, 9.991369573081412, 10.040071626988853, 9.869605171971205, 10.014531036702598, 10.06536860436928, 10.047751511015543, 10.09724144935608, 10.069676660909884, 9.698997911952791, 9.694326099895296], "unit": "ms", "name": "1-customize-enter.all.TART", "value": 10.001456480927583}, {"lowerIsBetter": true, "replicates": [322.8850000000002, 217.48999999999978, 225.88999999999942, 214.4449999999997, 218.52999999999884, 210.68999999999505, 214.02499999999418, 214.2899999999936, 214.70999999999913, 221.9300000000003, 213.5949999999939, 317.17000000000553, 218.94000000000233, 228.36500000000524, 222.2149999999965, 218.3249999999971, 220.20999999999185, 213.13999999999942, 218.9950000000099, 221.04000000000815, 220.4100000000035, 212.31500000000233, 221.23500000000058, 215.7600000000093, 215.64500000000407], "unit": "ms", "name": "1-customize-enter.error.TART", "value": 218.42749999999796}, {"lowerIsBetter": true, "replicates": [7.930162702287946, 7.598992986338479, 8.044758413519178, 8.01115872178759, 7.983126989432743, 8.780350634684929, 8.668134982769306, 8.013325665678297, 8.077041583401817, 7.697789907455444, 7.861317685672215, 7.53908965417317, 8.036283084324428, 8.023528780256, 8.059350269181389, 8.102056298937116, 7.891296880585807, 7.848152084009988, 8.113329299858638, 8.04514251436506, 7.740194312163761, 7.86093373809542, 8.05945988212313, 7.805089686598096, 7.859589721475329], "unit": "ms", "name": "2-customize-exit.half.TART", "value": 8.012242193732943}, {"lowerIsBetter": true, "replicates": [10.01125274764167, 9.45525201998259, 10.126080870628357, 10.092289168494089, 9.865664816564983, 10.255685337952205, 10.14331571261088, 9.963082495662901, 9.98254292011261, 9.498812385507533, 9.990310662133354, 9.162791683123661, 9.49884347013525, 10.30909367970058, 9.853611535496182, 10.146564524514334, 10.070456314086915, 9.781974782546362, 10.05270313223203, 10.111489043916976, 9.765773170703167, 9.826049019893011, 9.958655854066214, 9.79654473549611, 9.954101300901836], "unit": "ms", "name": "2-customize-exit.all.TART", "value": 9.960869174864557}, {"lowerIsBetter": true, "replicates": [180.72999999999956, 179.5550000000003, 174.42499999999927, 173.63000000000102, 176.08499999999913, 180.63500000000204, 183.61499999999796, 178.9250000000029, 169.91500000000087, 171.59999999999854, 169.29499999999825, 178.01499999999942, 171.30999999999767, 182.86500000000524, 175.24499999999534, 175.81500000000233, 172.6149999999907, 171.8000000000029, 182.45500000000175, 173.61999999999534, 181.54000000000815, 174.9499999999971, 179.01499999999942, 184.68499999999767, 178.13000000000466], "unit": "ms", "name": "2-customize-exit.error.TART", "value": 175.95000000000073}, {"lowerIsBetter": true, "replicates": [10.080335903167725, 10.855241394042968, 9.522210814736106, 9.463459014892578, 9.561169017444957, 9.423942392522639, 10.316340351104737, 10.134825134277344, 10.184283828735351, 10.20728521347046, 10.03809642791748, 9.401565985246139, 10.124841117858887, 10.194843673706055, 10.14857234954834, 10.560907936096191, 10.66243667602539, 10.32367467880249, 10.200527000427247, 10.097807788848877, 9.874580209905451, 9.972394509748979, 9.731000293384898, 9.600196925076572, 10.026922035217286], "unit": "ms", "name": "3-customize-enter-css.half.TART", "value": 10.111324453353882}, {"lowerIsBetter": true, "replicates": [13.607211208343506, 13.871679055063348, 12.574045544578915, 12.766427539643788, 12.828507150922503, 12.6884396870931, 13.418688249588012, 13.755453205108642, 13.50856261253357, 13.56867733001709, 13.416864347457885, 12.780178478785924, 13.412966585159301, 13.60713438987732, 14.033624498467697, 14.075116308111893, 14.151107135571932, 13.742301511764527, 13.559557247161866, 13.488594913482666, 13.219221019744873, 13.446796751022339, 13.044935181027366, 12.778002375648136, 13.392077255249024], "unit": "ms", "name": "3-customize-enter-css.all.TART", "value": 13.432742500305174}, {"lowerIsBetter": true, "replicates": [71.97000000000116, 63.36499999999796, 63.970000000001164, 67.8849999999984, 69.02999999999884, 66.4800000000032, 68.34999999999854, 74.94999999999709, 69.95000000000437, 71.26499999999942, 68.125, 68.34500000000116, 68.13500000000931, 72.08999999999651, 66.42999999999302, 67.27500000000873, 68.55499999999302, 74.61999999999534, 70.95000000001164, 69.5850000000064, 64.29000000000815, 68.68499999999767, 73.67999999999302, 68.07499999999709, 67.6649999999936], "unit": "ms", "name": "3-customize-enter-css.error.TART", "value": 68.34749999999985}], "name": "cart", "value": 23.14281526649035}]} 12:58:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tscrollx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[287,52,25]]", "result": "2.90"}, "tsvgr_opacity": {"url": "http://graphs.mozilla.org/graph.html#tests=[[225,52,25]]", "result": "500.33"}, "tart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[293,52,25]]", "result": "3.72"}, "cart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[309,52,25]]", "result": "23.14"}, "tsvgx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[281,52,25]]", "result": "384.80"}}} 12:58:28 INFO - Return code: 0 12:58:28 INFO - Validating PERFHERDER_DATA against C:\slave\test-pgo\build\tests\talos\treeherder-schemas\performance-artifact.json 12:58:28 INFO - # TBPL SUCCESS # 12:58:28 INFO - Running post-action listener: _resource_record_post_action 12:58:28 INFO - Running post-run listener: _resource_record_post_run 12:58:29 INFO - Total resource usage - Wall time: 1141s; CPU: 8.0%; Read bytes: 192135680; Write bytes: 511259136; Read time: 2412780; Write time: 9239210 12:58:29 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 20480; Read time: 0; Write time: 90 12:58:29 INFO - run-tests - Wall time: 1140s; CPU: 8.0%; Read bytes: 192135680; Write bytes: 486297088; Read time: 2412780; Write time: 9174110 12:58:29 INFO - Running post-run listener: _upload_blobber_files 12:58:29 INFO - Blob upload gear active. 12:58:29 INFO - Preparing to upload files from C:\slave\test-pgo\build\blobber_upload_dir. 12:58:29 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 12:58:29 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-Aurora', '-d', 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test-pgo\\build\\uploaded_files.json'] 12:58:29 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-Aurora -d C:\slave\test-pgo\build\blobber_upload_dir --output-manifest C:\slave\test-pgo\build\uploaded_files.json 12:58:30 INFO - (blobuploader) - INFO - Open directory for files ... 12:58:30 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log ... 12:58:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:30 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_errorsummary.log: uploaded 12:58:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:30 INFO - (blobuploader) - INFO - Done attempting. 12:58:30 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log ... 12:58:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:33 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_raw.log: uploaded 12:58:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:33 INFO - (blobuploader) - INFO - Done attempting. 12:58:33 INFO - (blobuploader) - INFO - Iteration through files over. 12:58:33 INFO - Return code: 0 12:58:33 INFO - rmtree: C:\slave\test-pgo\build\uploaded_files.json 12:58:33 INFO - Using _rmtree_windows ... 12:58:33 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build\\uploaded_files.json',), kwargs: {}, attempt #1 12:58:33 INFO - Setting buildbot property blobber_files to {"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/146fa7b8b57a99c65dba66f93d15ee1666807ba1651001128a23b2a1021ce7b7335a2fd128f08e373193ccc2cd3f1a7353b25940d66598107dfd596da1bfa3f0", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/941fbef29af323276f09fe4441e6567f5547cc0deb19b0ff11a1f181432dc7a1db885620e0dace2f3d38b77e3939d3a24ee83fac3d3303daae0d07bf2aa5769b"} 12:58:33 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test-pgo\properties\blobber_files 12:58:33 INFO - Writing to file C:\slave\test-pgo\properties\blobber_files 12:58:33 INFO - Contents: 12:58:33 INFO - blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/146fa7b8b57a99c65dba66f93d15ee1666807ba1651001128a23b2a1021ce7b7335a2fd128f08e373193ccc2cd3f1a7353b25940d66598107dfd596da1bfa3f0", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/941fbef29af323276f09fe4441e6567f5547cc0deb19b0ff11a1f181432dc7a1db885620e0dace2f3d38b77e3939d3a24ee83fac3d3303daae0d07bf2aa5769b"} 12:58:33 INFO - Running post-run listener: copy_logs_to_upload_dir 12:58:33 INFO - Copying logs to upload dir... 12:58:33 INFO - mkdir: C:\slave\test-pgo\build\upload\logs 12:58:33 INFO - Copying logs to upload dir... 12:58:33 INFO - Using _rmtree_windows ... 12:58:33 INFO - Using _rmtree_windows ... 12:58:33 INFO - Using _rmtree_windows ... 12:58:33 INFO - Using _rmtree_windows ... 12:58:33 INFO - Using _rmtree_windows ... 12:58:33 INFO - Using _rmtree_windows ... 12:58:33 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1355.055000 ========= master_lag: 0.56 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 35 secs) (at 2016-02-29 12:58:33.934959) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-29 12:58:33.935780) ========= '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-144 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-144 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-144 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-Aurora/sha512/146fa7b8b57a99c65dba66f93d15ee1666807ba1651001128a23b2a1021ce7b7335a2fd128f08e373193ccc2cd3f1a7353b25940d66598107dfd596da1bfa3f0", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/941fbef29af323276f09fe4441e6567f5547cc0deb19b0ff11a1f181432dc7a1db885620e0dace2f3d38b77e3939d3a24ee83fac3d3303daae0d07bf2aa5769b"} build_url:https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/HVX4h8cQTcCQsEILv7Se6Q/artifacts/public/build/firefox-46.0a2.en-US.win32.zip' blobber_files: '{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/146fa7b8b57a99c65dba66f93d15ee1666807ba1651001128a23b2a1021ce7b7335a2fd128f08e373193ccc2cd3f1a7353b25940d66598107dfd596da1bfa3f0", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/941fbef29af323276f09fe4441e6567f5547cc0deb19b0ff11a1f181432dc7a1db885620e0dace2f3d38b77e3939d3a24ee83fac3d3303daae0d07bf2aa5769b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-29 12:58:34.061648) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-02-29 12:58:34.061945) ========= '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-144 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-144 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-144 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: 2.34 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-02-29 12:58:36.505120) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-02-29 12:58:36.505458) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-02-29 12:58:37.428984) ========= ========= Total master_lag: 3.14 =========