builder: autoland-win32-debug slave: b-2008-spot-002 starttime: 1482912304.28 results: failure (2) buildid: 20161227235136 builduid: e042f36309ad4ae787d4cdca52b6a8af revision: 21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:04.283563) ========= master: http://buildbot-master70.bb.releng.use1.mozilla.com:8001/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:04.283984) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:04.284261) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:04.768088) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-12-28 00:05:04.768441) ========= '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:\builds\moz2_slave\autoland-w32-d-000000000000000\. (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 APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv BUILDBOT_PATH=C:\mozilla-build\buildbotve CHOCOLATEYINSTALL=C:\ProgramData\chocolatey COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=B-2008-SPOT-002 COMSPEC=C:\Windows\system32\cmd.exe CVS_RSH=ssh DXSDK_DIR=C:\Program Files (x86)\Microsoft DirectX SDK (June 2010)\ EDITOR=emacs.exe FP_NO_HOST_CHECK=NO GIT_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/git-shared HG_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/hg-shared HOME=c:/Users/cltbld HOMEDRIVE=C: HOMEPATH=\ HOSTTYPE=i686 IDLEIZER_HALT_ON_IDLE=true INPUTRC=C:/mozilla-build/msys/etc/inputrc LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOG="c:\runner\buildbot-startup.log" LOGNAME=cltbld LOGONSERVER=\\B-2008-SPOT-002 MACHTYPE=i686-pc-msys MAKE_MODE=unix MOZBUILDDIR=C:\mozilla-build MOZILLABUILD=C:\mozilla-build MOZ_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=8 OLDPWD=c:/Users/cltbld OS=Windows_NT OSTYPE=msys OS_VERSION=2008 PATH=C:\mozilla-build\msys\local\bin;c:\mozilla-build\wget;c:\mozilla-build\7zip;c:\mozilla-build\blat261\full;c:\mozilla-build\python;c:\mozilla-build\svn-win32-1.6.3\bin;c:\mozilla-build\upx203w;c:\mozilla-build\emacs-24.3\bin;c:\mozilla-build\info-zip;c:\mozilla-build\nsis-2.46u;c:\mozilla-build\nsis-3.0a2;c:\mozilla-build\wix-351728;c:\mozilla-build\hg;c:\mozilla-build\python\Scripts;c:\mozilla-build\kdiff3;c:\mozilla-build\yasm;c:\mozilla-build\mozmake;.;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;c:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;c:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;c:\Program Files (x86)\Puppet Labs\Puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;c:\Windows\system32;c:\Windows;c:\Windows\System32\Wbem;c:\Windows\System32\WindowsPowerShell\v1.0\;c:\Program Files\Amazon\cfn-bootstrap\;c:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;c:\Program Files\Microsoft SQL Server\110\Tools\Binn\;c:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;c:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\mozilla-build\msys\bin;c:\mozilla-build\python27\scripts;c:\mozilla-build\wget;c:\mozilla-build\info-zip;c:\mozilla-build;c:\mozilla-build\vim\vim72;c:\mozilla-build\python27;c:\mozilla-build\hg;c:\mozilla-build\buildbotve\Scripts;c:\ProgramData\chocolatey\bin;.;c:\opt\runner;c:\mozilla-build\buildbotve;c:\mozilla-build\python27;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;.;C:\mozilla-build\msys\local\bin;c:\mozilla-build\moztools-x64\bin;c:\mozilla-build\vim\vim72 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 2, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3f02 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PS1=\[\033]0;$MSYSTEM:\w\007 \033[32m\]\u@\h \[\033[33m\w\033[0m\] $ PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files (x86)\AWS Tools\PowerShell\ PUBLIC=C:\Users\Public PWD=c:/Users/cltbld RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg SHELL=C:/mozilla-build/msys/bin/sh SHLVL=1 SSH_AGENT_PID=4016 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TMP=C:/Users/cltbld/AppData/Local/Temp USERDOMAIN=B-2008-SPOT-002 USERNAME=cltbld USERPROFILE=C:\Users\cltbld VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\ VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\ VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\ WINDIR=C:\Windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log WIX_351728_PATH=c:/mozilla-build/wix-351728 _=C:\mozilla-build\buildbotve\Scripts\python using PTY: False --2016-12-28 00:05:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.36K=1.3s 2016-12-28 00:05:10 (9.36 KB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=6.891000 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-12-28 00:05:11.783539) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:11.784477) ========= 'rm' '-rf' 'scripts' 'properties' in dir c:\builds\moz2_slave\autoland-w32-d-000000000000000\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv BUILDBOT_PATH=C:\mozilla-build\buildbotve CHOCOLATEYINSTALL=C:\ProgramData\chocolatey COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=B-2008-SPOT-002 COMSPEC=C:\Windows\system32\cmd.exe CVS_RSH=ssh DXSDK_DIR=C:\Program Files (x86)\Microsoft DirectX SDK (June 2010)\ EDITOR=emacs.exe FP_NO_HOST_CHECK=NO GIT_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/git-shared HG_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/hg-shared HOME=c:/Users/cltbld HOMEDRIVE=C: HOMEPATH=\ HOSTTYPE=i686 IDLEIZER_HALT_ON_IDLE=true INPUTRC=C:/mozilla-build/msys/etc/inputrc LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOG="c:\runner\buildbot-startup.log" LOGNAME=cltbld LOGONSERVER=\\B-2008-SPOT-002 MACHTYPE=i686-pc-msys MAKE_MODE=unix MOZBUILDDIR=C:\mozilla-build MOZILLABUILD=C:\mozilla-build MOZ_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=8 OLDPWD=c:/Users/cltbld OS=Windows_NT OSTYPE=msys OS_VERSION=2008 PATH=C:\mozilla-build\msys\local\bin;c:\mozilla-build\wget;c:\mozilla-build\7zip;c:\mozilla-build\blat261\full;c:\mozilla-build\python;c:\mozilla-build\svn-win32-1.6.3\bin;c:\mozilla-build\upx203w;c:\mozilla-build\emacs-24.3\bin;c:\mozilla-build\info-zip;c:\mozilla-build\nsis-2.46u;c:\mozilla-build\nsis-3.0a2;c:\mozilla-build\wix-351728;c:\mozilla-build\hg;c:\mozilla-build\python\Scripts;c:\mozilla-build\kdiff3;c:\mozilla-build\yasm;c:\mozilla-build\mozmake;.;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;c:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;c:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;c:\Program Files (x86)\Puppet Labs\Puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;c:\Windows\system32;c:\Windows;c:\Windows\System32\Wbem;c:\Windows\System32\WindowsPowerShell\v1.0\;c:\Program Files\Amazon\cfn-bootstrap\;c:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;c:\Program Files\Microsoft SQL Server\110\Tools\Binn\;c:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;c:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\mozilla-build\msys\bin;c:\mozilla-build\python27\scripts;c:\mozilla-build\wget;c:\mozilla-build\info-zip;c:\mozilla-build;c:\mozilla-build\vim\vim72;c:\mozilla-build\python27;c:\mozilla-build\hg;c:\mozilla-build\buildbotve\Scripts;c:\ProgramData\chocolatey\bin;.;c:\opt\runner;c:\mozilla-build\buildbotve;c:\mozilla-build\python27;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;.;C:\mozilla-build\msys\local\bin;c:\mozilla-build\moztools-x64\bin;c:\mozilla-build\vim\vim72 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 2, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3f02 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PS1=\[\033]0;$MSYSTEM:\w\007 \033[32m\]\u@\h \[\033[33m\w\033[0m\] $ PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files (x86)\AWS Tools\PowerShell\ PUBLIC=C:\Users\Public PWD=c:/Users/cltbld RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg SHELL=C:/mozilla-build/msys/bin/sh SHLVL=1 SSH_AGENT_PID=4016 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TMP=C:/Users/cltbld/AppData/Local/Temp USERDOMAIN=B-2008-SPOT-002 USERNAME=cltbld USERPROFILE=C:\Users\cltbld VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\ VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\ VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\ WINDIR=C:\Windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log WIX_351728_PATH=c:/mozilla-build/wix-351728 _=C:\mozilla-build\buildbotve\Scripts\python using PTY: False program finished with exit code 0 elapsedTime=0.106000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:11.897138) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2016-12-28 00:05:11.897947) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 --destination scripts --debug' in dir c:\builds\moz2_slave\autoland-w32-d-000000000000000\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv BUILDBOT_PATH=C:\mozilla-build\buildbotve CHOCOLATEYINSTALL=C:\ProgramData\chocolatey COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=B-2008-SPOT-002 COMSPEC=C:\Windows\system32\cmd.exe CVS_RSH=ssh DXSDK_DIR=C:\Program Files (x86)\Microsoft DirectX SDK (June 2010)\ EDITOR=emacs.exe FP_NO_HOST_CHECK=NO GIT_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/git-shared HG_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/hg-shared HOME=c:/Users/cltbld HOMEDRIVE=C: HOMEPATH=\ HOSTTYPE=i686 IDLEIZER_HALT_ON_IDLE=true INPUTRC=C:/mozilla-build/msys/etc/inputrc LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOG="c:\runner\buildbot-startup.log" LOGNAME=cltbld LOGONSERVER=\\B-2008-SPOT-002 MACHTYPE=i686-pc-msys MAKE_MODE=unix MOZBUILDDIR=C:\mozilla-build MOZILLABUILD=C:\mozilla-build MOZ_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=8 OLDPWD=c:/Users/cltbld OS=Windows_NT OSTYPE=msys OS_VERSION=2008 PATH=C:\mozilla-build\msys\local\bin;c:\mozilla-build\wget;c:\mozilla-build\7zip;c:\mozilla-build\blat261\full;c:\mozilla-build\python;c:\mozilla-build\svn-win32-1.6.3\bin;c:\mozilla-build\upx203w;c:\mozilla-build\emacs-24.3\bin;c:\mozilla-build\info-zip;c:\mozilla-build\nsis-2.46u;c:\mozilla-build\nsis-3.0a2;c:\mozilla-build\wix-351728;c:\mozilla-build\hg;c:\mozilla-build\python\Scripts;c:\mozilla-build\kdiff3;c:\mozilla-build\yasm;c:\mozilla-build\mozmake;.;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;c:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;c:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;c:\Program Files (x86)\Puppet Labs\Puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;c:\Windows\system32;c:\Windows;c:\Windows\System32\Wbem;c:\Windows\System32\WindowsPowerShell\v1.0\;c:\Program Files\Amazon\cfn-bootstrap\;c:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;c:\Program Files\Microsoft SQL Server\110\Tools\Binn\;c:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;c:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\mozilla-build\msys\bin;c:\mozilla-build\python27\scripts;c:\mozilla-build\wget;c:\mozilla-build\info-zip;c:\mozilla-build;c:\mozilla-build\vim\vim72;c:\mozilla-build\python27;c:\mozilla-build\hg;c:\mozilla-build\buildbotve\Scripts;c:\ProgramData\chocolatey\bin;.;c:\opt\runner;c:\mozilla-build\buildbotve;c:\mozilla-build\python27;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;.;C:\mozilla-build\msys\local\bin;c:\mozilla-build\moztools-x64\bin;c:\mozilla-build\vim\vim72 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 2, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3f02 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PS1=\[\033]0;$MSYSTEM:\w\007 \033[32m\]\u@\h \[\033[33m\w\033[0m\] $ PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files (x86)\AWS Tools\PowerShell\ PUBLIC=C:\Users\Public PWD=c:/Users/cltbld RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg SHELL=C:/mozilla-build/msys/bin/sh SHLVL=1 SSH_AGENT_PID=4016 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TMP=C:/Users/cltbld/AppData/Local/Temp USERDOMAIN=B-2008-SPOT-002 USERNAME=cltbld USERPROFILE=C:\Users\cltbld VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\ VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\ VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\ WINDIR=C:\Windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log WIX_351728_PATH=c:/mozilla-build/wix-351728 _=C:\mozilla-build\buildbotve\Scripts\python using PTY: False 2016-12-28 00:05:10,664 truncating revision to first 12 chars 2016-12-28 00:05:10,664 Setting DEBUG logging. 2016-12-28 00:05:10,664 attempt 1/10 2016-12-28 00:05:10,664 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/21e27f6e2770?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 00:05:13,387 unpacking tar archive at: autoland-21e27f6e2770/testing/mozharness/ 2016-12-28 00:05:17,506 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=13.846000 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2016-12-28 00:05:25.839947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:25.841039) ========= script_repo_revision: 21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:25.841443) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:25.841711) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:25.849507) ========= ========= Started remove old nonce (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:25.849766) ========= 'rm' '-f' 'nonce' in dir c:\builds\moz2_slave\autoland-w32-d-000000000000000\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'nonce'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv BUILDBOT_PATH=C:\mozilla-build\buildbotve CHOCOLATEYINSTALL=C:\ProgramData\chocolatey COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=B-2008-SPOT-002 COMSPEC=C:\Windows\system32\cmd.exe CVS_RSH=ssh DXSDK_DIR=C:\Program Files (x86)\Microsoft DirectX SDK (June 2010)\ EDITOR=emacs.exe FP_NO_HOST_CHECK=NO GIT_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/git-shared HG_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/hg-shared HOME=c:/Users/cltbld HOMEDRIVE=C: HOMEPATH=\ HOSTTYPE=i686 IDLEIZER_HALT_ON_IDLE=true INPUTRC=C:/mozilla-build/msys/etc/inputrc LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOG="c:\runner\buildbot-startup.log" LOGNAME=cltbld LOGONSERVER=\\B-2008-SPOT-002 MACHTYPE=i686-pc-msys MAKE_MODE=unix MOZBUILDDIR=C:\mozilla-build MOZILLABUILD=C:\mozilla-build MOZ_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=8 OLDPWD=c:/Users/cltbld OS=Windows_NT OSTYPE=msys OS_VERSION=2008 PATH=C:\mozilla-build\msys\local\bin;c:\mozilla-build\wget;c:\mozilla-build\7zip;c:\mozilla-build\blat261\full;c:\mozilla-build\python;c:\mozilla-build\svn-win32-1.6.3\bin;c:\mozilla-build\upx203w;c:\mozilla-build\emacs-24.3\bin;c:\mozilla-build\info-zip;c:\mozilla-build\nsis-2.46u;c:\mozilla-build\nsis-3.0a2;c:\mozilla-build\wix-351728;c:\mozilla-build\hg;c:\mozilla-build\python\Scripts;c:\mozilla-build\kdiff3;c:\mozilla-build\yasm;c:\mozilla-build\mozmake;.;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;c:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;c:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;c:\Program Files (x86)\Puppet Labs\Puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;c:\Windows\system32;c:\Windows;c:\Windows\System32\Wbem;c:\Windows\System32\WindowsPowerShell\v1.0\;c:\Program Files\Amazon\cfn-bootstrap\;c:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;c:\Program Files\Microsoft SQL Server\110\Tools\Binn\;c:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;c:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\mozilla-build\msys\bin;c:\mozilla-build\python27\scripts;c:\mozilla-build\wget;c:\mozilla-build\info-zip;c:\mozilla-build;c:\mozilla-build\vim\vim72;c:\mozilla-build\python27;c:\mozilla-build\hg;c:\mozilla-build\buildbotve\Scripts;c:\ProgramData\chocolatey\bin;.;c:\opt\runner;c:\mozilla-build\buildbotve;c:\mozilla-build\python27;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;.;C:\mozilla-build\msys\local\bin;c:\mozilla-build\moztools-x64\bin;c:\mozilla-build\vim\vim72 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 2, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3f02 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PS1=\[\033]0;$MSYSTEM:\w\007 \033[32m\]\u@\h \[\033[33m\w\033[0m\] $ PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files (x86)\AWS Tools\PowerShell\ PUBLIC=C:\Users\Public PWD=c:/Users/cltbld RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg SHELL=C:/mozilla-build/msys/bin/sh SHLVL=1 SSH_AGENT_PID=4016 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TMP=C:/Users/cltbld/AppData/Local/Temp USERDOMAIN=B-2008-SPOT-002 USERNAME=cltbld USERPROFILE=C:\Users\cltbld VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\ VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\ VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\ WINDIR=C:\Windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log WIX_351728_PATH=c:/mozilla-build/wix-351728 _=C:\mozilla-build\buildbotve\Scripts\python using PTY: False program finished with exit code 0 elapsedTime=0.105000 ========= master_lag: 0.01 ========= ========= Finished remove old nonce (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:25.961898) ========= ========= Started downloading to token (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:25.962692) ========= Slave: b-2008-spot-002 IP: 10.134.55.108 Duration: 25200 URI: https://signing6.srv.releng.scl3.mozilla.com:9110/token ========= Finished downloading to token (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:26.371307) ========= ========= Started set props: toolsdir (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:26.371649) ========= 'bash' '-c' 'pwd' in dir c:\builds\moz2_slave\autoland-w32-d-000000000000000\scripts (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv BUILDBOT_PATH=C:\mozilla-build\buildbotve CHOCOLATEYINSTALL=C:\ProgramData\chocolatey COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=B-2008-SPOT-002 COMSPEC=C:\Windows\system32\cmd.exe CVS_RSH=ssh DXSDK_DIR=C:\Program Files (x86)\Microsoft DirectX SDK (June 2010)\ EDITOR=emacs.exe FP_NO_HOST_CHECK=NO GIT_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/git-shared HG_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/hg-shared HOME=c:/Users/cltbld HOMEDRIVE=C: HOMEPATH=\ HOSTTYPE=i686 IDLEIZER_HALT_ON_IDLE=true INPUTRC=C:/mozilla-build/msys/etc/inputrc LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOG="c:\runner\buildbot-startup.log" LOGNAME=cltbld LOGONSERVER=\\B-2008-SPOT-002 MACHTYPE=i686-pc-msys MAKE_MODE=unix MOZBUILDDIR=C:\mozilla-build MOZILLABUILD=C:\mozilla-build MOZ_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=8 OLDPWD=c:/Users/cltbld OS=Windows_NT OSTYPE=msys OS_VERSION=2008 PATH=C:\mozilla-build\msys\local\bin;c:\mozilla-build\wget;c:\mozilla-build\7zip;c:\mozilla-build\blat261\full;c:\mozilla-build\python;c:\mozilla-build\svn-win32-1.6.3\bin;c:\mozilla-build\upx203w;c:\mozilla-build\emacs-24.3\bin;c:\mozilla-build\info-zip;c:\mozilla-build\nsis-2.46u;c:\mozilla-build\nsis-3.0a2;c:\mozilla-build\wix-351728;c:\mozilla-build\hg;c:\mozilla-build\python\Scripts;c:\mozilla-build\kdiff3;c:\mozilla-build\yasm;c:\mozilla-build\mozmake;.;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;c:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;c:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;c:\Program Files (x86)\Puppet Labs\Puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;c:\Windows\system32;c:\Windows;c:\Windows\System32\Wbem;c:\Windows\System32\WindowsPowerShell\v1.0\;c:\Program Files\Amazon\cfn-bootstrap\;c:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;c:\Program Files\Microsoft SQL Server\110\Tools\Binn\;c:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;c:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\mozilla-build\msys\bin;c:\mozilla-build\python27\scripts;c:\mozilla-build\wget;c:\mozilla-build\info-zip;c:\mozilla-build;c:\mozilla-build\vim\vim72;c:\mozilla-build\python27;c:\mozilla-build\hg;c:\mozilla-build\buildbotve\Scripts;c:\ProgramData\chocolatey\bin;.;c:\opt\runner;c:\mozilla-build\buildbotve;c:\mozilla-build\python27;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;.;C:\mozilla-build\msys\local\bin;c:\mozilla-build\moztools-x64\bin;c:\mozilla-build\vim\vim72 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 2, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3f02 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PS1=\[\033]0;$MSYSTEM:\w\007 \033[32m\]\u@\h \[\033[33m\w\033[0m\] $ PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files (x86)\AWS Tools\PowerShell\ PUBLIC=C:\Users\Public PWD=c:/Users/cltbld RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg SHELL=C:/mozilla-build/msys/bin/sh SHLVL=1 SSH_AGENT_PID=4016 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TMP=C:/Users/cltbld/AppData/Local/Temp USERDOMAIN=B-2008-SPOT-002 USERNAME=cltbld USERPROFILE=C:\Users\cltbld VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\ VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\ VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\ WINDIR=C:\Windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log WIX_351728_PATH=c:/mozilla-build/wix-351728 _=C:\mozilla-build\buildbotve\Scripts\python using PTY: False /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts program finished with exit code 0 elapsedTime=0.105000 toolsdir: '/c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts' ========= master_lag: 0.01 ========= ========= Finished set props: toolsdir (results: 0, elapsed: 0 secs) (at 2016-12-28 00:05:26.484771) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 56 mins, 30 secs) (at 2016-12-28 00:05:26.485589) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/fx_desktop_build.py' '--config' 'builds/releng_base_windows_32_builds.py' '--custom-build-variant-cfg' 'debug' '--config' 'balrog/production.py' '--branch' 'autoland' '--build-pool' 'production' in dir c:\builds\moz2_slave\autoland-w32-d-000000000000000\. (timeout 10800 secs) (maxTime 27000 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/fx_desktop_build.py', '--config', 'builds/releng_base_windows_32_builds.py', '--custom-build-variant-cfg', 'debug', '--config', 'balrog/production.py', '--branch', 'autoland', '--build-pool', 'production'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv BINSCOPE=C:\Program Files (x86)\Microsoft\SDL BinScope\BinScope.exe BUILDBOT_PATH=C:\mozilla-build\buildbotve CHOCOLATEYINSTALL=C:\ProgramData\chocolatey COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=B-2008-SPOT-002 COMSPEC=C:\Windows\system32\cmd.exe CVS_RSH=ssh DXSDK_DIR=C:\Program Files (x86)\Microsoft DirectX SDK (June 2010)\ EDITOR=emacs.exe FP_NO_HOST_CHECK=NO GIT_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/git-shared HG_SHARE_BASE_DIR=c:/builds/hg-shared HOME=c:/Users/cltbld HOMEDRIVE=C: HOMEPATH=\ HOSTTYPE=i686 IDLEIZER_HALT_ON_IDLE=true INPUTRC=C:/mozilla-build/msys/etc/inputrc LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOG="c:\runner\buildbot-startup.log" LOGNAME=cltbld LOGONSERVER=\\B-2008-SPOT-002 MACHTYPE=i686-pc-msys MAKE_MODE=unix MOZBUILDDIR=C:\mozilla-build MOZILLABUILD=C:\mozilla-build MOZ_AUTOMATION=1 MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_OBJDIR=obj-firefox MOZ_SIGNING_SERVERS=gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110 MOZ_SIGN_CMD=python /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/autoland-w32-d-000000000000000/signing_cache -t /c/builds/moz2_slave/autoland-w32-d-000000000000000/token -n /c/builds/moz2_slave/autoland-w32-d-000000000000000/nonce -c /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110 MOZ_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=8 OLDPWD=c:/Users/cltbld OS=Windows_NT OSTYPE=msys OS_VERSION=2008 PATH=C:\mozilla-build\nsis-3.0b1;C:\mozilla-build\nsis-2.46u;C:\mozilla-build\python27;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\msys\local\bin;c:\mozilla-build\wget;c:\mozilla-build\7zip;c:\mozilla-build\blat261\full;c:\mozilla-build\python;c:\mozilla-build\svn-win32-1.6.3\bin;c:\mozilla-build\upx203w;c:\mozilla-build\emacs-24.3\bin;c:\mozilla-build\info-zip;c:\mozilla-build\nsis-2.46u;c:\mozilla-build\nsis-3.0a2;c:\mozilla-build\wix-351728;c:\mozilla-build\hg;c:\mozilla-build\python\Scripts;c:\mozilla-build\kdiff3;c:\mozilla-build\yasm;c:\mozilla-build\mozmake;.;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;c:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;c:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;c:\Program Files (x86)\Puppet Labs\Puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;c:\Windows\system32;c:\Windows;c:\Windows\System32\Wbem;c:\Windows\System32\WindowsPowerShell\v1.0\;c:\Program Files\Amazon\cfn-bootstrap\;c:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;c:\Program Files\Microsoft SQL Server\110\Tools\Binn\;c:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;c:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\mozilla-build\msys\bin;c:\mozilla-build\python27\scripts;c:\mozilla-build\wget;c:\mozilla-build\info-zip;c:\mozilla-build;c:\mozilla-build\vim\vim72;c:\mozilla-build\python27;c:\mozilla-build\hg;c:\mozilla-build\buildbotve\Scripts;c:\ProgramData\chocolatey\bin;.;c:\opt\runner;c:\mozilla-build\buildbotve;c:\mozilla-build\python27;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;.;C:\mozilla-build\msys\local\bin;c:\mozilla-build\moztools-x64\bin;c:\mozilla-build\vim\vim72 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 2, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3f02 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PROPERTIES_FILE=/c/builds/moz2_slave/autoland-w32-d-000000000000000/buildprops.json PS1=\[\033]0;$MSYSTEM:\w\007 \033[32m\]\u@\h \[\033[33m\w\033[0m\] $ PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files (x86)\AWS Tools\PowerShell\ PUBLIC=C:\Users\Public PWD=c:/Users/cltbld RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg SHELL=C:/mozilla-build/msys/bin/sh SHLVL=1 SSH_AGENT_PID=4016 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TINDERBOX_OUTPUT=1 TMP=C:/Users/cltbld/AppData/Local/Temp TOOLTOOL_CACHE=/c/builds/tooltool_cache TOOLTOOL_HOME=/c/builds USERDOMAIN=B-2008-SPOT-002 USERNAME=cltbld USERPROFILE=C:\Users\cltbld VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\ VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\ VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\ WINDIR=C:\Windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log WIX_351728_PATH=c:/mozilla-build/wix-351728 XPCOM_DEBUG_BREAK=stack-and-abort _=C:\mozilla-build\buildbotve\Scripts\python using PTY: False 00:05:27 INFO - MultiFileLogger online at 20161228 00:05:27 in c:\builds\moz2_slave\autoland-w32-d-000000000000000 00:05:27 INFO - Script was run with '--custom-build-variant-cfg debug' and 'debug' matches a key in 'builds/releng_sub_windows_configs/32_debug.py'. Updating self.config with items from that key's value. 00:05:27 INFO - Script was run with '--branch autoland' and 'autoland' matches a key in 'builds/branch_specifics.py'. Updating self.config with items from that key's value. 00:05:27 INFO - Script was run with '--build-pool production' and 'production' matches a key in 'builds/build_pool_specifics.py'. Updating self.config with items from that key's value. 00:05:27 INFO - To generate a config file based upon options passed and config files used, run script as before but extend options with "--dump-config" 00:05:27 INFO - For a diff of where self.config got its items, run the script again as before but extend options with: "--dump-config-hierarchy" 00:05:27 INFO - Both --dump-config and --dump-config-hierarchy don't actually run any actions. 00:05:27 INFO - We are running this in buildbot, grab the build props 00:05:27 INFO - Using buildbot properties: 00:05:27 INFO - { 00:05:27 INFO - "project": "", 00:05:27 INFO - "product": "firefox", 00:05:27 INFO - "script_repo_revision": "production", 00:05:27 INFO - "scheduler": "autoland-firefox", 00:05:27 INFO - "repository": "", 00:05:27 INFO - "buildername": "WINNT 5.2 autoland leak test build", 00:05:27 INFO - "buildid": "20161227235136", 00:05:27 INFO - "basedir": "/c/builds/moz2_slave/autoland-w32-d-000000000000000", 00:05:27 INFO - "buildnumber": 685, 00:05:27 INFO - "platform": "win32-debug", 00:05:27 INFO - "master": "http://buildbot-master70.bb.releng.use1.mozilla.com:8001/", 00:05:27 INFO - "branch": "autoland", 00:05:27 INFO - "revision": "21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0", 00:05:27 INFO - "slavename": "b-2008-spot-002", 00:05:27 INFO - "builduid": "e042f36309ad4ae787d4cdca52b6a8af", 00:05:27 INFO - "repo_path": "integration/autoland" 00:05:27 INFO - } 00:05:27 INFO - Setting buildbot property stage_platform to win32-debug 00:05:27 INFO - mkdir: c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties 00:05:27 INFO - Writing buildbot properties ['stage_platform'] to c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\stage_platform 00:05:27 INFO - Writing to file c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\stage_platform 00:05:27 INFO - Contents: 00:05:27 INFO - stage_platform:win32-debug 00:05:27 WARNING - Try message not found. 00:05:27 INFO - Run as scripts/scripts/fx_desktop_build.py --config builds/releng_base_windows_32_builds.py --custom-build-variant-cfg debug --config balrog/production.py --branch autoland --build-pool production 00:05:27 INFO - Determining buildid from buildbot properties 00:05:27 INFO - Setting buildbot property buildid to 20161227235136 00:05:27 INFO - Writing buildbot properties ['buildid'] to c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\buildid 00:05:27 INFO - Writing to file c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\buildid 00:05:27 INFO - Contents: 00:05:27 INFO - buildid:20161227235136 00:05:27 INFO - Determining builduid from buildbot properties 00:05:27 INFO - Setting buildbot property builduid to e042f36309ad4ae787d4cdca52b6a8af 00:05:27 INFO - Writing buildbot properties ['builduid'] to c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\builduid 00:05:27 INFO - Writing to file c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\builduid 00:05:27 INFO - Contents: 00:05:27 INFO - builduid:e042f36309ad4ae787d4cdca52b6a8af 00:05:27 INFO - Creating virtualenv c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv 00:05:27 INFO - mkdir: c:\builds\moz2_slave\autoland-w32-d-000000000000000\build 00:05:27 INFO - Running command: ['c:\\mozilla-build\\python27\\python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\venv'] in c:\builds\moz2_slave\autoland-w32-d-000000000000000\build 00:05:27 INFO - Copy/paste: c:\mozilla-build\python27\python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv 00:05:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:05:37 INFO - New python executable in c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\Scripts\python.exe 00:05:47 INFO - Installing distribute................................................................................................................................................................................................done. 00:06:16 INFO - Installing pip....................................done. 00:06:16 INFO - Return code: 0 00:06:16 INFO - Getting output from command: ['c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\venv\\Scripts\\pip', '--version'] 00:06:16 INFO - Copy/paste: c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\Scripts\pip --version 00:06:22 INFO - Reading from file tmpfile_stdout 00:06:22 INFO - Output received: 00:06:22 INFO - pip 9.0.1 from c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg (python 2.7) 00:06:22 INFO - Using _rmtree_windows ... 00:06:22 INFO - Using _rmtree_windows ... 00:06:22 INFO - Installing requests==2.8.1 into virtualenv c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv 00:06:22 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'requests==2.8.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0245DBA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x026651B0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02335CC0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x026901D0>, '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 0x0250B4B0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x026C8628>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build', 'env': {'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 'SYSTEMROOT': 'C:\\Windows', 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 'SHELL': 'C:/mozilla-build/msys/bin/sh', 'IDLEIZER_HALT_ON_IDLE': 'true', 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 'WINDOWS_TRACING_FLAGS': '3', 'TOOLTOOL_HOME': '/c/builds', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 'PROCESSOR_LEVEL': '6', 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'MACHTYPE': 'i686-pc-msys', 'OS': 'Windows_NT', 'MOZ_AUTOMATION': '1', 'CVS_RSH': 'ssh', 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 'MOZ_OBJDIR': 'obj-firefox', 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 'SHLVL': '1', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 'EDITOR': 'emacs.exe', 'HOMEPATH': '\\', 'MOZBUILDDIR': 'C:\\mozilla-build', 'MOZILLABUILD': 'C:\\mozilla-build', 'LOGONSERVER': '\\\\B-2008-SPOT-002', 'OS_VERSION': '2008', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'COMPUTERNAME': 'B-2008-SPOT-002', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-002', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 'HOME': 'c:/Users/cltbld', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 'PROCESSOR_ARCHITECTURE': 'x86', 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'OSTYPE': 'msys', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'PROMPT': '$P$G', 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'WINDIR': 'C:\\Windows', 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\.mozbuild', 'NUMBER_OF_PROCESSORS': '8', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'PUBLIC': 'C:\\Users\\Public', 'USERPROFILE': 'C:\\Users\\cltbld', 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 'LOG': '"c:\\runner\\buildbot-startup.log"', 'HOSTTYPE': 'i686', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 'LOGNAME': 'cltbld', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '3f02', 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\hg;c:\\mozilla-build\\buildbotve\\Scripts;c:\\ProgramData\\chocolatey\\bin;.;c:\\opt\\runner;c:\\mozilla-build\\buildbotve;c:\\mozilla-build\\python27;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;.;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\moztools-x64\\bin;c:\\mozilla-build\\vim\\vim72', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'SSH_AGENT_PID': '4016', 'MAKE_MODE': 'unix', 'TERM': 'cygwin', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 'MSYSTEM': 'MINGW32', 'PROGRAMDATA': 'C:\\ProgramData', 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/autoland-w32-d-000000000000000/signing_cache -t /c/builds/moz2_slave/autoland-w32-d-000000000000000/token -n /c/builds/moz2_slave/autoland-w32-d-000000000000000/nonce -c /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'FP_NO_HOST_CHECK': 'NO', 'OLDPWD': 'c:/Users/cltbld', 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 'PWD': 'c:/Users/cltbld', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files'}}, attempt #1 00:06:22 INFO - Running command: ['c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'requests==2.8.1'] in c:\builds\moz2_slave\autoland-w32-d-000000000000000\build 00:06:22 INFO - Copy/paste: c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\Scripts\pip install --timeout 120 requests==2.8.1 00:06:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 00:06:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 00:06:22 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 00:06:22 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 00:06:22 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 00:06:22 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 00:06:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 00:06:22 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 00:06:22 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 00:06:22 INFO - 'COMPUTERNAME': 'B-2008-SPOT-002', 00:06:22 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 00:06:22 INFO - 'CVS_RSH': 'ssh', 00:06:22 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 00:06:22 INFO - 'EDITOR': 'emacs.exe', 00:06:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 00:06:22 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 00:06:22 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 00:06:22 INFO - 'HOME': 'c:/Users/cltbld', 00:06:22 INFO - 'HOMEDRIVE': 'C:', 00:06:22 INFO - 'HOMEPATH': '\\', 00:06:22 INFO - 'HOSTTYPE': 'i686', 00:06:22 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 00:06:22 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 00:06:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 00:06:22 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 00:06:22 INFO - 'LOGNAME': 'cltbld', 00:06:22 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-002', 00:06:22 INFO - 'MACHTYPE': 'i686-pc-msys', 00:06:22 INFO - 'MAKE_MODE': 'unix', 00:06:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 00:06:22 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\.mozbuild', 00:06:22 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 00:06:22 INFO - 'MOZ_AUTOMATION': '1', 00:06:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:22 INFO - 'MOZ_OBJDIR': 'obj-firefox', 00:06:22 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 00:06:22 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/autoland-w32-d-000000000000000/signing_cache -t /c/builds/moz2_slave/autoland-w32-d-000000000000000/token -n /c/builds/moz2_slave/autoland-w32-d-000000000000000/nonce -c /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 00:06:22 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 00:06:22 INFO - 'MSYSTEM': 'MINGW32', 00:06:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 00:06:22 INFO - 'OLDPWD': 'c:/Users/cltbld', 00:06:22 INFO - 'OS': 'Windows_NT', 00:06:22 INFO - 'OSTYPE': 'msys', 00:06:22 INFO - 'OS_VERSION': '2008', 00:06:22 INFO - 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\hg;c:\\mozilla-build\\buildbotve\\Scripts;c:\\ProgramData\\chocolatey\\bin;.;c:\\opt\\runner;c:\\mozilla-build\\buildbotve;c:\\mozilla-build\\python27;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;.;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\moztools-x64\\bin;c:\\mozilla-build\\vim\\vim72', 00:06:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 00:06:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 00:06:22 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 00:06:22 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 00:06:22 INFO - 'PROCESSOR_LEVEL': '6', 00:06:22 INFO - 'PROCESSOR_REVISION': '3f02', 00:06:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 00:06:22 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 00:06:22 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 00:06:22 INFO - 'PROGRAMW6432': 'C:\\Program Files', 00:06:22 INFO - 'PROMPT': '$P$G', 00:06:22 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\buildprops.json', 00:06:22 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 00:06:22 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 00:06:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 00:06:22 INFO - 'PWD': 'c:/Users/cltbld', 00:06:22 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 00:06:22 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 00:06:22 INFO - 'SHLVL': '1', 00:06:22 INFO - 'SSH_AGENT_PID': '4016', 00:06:22 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960', 00:06:22 INFO - 'SYSTEMDRIVE': 'C:', 00:06:22 INFO - 'SYSTEMROOT': 'C:\\Windows', 00:06:22 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 00:06:22 INFO - 'TERM': 'cygwin', 00:06:22 INFO - 'TINDERBOX_OUTPUT': '1', 00:06:22 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 00:06:22 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 00:06:22 INFO - 'TOOLTOOL_HOME': '/c/builds', 00:06:22 INFO - 'USERDOMAIN': 'B-2008-SPOT-002', 00:06:22 INFO - 'USERNAME': 'cltbld', 00:06:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 00:06:22 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 00:06:22 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 00:06:22 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 00:06:22 INFO - 'WINDIR': 'C:\\Windows', 00:06:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 00:06:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 00:06:22 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 00:06:22 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 00:06:22 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 00:06:23 INFO - Collecting requests==2.8.1 00:06:25 INFO - Downloading requests-2.8.1-py2.py3-none-any.whl (497kB) 00:06:26 INFO - Installing collected packages: requests 00:06:26 INFO - Successfully installed requests-2.8.1 00:06:28 INFO - c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:318: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#snimissingwarning. 00:06:28 INFO - SNIMissingWarning 00:06:28 INFO - c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning. 00:06:28 INFO - InsecurePlatformWarning 00:06:28 INFO - c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning. 00:06:28 INFO - InsecurePlatformWarning 00:06:28 INFO - Return code: 0 00:06:28 INFO - Installing PyHawk-with-a-single-extra-commit==0.1.5 into virtualenv c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv 00:06:28 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'PyHawk-with-a-single-extra-commit==0.1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0245DBA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x026651B0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02335CC0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x026901D0>, '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 0x0250B4B0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x026C8628>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build', 'env': {'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 'SYSTEMROOT': 'C:\\Windows', 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 'SHELL': 'C:/mozilla-build/msys/bin/sh', 'IDLEIZER_HALT_ON_IDLE': 'true', 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 'WINDOWS_TRACING_FLAGS': '3', 'TOOLTOOL_HOME': '/c/builds', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 'PROCESSOR_LEVEL': '6', 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'MACHTYPE': 'i686-pc-msys', 'OS': 'Windows_NT', 'MOZ_AUTOMATION': '1', 'CVS_RSH': 'ssh', 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 'MOZ_OBJDIR': 'obj-firefox', 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 'SHLVL': '1', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 'EDITOR': 'emacs.exe', 'HOMEPATH': '\\', 'MOZBUILDDIR': 'C:\\mozilla-build', 'MOZILLABUILD': 'C:\\mozilla-build', 'LOGONSERVER': '\\\\B-2008-SPOT-002', 'OS_VERSION': '2008', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'COMPUTERNAME': 'B-2008-SPOT-002', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-002', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 'HOME': 'c:/Users/cltbld', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 'PROCESSOR_ARCHITECTURE': 'x86', 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'OSTYPE': 'msys', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'PROMPT': '$P$G', 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'WINDIR': 'C:\\Windows', 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\.mozbuild', 'NUMBER_OF_PROCESSORS': '8', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'PUBLIC': 'C:\\Users\\Public', 'USERPROFILE': 'C:\\Users\\cltbld', 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 'LOG': '"c:\\runner\\buildbot-startup.log"', 'HOSTTYPE': 'i686', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 'LOGNAME': 'cltbld', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '3f02', 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\hg;c:\\mozilla-build\\buildbotve\\Scripts;c:\\ProgramData\\chocolatey\\bin;.;c:\\opt\\runner;c:\\mozilla-build\\buildbotve;c:\\mozilla-build\\python27;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;.;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\moztools-x64\\bin;c:\\mozilla-build\\vim\\vim72', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'SSH_AGENT_PID': '4016', 'MAKE_MODE': 'unix', 'TERM': 'cygwin', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 'MSYSTEM': 'MINGW32', 'PROGRAMDATA': 'C:\\ProgramData', 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/autoland-w32-d-000000000000000/signing_cache -t /c/builds/moz2_slave/autoland-w32-d-000000000000000/token -n /c/builds/moz2_slave/autoland-w32-d-000000000000000/nonce -c /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'FP_NO_HOST_CHECK': 'NO', 'OLDPWD': 'c:/Users/cltbld', 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 'PWD': 'c:/Users/cltbld', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files'}}, attempt #1 00:06:28 INFO - Running command: ['c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'PyHawk-with-a-single-extra-commit==0.1.5'] in c:\builds\moz2_slave\autoland-w32-d-000000000000000\build 00:06:28 INFO - Copy/paste: c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\Scripts\pip install --timeout 120 PyHawk-with-a-single-extra-commit==0.1.5 00:06:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 00:06:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 00:06:28 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 00:06:28 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 00:06:28 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 00:06:28 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 00:06:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 00:06:28 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 00:06:28 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 00:06:28 INFO - 'COMPUTERNAME': 'B-2008-SPOT-002', 00:06:28 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 00:06:28 INFO - 'CVS_RSH': 'ssh', 00:06:28 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 00:06:28 INFO - 'EDITOR': 'emacs.exe', 00:06:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 00:06:28 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 00:06:28 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 00:06:28 INFO - 'HOME': 'c:/Users/cltbld', 00:06:28 INFO - 'HOMEDRIVE': 'C:', 00:06:28 INFO - 'HOMEPATH': '\\', 00:06:28 INFO - 'HOSTTYPE': 'i686', 00:06:28 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 00:06:28 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 00:06:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 00:06:28 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 00:06:28 INFO - 'LOGNAME': 'cltbld', 00:06:28 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-002', 00:06:28 INFO - 'MACHTYPE': 'i686-pc-msys', 00:06:28 INFO - 'MAKE_MODE': 'unix', 00:06:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 00:06:28 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\.mozbuild', 00:06:28 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 00:06:28 INFO - 'MOZ_AUTOMATION': '1', 00:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:28 INFO - 'MOZ_OBJDIR': 'obj-firefox', 00:06:28 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 00:06:28 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/autoland-w32-d-000000000000000/signing_cache -t /c/builds/moz2_slave/autoland-w32-d-000000000000000/token -n /c/builds/moz2_slave/autoland-w32-d-000000000000000/nonce -c /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 00:06:28 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 00:06:28 INFO - 'MSYSTEM': 'MINGW32', 00:06:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 00:06:28 INFO - 'OLDPWD': 'c:/Users/cltbld', 00:06:28 INFO - 'OS': 'Windows_NT', 00:06:28 INFO - 'OSTYPE': 'msys', 00:06:28 INFO - 'OS_VERSION': '2008', 00:06:28 INFO - 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\hg;c:\\mozilla-build\\buildbotve\\Scripts;c:\\ProgramData\\chocolatey\\bin;.;c:\\opt\\runner;c:\\mozilla-build\\buildbotve;c:\\mozilla-build\\python27;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;.;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\moztools-x64\\bin;c:\\mozilla-build\\vim\\vim72', 00:06:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 00:06:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 00:06:28 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 00:06:28 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 00:06:28 INFO - 'PROCESSOR_LEVEL': '6', 00:06:28 INFO - 'PROCESSOR_REVISION': '3f02', 00:06:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 00:06:28 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 00:06:28 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 00:06:28 INFO - 'PROGRAMW6432': 'C:\\Program Files', 00:06:28 INFO - 'PROMPT': '$P$G', 00:06:28 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\buildprops.json', 00:06:28 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 00:06:28 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 00:06:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 00:06:28 INFO - 'PWD': 'c:/Users/cltbld', 00:06:28 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 00:06:28 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 00:06:28 INFO - 'SHLVL': '1', 00:06:28 INFO - 'SSH_AGENT_PID': '4016', 00:06:28 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960', 00:06:28 INFO - 'SYSTEMDRIVE': 'C:', 00:06:28 INFO - 'SYSTEMROOT': 'C:\\Windows', 00:06:28 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 00:06:28 INFO - 'TERM': 'cygwin', 00:06:28 INFO - 'TINDERBOX_OUTPUT': '1', 00:06:28 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 00:06:28 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 00:06:28 INFO - 'TOOLTOOL_HOME': '/c/builds', 00:06:28 INFO - 'USERDOMAIN': 'B-2008-SPOT-002', 00:06:28 INFO - 'USERNAME': 'cltbld', 00:06:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 00:06:28 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 00:06:28 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 00:06:28 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 00:06:28 INFO - 'WINDIR': 'C:\\Windows', 00:06:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 00:06:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 00:06:28 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 00:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 00:06:28 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 00:06:29 INFO - Collecting PyHawk-with-a-single-extra-commit==0.1.5 00:06:29 INFO - Downloading PyHawk-with-a-single-extra-commit-0.1.5.tar.gz 00:06:30 INFO - Installing collected packages: PyHawk-with-a-single-extra-commit 00:06:30 INFO - Running setup.py install for PyHawk-with-a-single-extra-commit: started 00:06:30 INFO - Running setup.py install for PyHawk-with-a-single-extra-commit: finished with status 'done' 00:06:30 INFO - Successfully installed PyHawk-with-a-single-extra-commit-0.1.5 00:06:30 INFO - c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:318: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#snimissingwarning. 00:06:30 INFO - SNIMissingWarning 00:06:30 INFO - c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning. 00:06:30 INFO - InsecurePlatformWarning 00:06:30 INFO - Return code: 0 00:06:30 INFO - Installing taskcluster==0.0.26 into virtualenv c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv 00:06:30 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'taskcluster==0.0.26']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0245DBA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x026651B0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02335CC0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x026901D0>, '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 0x0250B4B0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x026C8628>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build', 'env': {'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 'SYSTEMROOT': 'C:\\Windows', 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 'SHELL': 'C:/mozilla-build/msys/bin/sh', 'IDLEIZER_HALT_ON_IDLE': 'true', 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 'WINDOWS_TRACING_FLAGS': '3', 'TOOLTOOL_HOME': '/c/builds', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 'PROCESSOR_LEVEL': '6', 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'MACHTYPE': 'i686-pc-msys', 'OS': 'Windows_NT', 'MOZ_AUTOMATION': '1', 'CVS_RSH': 'ssh', 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 'MOZ_OBJDIR': 'obj-firefox', 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 'SHLVL': '1', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 'EDITOR': 'emacs.exe', 'HOMEPATH': '\\', 'MOZBUILDDIR': 'C:\\mozilla-build', 'MOZILLABUILD': 'C:\\mozilla-build', 'LOGONSERVER': '\\\\B-2008-SPOT-002', 'OS_VERSION': '2008', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'COMPUTERNAME': 'B-2008-SPOT-002', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-002', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 'HOME': 'c:/Users/cltbld', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 'PROCESSOR_ARCHITECTURE': 'x86', 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'OSTYPE': 'msys', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'PROMPT': '$P$G', 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'WINDIR': 'C:\\Windows', 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\.mozbuild', 'NUMBER_OF_PROCESSORS': '8', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'PUBLIC': 'C:\\Users\\Public', 'USERPROFILE': 'C:\\Users\\cltbld', 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 'LOG': '"c:\\runner\\buildbot-startup.log"', 'HOSTTYPE': 'i686', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 'LOGNAME': 'cltbld', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '3f02', 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\hg;c:\\mozilla-build\\buildbotve\\Scripts;c:\\ProgramData\\chocolatey\\bin;.;c:\\opt\\runner;c:\\mozilla-build\\buildbotve;c:\\mozilla-build\\python27;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;.;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\moztools-x64\\bin;c:\\mozilla-build\\vim\\vim72', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'SSH_AGENT_PID': '4016', 'MAKE_MODE': 'unix', 'TERM': 'cygwin', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 'MSYSTEM': 'MINGW32', 'PROGRAMDATA': 'C:\\ProgramData', 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/autoland-w32-d-000000000000000/signing_cache -t /c/builds/moz2_slave/autoland-w32-d-000000000000000/token -n /c/builds/moz2_slave/autoland-w32-d-000000000000000/nonce -c /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'FP_NO_HOST_CHECK': 'NO', 'OLDPWD': 'c:/Users/cltbld', 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 'PWD': 'c:/Users/cltbld', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files'}}, attempt #1 00:06:30 INFO - Running command: ['c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'taskcluster==0.0.26'] in c:\builds\moz2_slave\autoland-w32-d-000000000000000\build 00:06:30 INFO - Copy/paste: c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\Scripts\pip install --timeout 120 taskcluster==0.0.26 00:06:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 00:06:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 00:06:30 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 00:06:30 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 00:06:30 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 00:06:30 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 00:06:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 00:06:30 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 00:06:30 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 00:06:30 INFO - 'COMPUTERNAME': 'B-2008-SPOT-002', 00:06:30 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 00:06:30 INFO - 'CVS_RSH': 'ssh', 00:06:30 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 00:06:30 INFO - 'EDITOR': 'emacs.exe', 00:06:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 00:06:30 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 00:06:30 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 00:06:30 INFO - 'HOME': 'c:/Users/cltbld', 00:06:30 INFO - 'HOMEDRIVE': 'C:', 00:06:30 INFO - 'HOMEPATH': '\\', 00:06:30 INFO - 'HOSTTYPE': 'i686', 00:06:30 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 00:06:30 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 00:06:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 00:06:30 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 00:06:30 INFO - 'LOGNAME': 'cltbld', 00:06:30 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-002', 00:06:30 INFO - 'MACHTYPE': 'i686-pc-msys', 00:06:30 INFO - 'MAKE_MODE': 'unix', 00:06:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 00:06:30 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\.mozbuild', 00:06:30 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 00:06:30 INFO - 'MOZ_AUTOMATION': '1', 00:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:30 INFO - 'MOZ_OBJDIR': 'obj-firefox', 00:06:30 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 00:06:30 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/autoland-w32-d-000000000000000/signing_cache -t /c/builds/moz2_slave/autoland-w32-d-000000000000000/token -n /c/builds/moz2_slave/autoland-w32-d-000000000000000/nonce -c /c/builds/moz2_slave/autoland-w32-d-000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 00:06:30 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 00:06:30 INFO - 'MSYSTEM': 'MINGW32', 00:06:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 00:06:30 INFO - 'OLDPWD': 'c:/Users/cltbld', 00:06:30 INFO - 'OS': 'Windows_NT', 00:06:30 INFO - 'OSTYPE': 'msys', 00:06:30 INFO - 'OS_VERSION': '2008', 00:06:30 INFO - 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\hg;c:\\mozilla-build\\buildbotve\\Scripts;c:\\ProgramData\\chocolatey\\bin;.;c:\\opt\\runner;c:\\mozilla-build\\buildbotve;c:\\mozilla-build\\python27;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;.;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\moztools-x64\\bin;c:\\mozilla-build\\vim\\vim72', 00:06:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 00:06:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 00:06:30 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 00:06:30 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 00:06:30 INFO - 'PROCESSOR_LEVEL': '6', 00:06:30 INFO - 'PROCESSOR_REVISION': '3f02', 00:06:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 00:06:30 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 00:06:30 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 00:06:30 INFO - 'PROGRAMW6432': 'C:\\Program Files', 00:06:30 INFO - 'PROMPT': '$P$G', 00:06:30 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\buildprops.json', 00:06:30 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 00:06:30 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 00:06:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 00:06:30 INFO - 'PWD': 'c:/Users/cltbld', 00:06:30 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 00:06:30 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 00:06:30 INFO - 'SHLVL': '1', 00:06:30 INFO - 'SSH_AGENT_PID': '4016', 00:06:30 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960', 00:06:30 INFO - 'SYSTEMDRIVE': 'C:', 00:06:30 INFO - 'SYSTEMROOT': 'C:\\Windows', 00:06:30 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 00:06:30 INFO - 'TERM': 'cygwin', 00:06:30 INFO - 'TINDERBOX_OUTPUT': '1', 00:06:30 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 00:06:30 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 00:06:30 INFO - 'TOOLTOOL_HOME': '/c/builds', 00:06:30 INFO - 'USERDOMAIN': 'B-2008-SPOT-002', 00:06:30 INFO - 'USERNAME': 'cltbld', 00:06:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 00:06:30 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 00:06:30 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 00:06:30 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 00:06:30 INFO - 'WINDIR': 'C:\\Windows', 00:06:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 00:06:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 00:06:30 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 00:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 00:06:30 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 00:06:31 INFO - Collecting taskcluster==0.0.26 00:06:32 INFO - Downloading taskcluster-0.0.26.tar.gz 00:06:32 INFO - Collecting requests<=2.7.0,>=2.4.3 (from taskcluster==0.0.26) 00:06:32 INFO - Downloading requests-2.7.0-py2.py3-none-any.whl (470kB) 00:06:33 INFO - Requirement already satisfied: PyHawk_with_a_single_extra_commit==0.1.5 in .\venv\lib\site-packages (from taskcluster==0.0.26) 00:06:33 INFO - Collecting slugid (from taskcluster==0.0.26) 00:06:33 INFO - Downloading slugid-1.0.7.tar.gz 00:06:33 INFO - Installing collected packages: requests, slugid, taskcluster 00:06:33 INFO - Found existing installation: requests 2.8.1 00:06:33 INFO - Uninstalling requests-2.8.1: 00:06:34 INFO - Successfully uninstalled requests-2.8.1 00:06:36 INFO - Running setup.py install for slugid: started 00:06:36 INFO - Running setup.py install for slugid: finished with status 'done' 00:06:37 INFO - Running setup.py install for taskcluster: started 00:06:37 INFO - Running setup.py install for taskcluster: finished with status 'done' 00:06:37 INFO - Successfully installed requests-2.7.0 slugid-1.0.7 taskcluster-0.0.26 00:06:38 INFO - c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:318: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#snimissingwarning. 00:06:38 INFO - SNIMissingWarning 00:06:38 INFO - c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning. 00:06:38 INFO - InsecurePlatformWarning 00:06:38 INFO - Return code: 0 00:06:38 INFO - Done creating virtualenv c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv. 00:06:38 INFO - Getting output from command: ['c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\venv\\Scripts\\pip', 'freeze'] 00:06:38 INFO - Copy/paste: c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\venv\Scripts\pip freeze 00:06:38 INFO - Reading from file tmpfile_stdout 00:06:38 INFO - Using _rmtree_windows ... 00:06:38 INFO - Using _rmtree_windows ... 00:06:38 INFO - Current package versions: 00:06:38 INFO - PyHawk-with-a-single-extra-commit == 0.1.5 00:06:38 INFO - requests == 2.7.0 00:06:38 INFO - slugid == 1.0.7 00:06:38 INFO - taskcluster == 0.0.26 00:06:38 INFO - Dumping config to c:\builds\moz2_slave\autoland-w32-d-000000000000000\logs\localconfig.json. 00:06:38 INFO - {'app_ini_path': '%(obj_dir)s/dist/bin/application.ini', 00:06:38 INFO - 'append_to_log': False, 00:06:38 INFO - 'balrog_credentials_file': 'oauth.txt', 00:06:38 INFO - 'balrog_servers': ({'balrog_api_root': 'https://aus4-admin.mozilla.org/api', 00:06:38 INFO - 'balrog_usernames': {'Fennec': 'ffxbld', 00:06:38 INFO - 'firefox': 'ffxbld', 00:06:38 INFO - 'mobile': 'ffxbld', 00:06:38 INFO - 'thunderbird': 'tbirdbld'}, 00:06:38 INFO - 'ignore_failures': False, 00:06:38 INFO - 'url_replacements': (('http://archive.mozilla.org/pub', 00:06:38 INFO - 'http://download.cdn.mozilla.net/pub'),)},), 00:06:38 INFO - 'base_name': 'WINNT_5.2_%(branch)s', 00:06:38 INFO - 'base_work_dir': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000', 00:06:38 INFO - 'branch': 'autoland', 00:06:38 INFO - 'build_pool': 'production', 00:06:38 INFO - 'build_resources_path': '%(abs_src_dir)s/obj-firefox/.mozbuild/build_resources.json', 00:06:38 INFO - 'build_variant': 'debug', 00:06:38 INFO - 'buildbot_json_path': 'buildprops.json', 00:06:38 INFO - 'check_test_env': {'MINIDUMP_SAVE_PATH': '%(base_work_dir)s/minidumps', 00:06:38 INFO - 'MINIDUMP_STACKWALK': '%(abs_tools_dir)s/breakpad/win32/minidump_stackwalk.exe'}, 00:06:38 INFO - 'clobberer_url': 'https://api.pub.build.mozilla.org/clobberer/lastclobber', 00:06:38 INFO - 'clone_by_revision': False, 00:06:38 INFO - 'clone_with_purge': False, 00:06:38 INFO - 'config_files': ('builds/releng_base_windows_32_builds.py', 00:06:38 INFO - 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\scripts\\scripts\\..\\configs\\builds/releng_sub_windows_configs/32_debug.py', 00:06:38 INFO - 'balrog/production.py', 00:06:38 INFO - 'builds/branch_specifics.py', 00:06:38 INFO - 'builds/build_pool_specifics.py'), 00:06:38 INFO - 'debug_build': True, 00:06:38 INFO - 'default_actions': ('clobber', 00:06:38 INFO - 'clone-tools', 00:06:38 INFO - 'checkout-sources', 00:06:38 INFO - 'build', 00:06:38 INFO - 'upload-files', 00:06:38 INFO - 'sendchange', 00:06:38 INFO - 'check-test', 00:06:38 INFO - 'generate-build-stats', 00:06:38 INFO - 'update'), 00:06:38 INFO - 'enable_ccache': False, 00:06:38 INFO - 'enable_count_ctors': False, 00:06:38 INFO - 'enable_pymake': True, 00:06:38 INFO - 'enable_signing': True, 00:06:38 INFO - 'enable_talos_sendchange': False, 00:06:38 INFO - 'enable_unittest_sendchange': True, 00:06:38 INFO - 'env': {'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 00:06:38 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 00:06:38 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\.mozbuild', 00:06:38 INFO - 'MOZ_AUTOMATION': '1', 00:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:38 INFO - 'MOZ_OBJDIR': 'obj-firefox', 00:06:38 INFO - 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\hg;c:\\mozilla-build\\buildbotve\\Scripts;c:\\ProgramData\\chocolatey\\bin;.;c:\\opt\\runner;c:\\mozilla-build\\buildbotve;c:\\mozilla-build\\python27;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;.;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\moztools-x64\\bin;c:\\mozilla-build\\vim\\vim72', 00:06:38 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\buildprops.json', 00:06:38 INFO - 'TINDERBOX_OUTPUT': '1', 00:06:38 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 00:06:38 INFO - 'TOOLTOOL_HOME': '/c/builds', 00:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort'}, 00:06:38 INFO - 'exes': {'buildbot': ('c:\\mozilla-build\\python27\\python.exe', 00:06:38 INFO - 'c:\\mozilla-build\\buildbotve\\scripts\\buildbot'), 00:06:38 INFO - 'make': ('c:\\mozilla-build\\python27\\python.exe', 00:06:38 INFO - 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src\\build\\pymake\\make.py'), 00:06:38 INFO - 'python2.7': 'c:\\mozilla-build\\python27\\python.exe', 00:06:38 INFO - 'virtualenv': ('c:\\mozilla-build\\python27\\python.exe', 00:06:38 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 00:06:38 INFO - 'graph_selector': '/server/collect.cgi', 00:06:38 INFO - 'graph_server': 'graphs.mozilla.org', 00:06:38 INFO - 'include_post_upload_builddir': False, 00:06:38 INFO - 'is_automation': True, 00:06:38 INFO - 'latest_mar_dir': '/pub/mozilla.org/firefox/nightly/latest-%(branch)s', 00:06:38 INFO - 'log_level': 'info', 00:06:38 INFO - 'log_to_console': True, 00:06:38 INFO - 'max_build_output_timeout': 4800, 00:06:38 INFO - 'nightly_build': False, 00:06:38 INFO - 'nightly_promotion_branches': ('mozilla-central', 'mozilla-aurora'), 00:06:38 INFO - 'objdir': 'obj-firefox', 00:06:38 INFO - 'old_packages': ('%(objdir)s/dist/firefox-*', 00:06:38 INFO - '%(objdir)s/dist/fennec*', 00:06:38 INFO - '%(objdir)s/dist/seamonkey*', 00:06:38 INFO - '%(objdir)s/dist/thunderbird*', 00:06:38 INFO - '%(objdir)s/dist/install/sea/*.exe'), 00:06:38 INFO - 'opt_config_files': (), 00:06:38 INFO - 'periodic_clobber': 168, 00:06:38 INFO - 'pgo_build': False, 00:06:38 INFO - 'pgo_platforms': ('linux', 'linux64', 'win32', 'win64'), 00:06:38 INFO - 'platform': 'win32', 00:06:38 INFO - 'platform_supports_post_upload_to_latest': True, 00:06:38 INFO - 'publish_nightly_en_US_routes': True, 00:06:38 INFO - 'release_to_try_builds': False, 00:06:38 INFO - 'repo_base': 'https://hg.mozilla.org', 00:06:38 INFO - 'repo_path': 'integration/autoland', 00:06:38 INFO - 'scm_level': 1, 00:06:38 INFO - 'sendchange_masters': ('buildbot-master81.build.mozilla.org:9301',), 00:06:38 INFO - 'src_mozconfig': 'browser/config/mozconfigs/win32/debug', 00:06:38 INFO - 'stage_platform': 'win32-debug', 00:06:38 INFO - 'stage_product': 'firefox', 00:06:38 INFO - 'stage_server': 'upload.ffxbld.productdelivery.prod.mozaws.net', 00:06:38 INFO - 'stage_ssh_key': 'ffxbld_rsa', 00:06:38 INFO - 'stage_username': 'ffxbld', 00:06:38 INFO - 'taskcluster_credentials_file': 'oauth.txt', 00:06:38 INFO - 'taskcluster_index': 'index', 00:06:38 INFO - 'tinderbox_build_dir': None, 00:06:38 INFO - 'to_tinderbox_dated': True, 00:06:38 INFO - 'tools_repo': 'https://hg.mozilla.org/build/tools', 00:06:38 INFO - 'tooltool_bootstrap': 'setup.sh', 00:06:38 INFO - 'tooltool_manifest_src': 'browser/config/tooltool-manifests/win32/releng.manifest', 00:06:38 INFO - 'tooltool_script': ('c:\\mozilla-build\\python27\\python.exe', 00:06:38 INFO - 'C:/mozilla-build/tooltool.py'), 00:06:38 INFO - 'tooltool_url': 'https://api.pub.build.mozilla.org/tooltool/', 00:06:38 INFO - 'upload_env': {'UPLOAD_HOST': '%(stage_server)s', 00:06:38 INFO - 'UPLOAD_SSH_KEY': '/c/Users/cltbld/.ssh/%(stage_ssh_key)s', 00:06:38 INFO - 'UPLOAD_TO_TEMP': '1', 00:06:38 INFO - 'UPLOAD_USER': '%(stage_username)s'}, 00:06:38 INFO - 'use_clobberer': True, 00:06:38 INFO - 'vcs_share_base': 'C:/builds/hg-shared', 00:06:38 INFO - 'virtualenv_modules': ('requests==2.8.1', 00:06:38 INFO - 'PyHawk-with-a-single-extra-commit==0.1.5', 00:06:38 INFO - 'taskcluster==0.0.26'), 00:06:38 INFO - 'virtualenv_path': 'venv', 00:06:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:06:38 INFO - 'who': '', 00:06:38 INFO - 'work_dir': 'build'} 00:06:38 INFO - [mozharness: 2016-12-28 08:06:38.438000Z] Skipping get-secrets step. 00:06:38 INFO - [mozharness: 2016-12-28 08:06:38.438000Z] Running clobber step. 00:06:38 INFO - Running main action method: clobber 00:06:38 INFO - retry: Calling run_command with args: [['python', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\scripts\\external_tools\\clobberer.py', '-s', 'scripts', '-s', 'logs', '-s', 'buildprops.json', '-s', 'token', '-s', 'oauth.txt', '-t', '168', 'https://api.pub.build.mozilla.org/clobberer/lastclobber', u'autoland', u'WINNT 5.2 autoland leak test build', 'autoland-w32-d-000000000000000', u'b-2008-spot-002', u'http://buildbot-master70.bb.releng.use1.mozilla.com:8001/']], kwargs: {'error_list': [{'substr': 'Error contacting server', 'explanation': 'Error contacting server for clobberer information.', 'level': 'error'}], 'cwd': 'c:\\builds\\moz2_slave'}, attempt #1 00:06:38 INFO - Running command: ['python', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\scripts\\external_tools\\clobberer.py', '-s', 'scripts', '-s', 'logs', '-s', 'buildprops.json', '-s', 'token', '-s', 'oauth.txt', '-t', '168', 'https://api.pub.build.mozilla.org/clobberer/lastclobber', u'autoland', u'WINNT 5.2 autoland leak test build', 'autoland-w32-d-000000000000000', u'b-2008-spot-002', u'http://buildbot-master70.bb.releng.use1.mozilla.com:8001/'] in c:\builds\moz2_slave 00:06:38 INFO - Copy/paste: python c:\builds\moz2_slave\autoland-w32-d-000000000000000\scripts\external_tools\clobberer.py -s scripts -s logs -s buildprops.json -s token -s oauth.txt -t 168 https://api.pub.build.mozilla.org/clobberer/lastclobber autoland "WINNT 5.2 autoland leak test build" autoland-w32-d-000000000000000 b-2008-spot-002 http://buildbot-master70.bb.releng.use1.mozilla.com:8001/ 00:06:39 INFO - Checking clobber URL: https://api.pub.build.mozilla.org/clobberer/lastclobber?master=http%3A%2F%2Fbuildbot-master70.bb.releng.use1.mozilla.com%3A8001%2F&slave=b-2008-spot-002&builddir=autoland-w32-d-000000000000000&branch=autoland&buildername=WINNT+5.2+autoland+leak+test+build 00:06:39 INFO - autoland-w32-d-000000000000000:Our last clobber date: None 00:06:39 INFO - autoland-w32-d-000000000000000:Server clobber date: 2016-12-17 14:09:38 00:06:39 INFO - TinderboxPrint: purged clobber 00:06:39 INFO - Return code: 0 00:06:39 INFO - [mozharness: 2016-12-28 08:06:39.087000Z] Finished clobber step (success) 00:06:39 INFO - [mozharness: 2016-12-28 08:06:39.087000Z] Running clone-tools step. 00:06:39 INFO - Running main action method: clone_tools 00:06:39 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\tools'), kwargs: {}, attempt #1 00:06:39 WARNING - did not specify revision or branch; assuming "default" 00:06:39 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 00:06:39 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 00:06:44 INFO - Mercurial Distributed SCM (version 3.9.1) 00:06:44 INFO - (see https://mercurial-scm.org for more information) 00:06:44 INFO - Copyright (C) 2005-2016 Matt Mackall and others 00:06:44 INFO - This is free software; see the source for copying conditions. There is NO 00:06:44 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 00:06:44 INFO - Return code: 0 00:06:44 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 00:06:44 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 00:06:45 INFO - checking encoding (cp1252)... 00:06:45 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 00:06:45 INFO - checking Python version (2.7.12) 00:06:45 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 00:06:45 INFO - checking Mercurial version (3.9.1) 00:06:45 INFO - checking Mercurial custom build () 00:06:45 INFO - checking module policy (c) 00:06:45 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 00:06:45 INFO - checking templates (c:\mozilla-build\hg\templates)... 00:06:45 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 00:06:45 INFO - checking commit editor... (notepad) 00:06:45 INFO - checking username (Mozilla Release Engineering ) 00:06:45 INFO - no problems detected 00:06:45 INFO - Return code: 0 00:06:45 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/build/tools', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\tools', '--sharebase', 'C:/builds/hg-shared', '--branch', 'default'] 00:06:45 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\autoland-w32-d-000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/build/tools c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\tools --sharebase C:/builds/hg-shared --branch default 00:06:57 INFO - 00:06:57 INFO - clone [================================> ] 20319000/29479930 04s 00:06:57 INFO - 00:06:57 INFO - ensuring https://hg.mozilla.org/build/tools@default is available at c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\tools 00:06:57 INFO - (sharing from new pooled repository 7ae7fb134bf7aec6ec96a062ff47a69053dd2973) 00:06:57 INFO - applying clone bundle from https://s3-external-1.amazonaws.com/moz-hg-bundles-us-east-1/build/tools/fc62aa72c4fb4366858ca90c7c9eaaf8c8b39c43.packed1.hg 00:06:57 INFO - 2048 files to transfer, 28.1 MB of data 00:06:57 INFO - transferred 28.1 MB in 9.8 seconds (2.87 MB/sec) 00:06:57 INFO - finished applying clone bundle 00:06:57 INFO - searching for changes 00:06:57 INFO - no changes found 00:06:57 INFO - searching for changes 00:06:57 INFO - no changes found 00:06:57 INFO - (pulling to obtain default) 00:06:57 INFO - (remote resolved default to fc62aa72c4fb4366858ca90c7c9eaaf8c8b39c43; result is not deterministic) 00:06:57 INFO - (revision already present locally; not pulling) 00:07:11 INFO - 00:07:11 INFO - updating [=====> ] 100/937 00:07:11 INFO - updating [====================================> ] 600/937 00:07:11 INFO - updating [========================================> ] 700/937 03s 00:07:11 INFO - updating [===================================================> ] 900/937 01s 00:07:11 INFO - 00:07:11 INFO - 937 files updated, 0 files merged, 0 files removed, 0 files unresolved 00:07:11 INFO - updated to fc62aa72c4fb4366858ca90c7c9eaaf8c8b39c43 00:07:11 INFO - Return code: 0 00:07:11 INFO - [mozharness: 2016-12-28 08:07:11.893000Z] Finished clone-tools step (success) 00:07:11 INFO - [mozharness: 2016-12-28 08:07:11.894000Z] Running checkout-sources step. 00:07:11 INFO - Running main action method: checkout_sources 00:07:11 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src'), kwargs: {}, attempt #1 00:07:11 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 00:07:11 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 00:07:12 INFO - Mercurial Distributed SCM (version 3.9.1) 00:07:12 INFO - (see https://mercurial-scm.org for more information) 00:07:12 INFO - Copyright (C) 2005-2016 Matt Mackall and others 00:07:12 INFO - This is free software; see the source for copying conditions. There is NO 00:07:12 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 00:07:12 INFO - Return code: 0 00:07:12 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 00:07:12 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 00:07:12 INFO - checking encoding (cp1252)... 00:07:12 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 00:07:12 INFO - checking Python version (2.7.12) 00:07:12 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 00:07:12 INFO - checking Mercurial version (3.9.1) 00:07:12 INFO - checking Mercurial custom build () 00:07:12 INFO - checking module policy (c) 00:07:12 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 00:07:12 INFO - checking templates (c:\mozilla-build\hg\templates)... 00:07:12 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 00:07:12 INFO - checking commit editor... (notepad) 00:07:12 INFO - checking username (Mozilla Release Engineering ) 00:07:12 INFO - no problems detected 00:07:12 INFO - Return code: 0 00:07:12 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/integration/autoland', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src', '--sharebase', 'C:/builds/hg-shared', '--revision', '21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0'] 00:07:12 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\autoland-w32-d-000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/integration/autoland c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src --sharebase C:/builds/hg-shared --revision 21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 00:09:18 INFO - 00:09:18 INFO - manifests [> ] 1/45 00:09:18 INFO - 00:09:18 INFO - 00:09:18 INFO - files [> ] 2/108 9m02s 00:09:18 INFO - files [=> ] 5/108 3m22s 00:09:18 INFO - files [==> ] 7/108 2m33s 00:09:18 INFO - files [===> ] 9/108 2m12s 00:09:18 INFO - files [===========> ] 23/108 51s 00:09:18 INFO - files [============> ] 26/108 49s 00:09:18 INFO - files [==============> ] 28/108 47s 00:09:18 INFO - files [=================> ] 34/108 38s 00:09:18 INFO - files [==================> ] 36/108 38s 00:09:18 INFO - files [===================> ] 39/108 36s 00:09:18 INFO - files [====================> ] 40/108 38s 00:09:18 INFO - files [=====================> ] 42/108 38s 00:09:18 INFO - files [=======================> ] 45/108 37s 00:09:18 INFO - files [==========================> ] 51/108 31s 00:09:18 INFO - files [===========================> ] 53/108 30s 00:09:18 INFO - files [=============================> ] 56/108 28s 00:09:18 INFO - files [==============================> ] 58/108 27s 00:09:18 INFO - files [===================================> ] 68/108 19s 00:09:18 INFO - files [=============================================> ] 86/108 09s 00:09:18 INFO - files [==============================================> ] 88/108 08s 00:09:18 INFO - files [===============================================> ] 91/108 07s 00:09:18 INFO - files [================================================> ] 92/108 07s 00:09:18 INFO - files [=================================================> ] 94/108 06s 00:09:18 INFO - files [==================================================> ] 95/108 06s 00:09:18 INFO - files [====================================================> ] 100/108 04s 00:09:18 INFO - 00:09:18 INFO - ensuring https://hg.mozilla.org/integration/autoland@21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 is available at c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src 00:09:18 INFO - (sharing from existing pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29) 00:09:18 INFO - searching for changes 00:09:18 INFO - adding changesets 00:09:18 INFO - adding manifests 00:09:18 INFO - adding file changes 00:09:18 INFO - added 45 changesets with 131 changes to 100 files 00:48:55 INFO - 00:48:55 INFO - updating [ ] 100/151687 00:48:55 INFO - updating [ ] 200/151687 3h21m 00:48:55 INFO - updating [ ] 300/151687 4h04m 00:48:55 INFO - updating [ ] 400/151687 2h56m 00:48:55 INFO - updating [ ] 500/151687 2h40m 00:48:55 INFO - updating [ ] 600/151687 2h15m 00:48:55 INFO - updating [ ] 700/151687 2h09m 00:48:55 INFO - updating [ ] 800/151687 1h56m 00:48:55 INFO - updating [ ] 900/151687 1h55m 00:48:55 INFO - updating [ ] 1000/151687 1h48m 00:48:55 INFO - updating [ ] 1100/151687 1h42m 00:48:55 INFO - updating [ ] 1200/151687 1h37m 00:48:55 INFO - updating [ ] 1300/151687 1h34m 00:48:55 INFO - updating [ ] 1400/151687 1h30m 00:48:55 INFO - updating [ ] 1500/151687 1h26m 00:48:55 INFO - updating [ ] 1600/151687 1h23m 00:48:55 INFO - updating [ ] 1700/151687 1h20m 00:48:55 INFO - updating [ ] 1800/151687 1h20m 00:48:55 INFO - updating [ ] 1900/151687 1h19m 00:48:55 INFO - updating [ ] 2000/151687 1h25m 00:48:55 INFO - updating [ ] 2100/151687 1h26m 00:48:55 INFO - updating [ ] 2200/151687 1h25m 00:48:55 INFO - updating [ ] 2300/151687 1h28m 00:48:55 INFO - updating [ ] 2400/151687 1h32m 00:48:55 INFO - updating [ ] 2500/151687 1h32m 00:48:55 INFO - updating [ ] 2600/151687 1h30m 00:48:55 INFO - updating [ ] 2700/151687 1h30m 00:48:55 INFO - updating [ ] 2900/151687 1h27m 00:48:55 INFO - updating [ ] 3000/151687 1h25m 00:48:55 INFO - updating [ ] 3100/151687 1h26m 00:48:55 INFO - updating [ ] 3200/151687 1h29m 00:48:55 INFO - updating [> ] 3300/151687 1h37m 00:48:55 INFO - updating [> ] 3400/151687 1h37m 00:48:55 INFO - updating [> ] 3600/151687 1h34m 00:48:55 INFO - updating [> ] 3700/151687 1h34m 00:48:55 INFO - updating [> ] 3800/151687 1h33m 00:48:55 INFO - updating [> ] 3900/151687 1h32m 00:48:55 INFO - updating [> ] 4000/151687 1h31m 00:48:55 INFO - updating [> ] 4100/151687 1h31m 00:48:55 INFO - updating [> ] 4200/151687 1h30m 00:48:55 INFO - updating [> ] 4300/151687 1h30m 00:48:55 INFO - updating [> ] 4400/151687 1h29m 00:48:55 INFO - updating [> ] 4500/151687 1h28m 00:48:55 INFO - updating [> ] 4600/151687 1h31m 00:48:55 INFO - updating [> ] 4700/151687 1h30m 00:48:55 INFO - updating [> ] 4800/151687 1h29m 00:48:55 INFO - updating [> ] 5000/151687 1h27m 00:48:55 INFO - updating [> ] 5100/151687 1h26m 00:48:55 INFO - updating [> ] 5200/151687 1h27m 00:48:55 INFO - updating [> ] 5400/151687 1h27m 00:48:55 INFO - updating [> ] 5500/151687 1h26m 00:48:55 INFO - updating [> ] 5700/151687 1h26m 00:48:55 INFO - updating [> ] 5800/151687 1h26m 00:48:55 INFO - updating [> ] 5900/151687 1h25m 00:48:55 INFO - updating [> ] 6000/151687 1h25m 00:48:55 INFO - updating [> ] 6100/151687 1h24m 00:48:55 INFO - updating [> ] 6200/151687 1h26m 00:48:55 INFO - updating [> ] 6300/151687 1h25m 00:48:55 INFO - updating [> ] 6400/151687 1h25m 00:48:55 INFO - updating [=> ] 6500/151687 1h24m 00:48:55 INFO - updating [=> ] 6600/151687 1h24m 00:48:55 INFO - updating [=> ] 6700/151687 1h24m 00:48:55 INFO - updating [=> ] 6800/151687 1h23m 00:48:55 INFO - updating [=> ] 6900/151687 1h22m 00:48:55 INFO - updating [=> ] 7000/151687 1h22m 00:48:55 INFO - updating [=> ] 7200/151687 1h21m 00:48:55 INFO - updating [=> ] 7300/151687 1h21m 00:48:55 INFO - updating [=> ] 7400/151687 1h21m 00:48:55 INFO - updating [=> ] 7500/151687 1h24m 00:48:55 INFO - updating [=> ] 7600/151687 1h24m 00:48:55 INFO - updating [=> ] 7700/151687 1h24m 00:48:55 INFO - updating [=> ] 7800/151687 1h24m 00:48:55 INFO - updating [=> ] 7900/151687 1h24m 00:48:55 INFO - updating [=> ] 8000/151687 1h24m 00:48:55 INFO - updating [=> ] 8100/151687 1h24m 00:48:55 INFO - updating [=> ] 8300/151687 1h22m 00:48:55 INFO - updating [=> ] 8400/151687 1h21m 00:48:55 INFO - updating [=> ] 8500/151687 1h21m 00:48:55 INFO - updating [=> ] 8600/151687 1h20m 00:48:55 INFO - updating [=> ] 8800/151687 1h20m 00:48:55 INFO - updating [=> ] 9000/151687 1h19m 00:48:55 INFO - updating [=> ] 9200/151687 1h17m 00:48:55 INFO - updating [=> ] 9300/151687 1h17m 00:48:55 INFO - updating [=> ] 9400/151687 1h16m 00:48:55 INFO - updating [=> ] 9500/151687 1h16m 00:48:55 INFO - updating [=> ] 9600/151687 1h15m 00:48:55 INFO - updating [==> ] 9700/151687 1h15m 00:48:55 INFO - updating [==> ] 9900/151687 1h14m 00:48:55 INFO - updating [==> ] 10100/151687 1h13m 00:48:55 INFO - updating [==> ] 10200/151687 1h12m 00:48:55 INFO - updating [==> ] 10300/151687 1h12m 00:48:55 INFO - updating [==> ] 10500/151687 1h11m 00:48:55 INFO - updating [==> ] 10600/151687 1h11m 00:48:55 INFO - updating [==> ] 10700/151687 1h11m 00:48:55 INFO - updating [==> ] 10900/151687 1h10m 00:48:55 INFO - updating [==> ] 11100/151687 1h09m 00:48:55 INFO - updating [==> ] 11200/151687 1h09m 00:48:55 INFO - updating [==> ] 11300/151687 1h08m 00:48:55 INFO - updating [==> ] 11400/151687 1h08m 00:48:55 INFO - updating [==> ] 11700/151687 1h06m 00:48:55 INFO - updating [==> ] 11800/151687 1h06m 00:48:55 INFO - updating [==> ] 11900/151687 1h06m 00:48:55 INFO - updating [==> ] 12000/151687 1h06m 00:48:55 INFO - updating [==> ] 12100/151687 1h06m 00:48:55 INFO - updating [==> ] 12400/151687 1h05m 00:48:55 INFO - updating [==> ] 12500/151687 1h04m 00:48:55 INFO - updating [==> ] 12600/151687 1h04m 00:48:55 INFO - updating [==> ] 12700/151687 1h04m 00:48:55 INFO - updating [==> ] 12800/151687 1h04m 00:48:55 INFO - updating [===> ] 13000/151687 1h03m 00:48:55 INFO - updating [===> ] 13100/151687 1h03m 00:48:55 INFO - updating [===> ] 13300/151687 1h02m 00:48:55 INFO - updating [===> ] 13500/151687 1h01m 00:48:55 INFO - updating [===> ] 13600/151687 1h01m 00:48:55 INFO - updating [===> ] 13700/151687 1h01m 00:48:55 INFO - updating [===> ] 13800/151687 1h01m 00:48:55 INFO - updating [===> ] 14000/151687 59m48s 00:48:55 INFO - updating [===> ] 14200/151687 59m36s 00:48:55 INFO - updating [===> ] 14300/151687 59m34s 00:48:55 INFO - updating [===> ] 14400/151687 59m21s 00:48:55 INFO - updating [===> ] 14500/151687 59m04s 00:48:55 INFO - updating [===> ] 14800/151687 58m02s 00:48:55 INFO - updating [===> ] 15100/151687 56m58s 00:48:55 INFO - updating [===> ] 15200/151687 57m02s 00:48:55 INFO - updating [===> ] 15300/151687 56m55s 00:48:55 INFO - updating [===> ] 15400/151687 56m47s 00:48:55 INFO - updating [===> ] 15600/151687 56m23s 00:48:55 INFO - updating [===> ] 15700/151687 56m11s 00:48:55 INFO - updating [===> ] 15900/151687 55m37s 00:48:55 INFO - updating [===> ] 16000/151687 55m31s 00:48:55 INFO - updating [===> ] 16100/151687 55m27s 00:48:55 INFO - updating [===> ] 16200/151687 55m23s 00:48:55 INFO - updating [===> ] 16300/151687 55m12s 00:48:55 INFO - updating [===> ] 16400/151687 55m08s 00:48:55 INFO - updating [====> ] 16600/151687 54m45s 00:48:55 INFO - updating [====> ] 16700/151687 54m44s 00:48:55 INFO - updating [====> ] 16800/151687 54m33s 00:48:55 INFO - updating [====> ] 16900/151687 54m38s 00:48:55 INFO - updating [====> ] 17000/151687 54m33s 00:48:55 INFO - updating [====> ] 17100/151687 54m24s 00:48:55 INFO - updating [====> ] 17200/151687 54m16s 00:48:55 INFO - updating [====> ] 17400/151687 53m49s 00:48:55 INFO - updating [====> ] 17600/151687 53m26s 00:48:55 INFO - updating [====> ] 17800/151687 53m05s 00:48:55 INFO - updating [====> ] 18000/151687 52m54s 00:48:55 INFO - updating [====> ] 18100/151687 52m42s 00:48:55 INFO - updating [====> ] 18300/151687 52m17s 00:48:55 INFO - updating [====> ] 18400/151687 52m07s 00:48:55 INFO - updating [====> ] 18600/151687 51m37s 00:48:55 INFO - updating [====> ] 18800/151687 51m28s 00:48:55 INFO - updating [====> ] 18900/151687 51m21s 00:48:55 INFO - updating [====> ] 19000/151687 51m23s 00:48:55 INFO - updating [====> ] 19100/151687 51m14s 00:48:55 INFO - updating [====> ] 19200/151687 51m03s 00:48:55 INFO - updating [====> ] 19400/151687 50m38s 00:48:55 INFO - updating [====> ] 19500/151687 50m29s 00:48:55 INFO - updating [====> ] 19700/151687 50m09s 00:48:55 INFO - updating [=====> ] 19800/151687 49m59s 00:48:55 INFO - updating [=====> ] 20000/151687 49m54s 00:48:55 INFO - updating [=====> ] 20100/151687 49m57s 00:48:55 INFO - updating [=====> ] 20300/151687 49m38s 00:48:55 INFO - updating [=====> ] 20400/151687 49m31s 00:48:55 INFO - updating [=====> ] 20500/151687 49m25s 00:48:55 INFO - updating [=====> ] 20700/151687 49m02s 00:48:55 INFO - updating [=====> ] 20900/151687 48m45s 00:48:55 INFO - updating [=====> ] 21000/151687 48m44s 00:48:55 INFO - updating [=====> ] 21300/151687 48m04s 00:48:55 INFO - updating [=====> ] 21500/151687 47m41s 00:48:55 INFO - updating [=====> ] 22100/151687 46m21s 00:48:55 INFO - updating [=====> ] 22400/151687 45m48s 00:48:55 INFO - updating [=====> ] 22500/151687 45m44s 00:48:55 INFO - updating [=====> ] 22600/151687 45m38s 00:48:55 INFO - updating [=====> ] 22700/151687 45m33s 00:48:55 INFO - updating [=====> ] 22800/151687 45m27s 00:48:55 INFO - updating [=====> ] 23000/151687 45m04s 00:48:55 INFO - updating [======> ] 23100/151687 44m59s 00:48:55 INFO - updating [======> ] 23200/151687 44m54s 00:48:55 INFO - updating [======> ] 23300/151687 44m51s 00:48:55 INFO - updating [======> ] 23500/151687 44m33s 00:48:55 INFO - updating [======> ] 23800/151687 43m59s 00:48:55 INFO - updating [======> ] 24100/151687 43m26s 00:48:55 INFO - updating [======> ] 24200/151687 43m19s 00:48:55 INFO - updating [======> ] 24400/151687 43m01s 00:48:55 INFO - updating [======> ] 24500/151687 43m12s 00:48:55 INFO - updating [======> ] 24600/151687 43m10s 00:48:55 INFO - updating [======> ] 24800/151687 42m50s 00:48:55 INFO - updating [======> ] 24900/151687 42m45s 00:48:55 INFO - updating [======> ] 25100/151687 42m42s 00:48:55 INFO - updating [======> ] 25200/151687 42m39s 00:48:55 INFO - updating [======> ] 25300/151687 42m41s 00:48:55 INFO - updating [======> ] 25400/151687 42m40s 00:48:55 INFO - updating [======> ] 25500/151687 42m36s 00:48:55 INFO - updating [======> ] 25600/151687 42m30s 00:48:55 INFO - updating [======> ] 25700/151687 42m30s 00:48:55 INFO - updating [======> ] 25800/151687 42m29s 00:48:55 INFO - updating [======> ] 25900/151687 42m28s 00:48:55 INFO - updating [======> ] 26100/151687 42m11s 00:48:55 INFO - updating [======> ] 26300/151687 42m03s 00:48:55 INFO - updating [=======> ] 26400/151687 42m02s 00:48:55 INFO - updating [=======> ] 26600/151687 41m52s 00:48:55 INFO - updating [=======> ] 26700/151687 41m53s 00:48:55 INFO - updating [=======> ] 26900/151687 41m39s 00:48:55 INFO - updating [=======> ] 27000/151687 41m46s 00:48:55 INFO - updating [=======> ] 27100/151687 42m01s 00:48:55 INFO - updating [=======> ] 27200/151687 41m59s 00:48:55 INFO - updating [=======> ] 27400/151687 41m45s 00:48:55 INFO - updating [=======> ] 27600/151687 41m31s 00:48:55 INFO - updating [=======> ] 27700/151687 41m31s 00:48:55 INFO - updating [=======> ] 27900/151687 41m20s 00:48:55 INFO - updating [=======> ] 28000/151687 42m05s 00:48:55 INFO - updating [=======> ] 28100/151687 42m01s 00:48:55 INFO - updating [=======> ] 28300/151687 41m44s 00:48:55 INFO - updating [=======> ] 28500/151687 41m27s 00:48:55 INFO - updating [=======> ] 28800/151687 41m06s 00:48:55 INFO - updating [=======> ] 29000/151687 40m50s 00:48:55 INFO - updating [=======> ] 29100/151687 40m49s 00:48:55 INFO - updating [=======> ] 29400/151687 40m23s 00:48:55 INFO - updating [=======> ] 29600/151687 40m09s 00:48:55 INFO - updating [========> ] 29700/151687 40m04s 00:48:55 INFO - updating [========> ] 29900/151687 39m49s 00:48:55 INFO - updating [========> ] 30200/151687 39m32s 00:48:55 INFO - updating [========> ] 30300/151687 39m28s 00:48:55 INFO - updating [========> ] 30400/151687 39m24s 00:48:55 INFO - updating [========> ] 30600/151687 39m10s 00:48:55 INFO - updating [========> ] 30700/151687 39m06s 00:48:55 INFO - updating [========> ] 30900/151687 38m51s 00:48:55 INFO - updating [========> ] 31100/151687 38m36s 00:48:55 INFO - updating [========> ] 31200/151687 38m33s 00:48:55 INFO - updating [========> ] 31400/151687 38m18s 00:48:55 INFO - updating [========> ] 31600/151687 38m04s 00:48:55 INFO - updating [========> ] 31800/151687 37m51s 00:48:55 INFO - updating [========> ] 32000/151687 37m39s 00:48:55 INFO - updating [========> ] 32400/151687 37m09s 00:48:55 INFO - updating [========> ] 32500/151687 37m05s 00:48:55 INFO - updating [========> ] 32700/151687 36m53s 00:48:55 INFO - updating [========> ] 32900/151687 36m41s 00:48:55 INFO - updating [=========> ] 33000/151687 36m36s 00:48:55 INFO - updating [=========> ] 33300/151687 36m16s 00:48:55 INFO - updating [=========> ] 33600/151687 35m55s 00:48:55 INFO - updating [=========> ] 33800/151687 35m44s 00:48:55 INFO - updating [=========> ] 33900/151687 35m40s 00:48:55 INFO - updating [=========> ] 34100/151687 35m28s 00:48:55 INFO - updating [=========> ] 34200/151687 35m24s 00:48:55 INFO - updating [=========> ] 34700/151687 34m49s 00:48:55 INFO - updating [=========> ] 35000/151687 34m31s 00:48:55 INFO - updating [=========> ] 35200/151687 34m21s 00:48:55 INFO - updating [=========> ] 35400/151687 34m13s 00:48:55 INFO - updating [=========> ] 35500/151687 34m10s 00:48:55 INFO - updating [=========> ] 35600/151687 34m07s 00:48:55 INFO - updating [=========> ] 35700/151687 34m06s 00:48:55 INFO - updating [=========> ] 36000/151687 33m58s 00:48:55 INFO - updating [=========> ] 36200/151687 33m48s 00:48:55 INFO - updating [==========> ] 36400/151687 33m39s 00:48:55 INFO - updating [==========> ] 36500/151687 33m36s 00:48:55 INFO - updating [==========> ] 36600/151687 33m32s 00:48:55 INFO - updating [==========> ] 36700/151687 33m29s 00:48:55 INFO - updating [==========> ] 36800/151687 33m26s 00:48:55 INFO - updating [==========> ] 37400/151687 32m54s 00:48:55 INFO - updating [==========> ] 37500/151687 32m55s 00:48:55 INFO - updating [==========> ] 37600/151687 32m54s 00:48:55 INFO - updating [==========> ] 37700/151687 33m05s 00:48:55 INFO - updating [==========> ] 37800/151687 33m04s 00:48:55 INFO - updating [==========> ] 38000/151687 32m58s 00:48:55 INFO - updating [==========> ] 38100/151687 32m55s 00:48:55 INFO - updating [==========> ] 38200/151687 32m52s 00:48:55 INFO - updating [==========> ] 38300/151687 32m51s 00:48:55 INFO - updating [==========> ] 38500/151687 32m42s 00:48:55 INFO - updating [==========> ] 38600/151687 32m44s 00:48:55 INFO - updating [==========> ] 38700/151687 32m44s 00:48:55 INFO - updating [==========> ] 38800/151687 32m41s 00:48:55 INFO - updating [==========> ] 38900/151687 32m38s 00:48:55 INFO - updating [==========> ] 39000/151687 32m39s 00:48:55 INFO - updating [==========> ] 39100/151687 32m39s 00:48:55 INFO - updating [==========> ] 39300/151687 32m36s 00:48:55 INFO - updating [==========> ] 39400/151687 32m35s 00:48:55 INFO - updating [===========> ] 39600/151687 32m36s 00:48:55 INFO - updating [===========> ] 39800/151687 32m27s 00:48:55 INFO - updating [===========> ] 39900/151687 32m30s 00:48:55 INFO - updating [===========> ] 40000/151687 32m36s 00:48:55 INFO - updating [===========> ] 40100/151687 32m35s 00:48:55 INFO - updating [===========> ] 40300/151687 32m29s 00:48:55 INFO - updating [===========> ] 40400/151687 32m27s 00:48:55 INFO - updating [===========> ] 40600/151687 32m19s 00:48:55 INFO - updating [===========> ] 40800/151687 32m11s 00:48:55 INFO - updating [===========> ] 41000/151687 32m03s 00:48:55 INFO - updating [===========> ] 41200/151687 31m54s 00:48:55 INFO - updating [===========> ] 41400/151687 31m49s 00:48:55 INFO - updating [===========> ] 41500/151687 31m47s 00:48:55 INFO - updating [===========> ] 41600/151687 31m44s 00:48:55 INFO - updating [===========> ] 41800/151687 31m40s 00:48:55 INFO - updating [===========> ] 41900/151687 31m37s 00:48:55 INFO - updating [===========> ] 42200/151687 31m30s 00:48:55 INFO - updating [===========> ] 42300/151687 31m28s 00:48:55 INFO - updating [===========> ] 42400/151687 31m28s 00:48:55 INFO - updating [===========> ] 42500/151687 31m32s 00:48:55 INFO - updating [===========> ] 42600/151687 31m37s 00:48:55 INFO - updating [===========> ] 42700/151687 31m34s 00:48:55 INFO - updating [============> ] 42900/151687 31m27s 00:48:55 INFO - updating [============> ] 43000/151687 31m25s 00:48:55 INFO - updating [============> ] 43300/151687 31m10s 00:48:55 INFO - updating [============> ] 43600/151687 30m55s 00:48:55 INFO - updating [============> ] 43800/151687 30m49s 00:48:55 INFO - updating [============> ] 43900/151687 30m47s 00:48:55 INFO - updating [============> ] 44200/151687 30m38s 00:48:55 INFO - updating [============> ] 44300/151687 30m39s 00:48:55 INFO - updating [============> ] 44400/151687 30m42s 00:48:55 INFO - updating [============> ] 44500/151687 30m41s 00:48:55 INFO - updating [============> ] 44600/151687 30m40s 00:48:55 INFO - updating [============> ] 44700/151687 30m40s 00:48:55 INFO - updating [============> ] 44800/151687 30m39s 00:48:55 INFO - updating [============> ] 45100/151687 30m26s 00:48:55 INFO - updating [============> ] 45300/151687 30m30s 00:48:55 INFO - updating [============> ] 45400/151687 30m27s 00:48:55 INFO - updating [============> ] 45600/151687 30m18s 00:48:55 INFO - updating [============> ] 45700/151687 30m17s 00:48:55 INFO - updating [============> ] 45800/151687 30m14s 00:48:55 INFO - updating [============> ] 46000/151687 30m08s 00:48:55 INFO - updating [============> ] 46100/151687 30m21s 00:48:55 INFO - updating [=============> ] 46200/151687 30m19s 00:48:55 INFO - updating [=============> ] 46400/151687 30m20s 00:48:55 INFO - updating [=============> ] 46500/151687 30m19s 00:48:55 INFO - updating [=============> ] 46600/151687 30m18s 00:48:55 INFO - updating [=============> ] 46900/151687 30m12s 00:48:55 INFO - updating [=============> ] 47000/151687 30m12s 00:48:55 INFO - updating [=============> ] 47200/151687 30m04s 00:48:55 INFO - updating [=============> ] 47300/151687 30m04s 00:48:55 INFO - updating [=============> ] 47400/151687 30m01s 00:48:55 INFO - updating [=============> ] 47500/151687 30m04s 00:48:55 INFO - updating [=============> ] 47600/151687 30m04s 00:48:55 INFO - updating [=============> ] 47800/151687 29m58s 00:48:55 INFO - updating [=============> ] 47900/151687 29m54s 00:48:55 INFO - updating [=============> ] 48000/151687 29m55s 00:48:55 INFO - updating [=============> ] 48100/151687 29m59s 00:48:55 INFO - updating [=============> ] 48200/151687 29m59s 00:48:55 INFO - updating [=============> ] 48300/151687 29m59s 00:48:55 INFO - updating [=============> ] 48500/151687 29m54s 00:48:55 INFO - updating [=============> ] 48600/151687 29m54s 00:48:55 INFO - updating [=============> ] 48900/151687 29m41s 00:48:55 INFO - updating [=============> ] 49000/151687 29m52s 00:48:55 INFO - updating [=============> ] 49100/151687 29m56s 00:48:55 INFO - updating [=============> ] 49200/151687 29m53s 00:48:55 INFO - updating [=============> ] 49400/151687 29m47s 00:48:55 INFO - updating [==============> ] 49500/151687 29m51s 00:48:55 INFO - updating [==============> ] 49600/151687 30m04s 00:48:55 INFO - updating [==============> ] 49700/151687 30m02s 00:48:55 INFO - updating [==============> ] 50000/151687 29m48s 00:48:55 INFO - updating [==============> ] 50100/151687 29m59s 00:48:55 INFO - updating [==============> ] 50400/151687 29m46s 00:48:55 INFO - updating [==============> ] 50700/151687 29m32s 00:48:55 INFO - updating [==============> ] 50900/151687 29m25s 00:48:55 INFO - updating [==============> ] 51100/151687 29m16s 00:48:55 INFO - updating [==============> ] 51300/151687 29m08s 00:48:55 INFO - updating [==============> ] 51600/151687 28m55s 00:48:55 INFO - updating [==============> ] 52100/151687 28m33s 00:48:55 INFO - updating [==============> ] 52200/151687 28m30s 00:48:55 INFO - updating [==============> ] 52600/151687 28m13s 00:48:55 INFO - updating [===============> ] 53000/151687 27m55s 00:48:55 INFO - updating [===============> ] 53300/151687 27m44s 00:48:55 INFO - updating [===============> ] 53700/151687 27m28s 00:48:55 INFO - updating [===============> ] 53800/151687 27m26s 00:48:55 INFO - updating [===============> ] 54000/151687 27m19s 00:48:55 INFO - updating [===============> ] 54400/151687 27m02s 00:48:55 INFO - updating [===============> ] 54800/151687 26m46s 00:48:55 INFO - updating [===============> ] 55100/151687 26m37s 00:48:55 INFO - updating [===============> ] 55200/151687 26m35s 00:48:55 INFO - updating [===============> ] 55300/151687 26m39s 00:48:55 INFO - updating [===============> ] 55400/151687 26m46s 00:48:55 INFO - updating [===============> ] 55500/151687 26m46s 00:48:55 INFO - updating [===============> ] 55600/151687 26m48s 00:48:55 INFO - updating [===============> ] 55700/151687 26m47s 00:48:55 INFO - updating [===============> ] 55800/151687 26m54s 00:48:55 INFO - updating [===============> ] 55900/151687 27m17s 00:48:55 INFO - updating [===============> ] 56000/151687 27m14s 00:48:55 INFO - updating [================> ] 56200/151687 27m08s 00:48:55 INFO - updating [================> ] 56400/151687 27m02s 00:48:55 INFO - updating [================> ] 56500/151687 27m00s 00:48:55 INFO - updating [================> ] 56600/151687 26m58s 00:48:55 INFO - updating [================> ] 56700/151687 26m55s 00:48:55 INFO - updating [================> ] 56800/151687 26m55s 00:48:55 INFO - updating [================> ] 57000/151687 26m49s 00:48:55 INFO - updating [================> ] 57100/151687 26m46s 00:48:55 INFO - updating [================> ] 57300/151687 26m39s 00:48:55 INFO - updating [================> ] 57500/151687 26m33s 00:48:55 INFO - updating [================> ] 57600/151687 26m32s 00:48:55 INFO - updating [================> ] 57900/151687 26m21s 00:48:55 INFO - updating [================> ] 58100/151687 26m14s 00:48:55 INFO - updating [================> ] 58200/151687 26m11s 00:48:55 INFO - updating [================> ] 58300/151687 26m11s 00:48:55 INFO - updating [================> ] 58500/151687 26m03s 00:48:55 INFO - updating [================> ] 58600/151687 26m02s 00:48:55 INFO - updating [================> ] 58800/151687 25m57s 00:48:55 INFO - updating [================> ] 59000/151687 25m51s 00:48:55 INFO - updating [================> ] 59100/151687 25m52s 00:48:55 INFO - updating [=================> ] 59400/151687 25m41s 00:48:55 INFO - updating [=================> ] 59500/151687 25m39s 00:48:55 INFO - updating [=================> ] 59700/151687 25m34s 00:48:55 INFO - updating [=================> ] 60000/151687 25m25s 00:48:55 INFO - updating [=================> ] 60300/151687 25m15s 00:48:55 INFO - updating [=================> ] 60600/151687 25m04s 00:48:55 INFO - updating [=================> ] 60700/151687 25m02s 00:48:55 INFO - updating [=================> ] 61100/151687 24m47s 00:48:55 INFO - updating [=================> ] 61400/151687 24m37s 00:48:55 INFO - updating [=================> ] 61500/151687 24m34s 00:48:55 INFO - updating [=================> ] 61800/151687 24m25s 00:48:55 INFO - updating [=================> ] 62000/151687 24m18s 00:48:55 INFO - updating [=================> ] 62200/151687 24m12s 00:48:55 INFO - updating [=================> ] 62400/151687 24m07s 00:48:55 INFO - updating [==================> ] 62700/151687 23m57s 00:48:55 INFO - updating [==================> ] 62900/151687 23m51s 00:48:55 INFO - updating [==================> ] 63200/151687 23m41s 00:48:55 INFO - updating [==================> ] 63400/151687 23m35s 00:48:55 INFO - updating [==================> ] 63600/151687 23m29s 00:48:55 INFO - updating [==================> ] 64000/151687 23m16s 00:48:55 INFO - updating [==================> ] 64100/151687 23m14s 00:48:55 INFO - updating [==================> ] 64200/151687 23m17s 00:48:55 INFO - updating [==================> ] 64300/151687 23m23s 00:48:55 INFO - updating [==================> ] 64400/151687 23m23s 00:48:55 INFO - updating [==================> ] 64500/151687 23m24s 00:48:55 INFO - updating [==================> ] 64700/151687 23m19s 00:48:55 INFO - updating [==================> ] 64900/151687 23m15s 00:48:55 INFO - updating [==================> ] 65200/151687 23m05s 00:48:55 INFO - updating [==================> ] 65400/151687 22m59s 00:48:55 INFO - updating [==================> ] 65500/151687 23m01s 00:48:55 INFO - updating [==================> ] 65800/151687 22m52s 00:48:55 INFO - updating [==================> ] 65900/151687 22m49s 00:48:55 INFO - updating [===================> ] 66000/151687 22m47s 00:48:55 INFO - updating [===================> ] 66300/151687 22m37s 00:48:55 INFO - updating [===================> ] 66500/151687 22m32s 00:48:55 INFO - updating [===================> ] 66800/151687 22m29s 00:48:55 INFO - updating [===================> ] 66900/151687 22m27s 00:48:55 INFO - updating [===================> ] 67000/151687 22m26s 00:48:55 INFO - updating [===================> ] 67200/151687 22m22s 00:48:55 INFO - updating [===================> ] 67600/151687 22m10s 00:48:55 INFO - updating [===================> ] 67800/151687 22m06s 00:48:55 INFO - updating [===================> ] 68000/151687 22m01s 00:48:55 INFO - updating [===================> ] 68500/151687 21m45s 00:48:55 INFO - updating [===================> ] 68800/151687 21m37s 00:48:55 INFO - updating [===================> ] 69100/151687 21m28s 00:48:55 INFO - updating [====================> ] 69500/151687 21m20s 00:48:55 INFO - updating [====================> ] 69700/151687 21m15s 00:48:55 INFO - updating [====================> ] 70000/151687 21m07s 00:48:55 INFO - updating [====================> ] 70500/151687 20m52s 00:48:55 INFO - updating [====================> ] 70800/151687 20m43s 00:48:55 INFO - updating [====================> ] 71300/151687 20m28s 00:48:55 INFO - updating [====================> ] 71600/151687 20m21s 00:48:55 INFO - updating [====================> ] 72000/151687 20m09s 00:48:55 INFO - updating [====================> ] 72400/151687 19m58s 00:48:55 INFO - updating [=====================> ] 72700/151687 19m50s 00:48:55 INFO - updating [=====================> ] 73100/151687 19m40s 00:48:55 INFO - updating [=====================> ] 73400/151687 19m32s 00:48:55 INFO - updating [=====================> ] 73600/151687 19m28s 00:48:55 INFO - updating [=====================> ] 73800/151687 19m24s 00:48:55 INFO - updating [=====================> ] 74100/151687 19m18s 00:48:55 INFO - updating [=====================> ] 74500/151687 19m07s 00:48:55 INFO - updating [=====================> ] 74900/151687 18m56s 00:48:55 INFO - updating [=====================> ] 75100/151687 18m52s 00:48:55 INFO - updating [=====================> ] 75400/151687 18m45s 00:48:55 INFO - updating [=====================> ] 75500/151687 18m43s 00:48:55 INFO - updating [=====================> ] 75600/151687 18m41s 00:48:55 INFO - updating [=====================> ] 75800/151687 18m37s 00:48:55 INFO - updating [======================> ] 76000/151687 18m32s 00:48:55 INFO - updating [======================> ] 76200/151687 18m28s 00:48:55 INFO - updating [======================> ] 76400/151687 18m24s 00:48:55 INFO - updating [======================> ] 76500/151687 18m30s 00:48:55 INFO - updating [======================> ] 76600/151687 18m35s 00:48:55 INFO - updating [======================> ] 77100/151687 18m22s 00:48:55 INFO - updating [======================> ] 77400/151687 18m16s 00:48:55 INFO - updating [======================> ] 77700/151687 18m09s 00:48:55 INFO - updating [======================> ] 77900/151687 18m05s 00:48:55 INFO - updating [======================> ] 78400/151687 17m52s 00:48:55 INFO - updating [======================> ] 78800/151687 17m43s 00:48:55 INFO - updating [======================> ] 79000/151687 17m38s 00:48:55 INFO - updating [======================> ] 79100/151687 17m36s 00:48:55 INFO - updating [=======================> ] 79300/151687 17m33s 00:48:55 INFO - updating [=======================> ] 79600/151687 17m26s 00:48:55 INFO - updating [=======================> ] 80100/151687 17m14s 00:48:55 INFO - updating [=======================> ] 80500/151687 17m04s 00:48:55 INFO - updating [=======================> ] 80800/151687 16m57s 00:48:55 INFO - updating [=======================> ] 81100/151687 16m50s 00:48:55 INFO - updating [=======================> ] 81400/151687 16m43s 00:48:55 INFO - updating [=======================> ] 81600/151687 16m39s 00:48:55 INFO - updating [=======================> ] 81800/151687 16m35s 00:48:55 INFO - updating [=======================> ] 82100/151687 16m28s 00:48:55 INFO - updating [=======================> ] 82400/151687 16m21s 00:48:55 INFO - updating [========================> ] 82800/151687 16m13s 00:48:55 INFO - updating [========================> ] 83200/151687 16m03s 00:48:55 INFO - updating [========================> ] 83500/151687 15m57s 00:48:55 INFO - updating [========================> ] 83900/151687 15m48s 00:48:55 INFO - updating [========================> ] 84100/151687 15m44s 00:48:55 INFO - updating [========================> ] 84500/151687 15m35s 00:48:55 INFO - updating [========================> ] 84700/151687 15m32s 00:48:55 INFO - updating [========================> ] 84900/151687 15m28s 00:48:55 INFO - updating [========================> ] 85200/151687 15m21s 00:48:55 INFO - updating [========================> ] 85400/151687 15m17s 00:48:55 INFO - updating [========================> ] 85600/151687 15m13s 00:48:55 INFO - updating [========================> ] 85700/151687 15m12s 00:48:55 INFO - updating [=========================> ] 86000/151687 15m06s 00:48:55 INFO - updating [=========================> ] 86100/151687 15m04s 00:48:55 INFO - updating [=========================> ] 86200/151687 15m02s 00:48:55 INFO - updating [=========================> ] 86400/151687 14m59s 00:48:55 INFO - updating [=========================> ] 86500/151687 14m58s 00:48:55 INFO - updating [=========================> ] 86700/151687 14m54s 00:48:55 INFO - updating [=========================> ] 86900/151687 14m50s 00:48:55 INFO - updating [=========================> ] 87100/151687 14m46s 00:48:55 INFO - updating [=========================> ] 87300/151687 14m42s 00:48:55 INFO - updating [=========================> ] 87400/151687 14m41s 00:48:55 INFO - updating [=========================> ] 87600/151687 14m39s 00:48:55 INFO - updating [=========================> ] 87700/151687 14m38s 00:48:55 INFO - updating [=========================> ] 88000/151687 14m31s 00:48:55 INFO - updating [=========================> ] 88200/151687 14m28s 00:48:55 INFO - updating [=========================> ] 88400/151687 14m24s 00:48:55 INFO - updating [=========================> ] 88700/151687 14m18s 00:48:55 INFO - updating [=========================> ] 89000/151687 14m11s 00:48:55 INFO - updating [==========================> ] 89200/151687 14m09s 00:48:55 INFO - updating [==========================> ] 89400/151687 14m06s 00:48:55 INFO - updating [==========================> ] 89500/151687 14m45s 00:48:55 INFO - updating [==========================> ] 89600/151687 15m35s 00:48:55 INFO - updating [==========================> ] 89700/151687 16m23s 00:48:55 INFO - updating [==========================> ] 89800/151687 17m09s 00:48:55 INFO - updating [==========================> ] 89900/151687 17m31s 00:48:55 INFO - updating [==========================> ] 90000/151687 17m53s 00:48:55 INFO - updating [==========================> ] 90100/151687 18m40s 00:48:55 INFO - updating [==========================> ] 90300/151687 18m35s 00:48:55 INFO - updating [==========================> ] 90400/151687 18m37s 00:48:55 INFO - updating [==========================> ] 90500/151687 18m37s 00:48:55 INFO - updating [==========================> ] 90600/151687 18m34s 00:48:55 INFO - updating [==========================> ] 90700/151687 18m32s 00:48:55 INFO - updating [==========================> ] 90800/151687 18m30s 00:48:55 INFO - updating [==========================> ] 90900/151687 18m28s 00:48:55 INFO - updating [==========================> ] 91000/151687 18m27s 00:48:55 INFO - updating [==========================> ] 91200/151687 18m23s 00:48:55 INFO - updating [==========================> ] 91400/151687 18m18s 00:48:55 INFO - updating [==========================> ] 91500/151687 18m16s 00:48:55 INFO - updating [==========================> ] 91600/151687 18m14s 00:48:55 INFO - updating [==========================> ] 91700/151687 18m12s 00:48:55 INFO - updating [==========================> ] 91800/151687 18m11s 00:48:55 INFO - updating [==========================> ] 91900/151687 18m09s 00:48:55 INFO - updating [==========================> ] 92000/151687 18m08s 00:48:55 INFO - updating [==========================> ] 92200/151687 18m05s 00:48:55 INFO - updating [==========================> ] 92300/151687 18m03s 00:48:55 INFO - updating [===========================> ] 92400/151687 18m02s 00:48:55 INFO - updating [===========================> ] 92500/151687 18m00s 00:48:55 INFO - updating [===========================> ] 92600/151687 17m58s 00:48:55 INFO - updating [===========================> ] 92700/151687 17m56s 00:48:55 INFO - updating [===========================> ] 92900/151687 17m53s 00:48:55 INFO - updating [===========================> ] 93000/151687 17m51s 00:48:55 INFO - updating [===========================> ] 93200/151687 17m46s 00:48:55 INFO - updating [===========================> ] 93300/151687 17m44s 00:48:55 INFO - updating [===========================> ] 93400/151687 17m42s 00:48:55 INFO - updating [===========================> ] 93500/151687 17m41s 00:48:55 INFO - updating [===========================> ] 93600/151687 17m39s 00:48:55 INFO - updating [===========================> ] 93700/151687 17m38s 00:48:55 INFO - updating [===========================> ] 93900/151687 17m33s 00:48:55 INFO - updating [===========================> ] 94000/151687 17m31s 00:48:55 INFO - updating [===========================> ] 94100/151687 17m30s 00:48:55 INFO - updating [===========================> ] 94400/151687 17m22s 00:48:55 INFO - updating [===========================> ] 94600/151687 17m17s 00:48:55 INFO - updating [===========================> ] 94900/151687 17m09s 00:48:55 INFO - updating [===========================> ] 95200/151687 17m01s 00:48:55 INFO - updating [===========================> ] 95600/151687 16m50s 00:48:55 INFO - updating [============================> ] 95700/151687 16m48s 00:48:55 INFO - updating [============================> ] 95900/151687 16m45s 00:48:55 INFO - updating [============================> ] 96000/151687 16m42s 00:48:55 INFO - updating [============================> ] 96200/151687 16m38s 00:48:55 INFO - updating [============================> ] 96400/151687 16m33s 00:48:55 INFO - updating [============================> ] 96500/151687 16m31s 00:48:55 INFO - updating [============================> ] 96700/151687 16m26s 00:48:55 INFO - updating [============================> ] 96800/151687 16m25s 00:48:55 INFO - updating [============================> ] 97000/151687 16m21s 00:48:55 INFO - updating [============================> ] 97100/151687 16m20s 00:48:55 INFO - updating [============================> ] 97300/151687 16m15s 00:48:55 INFO - updating [============================> ] 97500/151687 16m10s 00:48:55 INFO - updating [============================> ] 97600/151687 16m08s 00:48:55 INFO - updating [============================> ] 97800/151687 16m04s 00:48:55 INFO - updating [============================> ] 98000/151687 16m00s 00:48:55 INFO - updating [============================> ] 98100/151687 15m58s 00:48:55 INFO - updating [============================> ] 98300/151687 15m53s 00:48:55 INFO - updating [============================> ] 98500/151687 15m48s 00:48:55 INFO - updating [============================> ] 98700/151687 15m44s 00:48:55 INFO - updating [============================> ] 98800/151687 15m42s 00:48:55 INFO - updating [============================> ] 98900/151687 15m41s 00:48:55 INFO - updating [=============================> ] 99100/151687 15m36s 00:48:55 INFO - updating [=============================> ] 99200/151687 15m34s 00:48:55 INFO - updating [=============================> ] 99400/151687 15m31s 00:48:55 INFO - updating [=============================> ] 99600/151687 15m27s 00:48:55 INFO - updating [=============================> ] 99700/151687 15m25s 00:48:55 INFO - updating [=============================> ] 99800/151687 15m23s 00:48:55 INFO - updating [=============================> ] 99900/151687 15m21s 00:48:55 INFO - updating [=============================> ] 100000/151687 15m20s 00:48:55 INFO - updating [=============================> ] 100100/151687 15m18s 00:48:55 INFO - updating [=============================> ] 100200/151687 15m17s 00:48:55 INFO - updating [=============================> ] 100400/151687 15m13s 00:48:55 INFO - updating [=============================> ] 100600/151687 15m08s 00:48:55 INFO - updating [=============================> ] 100700/151687 15m09s 00:48:55 INFO - updating [=============================> ] 100800/151687 15m09s 00:48:55 INFO - updating [=============================> ] 100900/151687 15m07s 00:48:55 INFO - updating [=============================> ] 101000/151687 15m07s 00:48:55 INFO - updating [=============================> ] 101100/151687 15m05s 00:48:55 INFO - updating [=============================> ] 101300/151687 15m01s 00:48:55 INFO - updating [=============================> ] 101500/151687 14m56s 00:48:55 INFO - updating [=============================> ] 101600/151687 14m54s 00:48:55 INFO - updating [=============================> ] 101800/151687 14m50s 00:48:55 INFO - updating [=============================> ] 101900/151687 14m48s 00:48:55 INFO - updating [=============================> ] 102000/151687 14m46s 00:48:55 INFO - updating [=============================> ] 102100/151687 14m44s 00:48:55 INFO - updating [==============================> ] 102300/151687 14m39s 00:48:55 INFO - updating [==============================> ] 102400/151687 14m38s 00:48:55 INFO - updating [==============================> ] 102500/151687 14m37s 00:48:55 INFO - updating [==============================> ] 102700/151687 14m33s 00:48:55 INFO - updating [==============================> ] 102800/151687 14m31s 00:48:55 INFO - updating [==============================> ] 103000/151687 14m26s 00:48:55 INFO - updating [==============================> ] 103100/151687 14m25s 00:48:55 INFO - updating [==============================> ] 103300/151687 14m20s 00:48:55 INFO - updating [==============================> ] 103400/151687 14m18s 00:48:55 INFO - updating [==============================> ] 103500/151687 14m16s 00:48:55 INFO - updating [==============================> ] 103600/151687 14m14s 00:48:55 INFO - updating [==============================> ] 103700/151687 14m12s 00:48:55 INFO - updating [==============================> ] 103800/151687 14m12s 00:48:55 INFO - updating [==============================> ] 103900/151687 14m10s 00:48:55 INFO - updating [==============================> ] 104000/151687 14m08s 00:48:55 INFO - updating [==============================> ] 104200/151687 14m04s 00:48:55 INFO - updating [==============================> ] 104300/151687 14m02s 00:48:55 INFO - updating [==============================> ] 104400/151687 13m59s 00:48:55 INFO - updating [==============================> ] 104500/151687 13m58s 00:48:55 INFO - updating [==============================> ] 104600/151687 13m57s 00:48:55 INFO - updating [==============================> ] 104700/151687 13m55s 00:48:55 INFO - updating [==============================> ] 104900/151687 13m51s 00:48:55 INFO - updating [==============================> ] 105100/151687 13m47s 00:48:55 INFO - updating [==============================> ] 105200/151687 13m46s 00:48:55 INFO - updating [==============================> ] 105300/151687 13m44s 00:48:55 INFO - updating [==============================> ] 105400/151687 13m42s 00:48:55 INFO - updating [==============================> ] 105500/151687 13m40s 00:48:55 INFO - updating [===============================> ] 105600/151687 13m38s 00:48:55 INFO - updating [===============================> ] 105700/151687 13m36s 00:48:55 INFO - updating [===============================> ] 105800/151687 13m34s 00:48:55 INFO - updating [===============================> ] 105900/151687 13m32s 00:48:55 INFO - updating [===============================> ] 106100/151687 13m28s 00:48:55 INFO - updating [===============================> ] 106300/151687 13m24s 00:48:55 INFO - updating [===============================> ] 106500/151687 13m19s 00:48:55 INFO - updating [===============================> ] 106700/151687 13m15s 00:48:55 INFO - updating [===============================> ] 106800/151687 13m13s 00:48:55 INFO - updating [===============================> ] 106900/151687 13m11s 00:48:55 INFO - updating [===============================> ] 107000/151687 13m09s 00:48:55 INFO - updating [===============================> ] 107300/151687 13m02s 00:48:55 INFO - updating [===============================> ] 107400/151687 13m01s 00:48:55 INFO - updating [===============================> ] 107500/151687 12m59s 00:48:55 INFO - updating [===============================> ] 107800/151687 12m52s 00:48:55 INFO - updating [===============================> ] 107900/151687 12m51s 00:48:55 INFO - updating [===============================> ] 108000/151687 12m49s 00:48:55 INFO - updating [===============================> ] 108200/151687 12m45s 00:48:55 INFO - updating [===============================> ] 108300/151687 12m43s 00:48:55 INFO - updating [===============================> ] 108500/151687 12m39s 00:48:55 INFO - updating [===============================> ] 108800/151687 12m32s 00:48:55 INFO - updating [================================> ] 109100/151687 12m26s 00:48:55 INFO - updating [================================> ] 109200/151687 12m24s 00:48:55 INFO - updating [================================> ] 109300/151687 12m22s 00:48:55 INFO - updating [================================> ] 109600/151687 12m16s 00:48:55 INFO - updating [================================> ] 109800/151687 12m12s 00:48:55 INFO - updating [================================> ] 109900/151687 12m11s 00:48:55 INFO - updating [================================> ] 110100/151687 12m08s 00:48:55 INFO - updating [================================> ] 110400/151687 12m01s 00:48:55 INFO - updating [================================> ] 110500/151687 12m00s 00:48:55 INFO - updating [================================> ] 110700/151687 11m55s 00:48:55 INFO - updating [================================> ] 110900/151687 11m51s 00:48:55 INFO - updating [================================> ] 111000/151687 11m49s 00:48:55 INFO - updating [================================> ] 111100/151687 11m48s 00:48:55 INFO - updating [================================> ] 111300/151687 11m44s 00:48:55 INFO - updating [================================> ] 111800/151687 11m32s 00:48:55 INFO - updating [================================> ] 112100/151687 11m26s 00:48:55 INFO - updating [=================================> ] 112200/151687 11m24s 00:48:55 INFO - updating [=================================> ] 112400/151687 11m21s 00:48:55 INFO - updating [=================================> ] 112500/151687 11m19s 00:48:55 INFO - updating [=================================> ] 112700/151687 11m15s 00:48:55 INFO - updating [=================================> ] 112900/151687 11m12s 00:48:55 INFO - updating [=================================> ] 113000/151687 11m10s 00:48:55 INFO - updating [=================================> ] 113100/151687 11m08s 00:48:55 INFO - updating [=================================> ] 113200/151687 11m06s 00:48:55 INFO - updating [=================================> ] 113300/151687 11m04s 00:48:55 INFO - updating [=================================> ] 113500/151687 11m01s 00:48:55 INFO - updating [=================================> ] 113700/151687 10m57s 00:48:55 INFO - updating [=================================> ] 113800/151687 10m55s 00:48:55 INFO - updating [=================================> ] 113900/151687 10m54s 00:48:55 INFO - updating [=================================> ] 114000/151687 10m53s 00:48:55 INFO - updating [=================================> ] 114100/151687 10m51s 00:48:55 INFO - updating [=================================> ] 114500/151687 10m43s 00:48:55 INFO - updating [=================================> ] 114600/151687 10m41s 00:48:55 INFO - updating [=================================> ] 114800/151687 10m38s 00:48:55 INFO - updating [=================================> ] 114900/151687 10m36s 00:48:55 INFO - updating [=================================> ] 115100/151687 10m32s 00:48:55 INFO - updating [=================================> ] 115200/151687 10m31s 00:48:55 INFO - updating [=================================> ] 115300/151687 10m29s 00:48:55 INFO - updating [==================================> ] 115500/151687 10m25s 00:48:55 INFO - updating [==================================> ] 115700/151687 10m21s 00:48:55 INFO - updating [==================================> ] 115900/151687 10m17s 00:48:55 INFO - updating [==================================> ] 116000/151687 10m15s 00:48:55 INFO - updating [==================================> ] 116100/151687 10m13s 00:48:55 INFO - updating [==================================> ] 116300/151687 10m09s 00:48:55 INFO - updating [==================================> ] 116500/151687 10m06s 00:48:55 INFO - updating [==================================> ] 116700/151687 10m02s 00:48:55 INFO - updating [===================================> ] 116900/151687 9m57s 00:48:55 INFO - updating [===================================> ] 117000/151687 9m56s 00:48:55 INFO - updating [===================================> ] 117200/151687 9m51s 00:48:55 INFO - updating [===================================> ] 117400/151687 9m47s 00:48:55 INFO - updating [===================================> ] 117500/151687 9m46s 00:48:55 INFO - updating [===================================> ] 117600/151687 9m46s 00:48:55 INFO - updating [===================================> ] 117700/151687 9m46s 00:48:55 INFO - updating [===================================> ] 117800/151687 9m45s 00:48:55 INFO - updating [===================================> ] 118000/151687 9m41s 00:48:55 INFO - updating [===================================> ] 118300/151687 9m35s 00:48:55 INFO - updating [===================================> ] 118400/151687 9m34s 00:48:55 INFO - updating [===================================> ] 118700/151687 9m28s 00:48:55 INFO - updating [===================================> ] 119000/151687 9m22s 00:48:55 INFO - updating [===================================> ] 119200/151687 9m18s 00:48:55 INFO - updating [===================================> ] 119300/151687 9m16s 00:48:55 INFO - updating [===================================> ] 119400/151687 9m14s 00:48:55 INFO - updating [====================================> ] 119500/151687 9m12s 00:48:55 INFO - updating [====================================> ] 119800/151687 9m06s 00:48:55 INFO - updating [====================================> ] 120100/151687 9m00s 00:48:55 INFO - updating [====================================> ] 120500/151687 8m52s 00:48:55 INFO - updating [====================================> ] 120700/151687 8m48s 00:48:55 INFO - updating [====================================> ] 120900/151687 8m44s 00:48:55 INFO - updating [====================================> ] 121200/151687 8m38s 00:48:55 INFO - updating [====================================> ] 121400/151687 8m34s 00:48:55 INFO - updating [====================================> ] 121500/151687 8m32s 00:48:55 INFO - updating [====================================> ] 121600/151687 8m31s 00:48:55 INFO - updating [====================================> ] 121900/151687 8m25s 00:48:55 INFO - updating [====================================> ] 122100/151687 8m21s 00:48:55 INFO - updating [====================================> ] 122300/151687 8m17s 00:48:55 INFO - updating [====================================> ] 122600/151687 8m11s 00:48:55 INFO - updating [=====================================> ] 122800/151687 8m07s 00:48:55 INFO - updating [=====================================> ] 123100/151687 8m01s 00:48:55 INFO - updating [=====================================> ] 123400/151687 7m55s 00:48:55 INFO - updating [=====================================> ] 123700/151687 7m49s 00:48:55 INFO - updating [=====================================> ] 124100/151687 7m42s 00:48:55 INFO - updating [=====================================> ] 124300/151687 7m38s 00:48:55 INFO - updating [=====================================> ] 124500/151687 7m34s 00:48:55 INFO - updating [=====================================> ] 124800/151687 7m28s 00:48:55 INFO - updating [=====================================> ] 124900/151687 7m26s 00:48:55 INFO - updating [=====================================> ] 125400/151687 7m17s 00:48:55 INFO - updating [======================================> ] 125900/151687 7m07s 00:48:55 INFO - updating [======================================> ] 126300/151687 6m59s 00:48:55 INFO - updating [======================================> ] 126800/151687 6m49s 00:48:55 INFO - updating [======================================> ] 127300/151687 6m40s 00:48:55 INFO - updating [======================================> ] 127900/151687 6m29s 00:48:55 INFO - updating [======================================> ] 128000/151687 6m27s 00:48:55 INFO - updating [======================================> ] 128300/151687 6m21s 00:48:55 INFO - updating [======================================> ] 128400/151687 6m20s 00:48:55 INFO - updating [======================================> ] 128600/151687 6m17s 00:48:55 INFO - updating [======================================> ] 128700/151687 6m15s 00:48:55 INFO - updating [======================================> ] 128900/151687 6m12s 00:48:55 INFO - updating [=======================================> ] 129200/151687 6m06s 00:48:55 INFO - updating [=======================================> ] 129500/151687 6m00s 00:48:55 INFO - updating [=======================================> ] 129700/151687 5m57s 00:48:55 INFO - updating [=======================================> ] 130000/151687 5m52s 00:48:55 INFO - updating [=======================================> ] 130100/151687 5m50s 00:48:55 INFO - updating [=======================================> ] 130200/151687 5m48s 00:48:55 INFO - updating [=======================================> ] 130500/151687 5m43s 00:48:55 INFO - updating [=======================================> ] 130900/151687 5m35s 00:48:55 INFO - updating [=======================================> ] 131200/151687 5m30s 00:48:55 INFO - updating [=======================================> ] 131500/151687 5m24s 00:48:55 INFO - updating [=======================================> ] 131900/151687 5m17s 00:48:55 INFO - updating [=======================================> ] 132100/151687 5m14s 00:48:55 INFO - updating [=======================================> ] 132300/151687 5m10s 00:48:55 INFO - updating [========================================> ] 132700/151687 5m03s 00:48:55 INFO - updating [========================================> ] 133000/151687 4m58s 00:48:55 INFO - updating [========================================> ] 133100/151687 4m58s 00:48:55 INFO - updating [========================================> ] 133200/151687 4m56s 00:48:55 INFO - updating [========================================> ] 133500/151687 4m51s 00:48:55 INFO - updating [========================================> ] 133800/151687 4m45s 00:48:55 INFO - updating [========================================> ] 134000/151687 4m42s 00:48:55 INFO - updating [========================================> ] 134100/151687 4m40s 00:48:55 INFO - updating [========================================> ] 134300/151687 4m37s 00:48:55 INFO - updating [========================================> ] 134500/151687 4m34s 00:48:55 INFO - updating [========================================> ] 134800/151687 4m28s 00:48:55 INFO - updating [========================================> ] 135000/151687 4m25s 00:48:55 INFO - updating [========================================> ] 135400/151687 4m18s 00:48:55 INFO - updating [========================================> ] 135500/151687 4m17s 00:48:55 INFO - updating [=========================================> ] 135800/151687 4m12s 00:48:55 INFO - updating [=========================================> ] 135900/151687 4m10s 00:48:55 INFO - updating [=========================================> ] 136000/151687 4m08s 00:48:55 INFO - updating [=========================================> ] 136300/151687 4m03s 00:48:55 INFO - updating [=========================================> ] 136500/151687 4m00s 00:48:55 INFO - updating [=========================================> ] 136700/151687 3m57s 00:48:55 INFO - updating [=========================================> ] 136900/151687 3m53s 00:48:55 INFO - updating [=========================================> ] 137100/151687 3m50s 00:48:55 INFO - updating [=========================================> ] 137300/151687 3m47s 00:48:55 INFO - updating [=========================================> ] 137400/151687 3m45s 00:48:55 INFO - updating [=========================================> ] 137500/151687 3m44s 00:48:55 INFO - updating [=========================================> ] 137700/151687 3m41s 00:48:55 INFO - updating [=========================================> ] 138100/151687 3m34s 00:48:55 INFO - updating [=========================================> ] 138300/151687 3m31s 00:48:55 INFO - updating [=========================================> ] 138400/151687 3m29s 00:48:55 INFO - updating [=========================================> ] 138700/151687 3m24s 00:48:55 INFO - updating [==========================================> ] 138900/151687 3m21s 00:48:55 INFO - updating [==========================================> ] 139100/151687 3m18s 00:48:55 INFO - updating [==========================================> ] 139300/151687 3m15s 00:48:55 INFO - updating [==========================================> ] 139600/151687 3m09s 00:48:55 INFO - updating [==========================================> ] 139900/151687 3m05s 00:48:55 INFO - updating [==========================================> ] 140200/151687 3m00s 00:48:55 INFO - updating [==========================================> ] 140300/151687 2m58s 00:48:55 INFO - updating [==========================================> ] 140500/151687 2m55s 00:48:55 INFO - updating [==========================================> ] 140800/151687 2m50s 00:48:55 INFO - updating [==========================================> ] 140900/151687 2m48s 00:48:55 INFO - updating [==========================================> ] 141000/151687 2m47s 00:48:55 INFO - updating [==========================================> ] 141400/151687 2m40s 00:48:55 INFO - updating [==========================================> ] 141700/151687 2m35s 00:48:55 INFO - updating [==========================================> ] 142000/151687 2m30s 00:48:55 INFO - updating [===========================================> ] 142300/151687 2m26s 00:48:55 INFO - updating [===========================================> ] 142500/151687 2m22s 00:48:55 INFO - updating [===========================================> ] 142600/151687 2m21s 00:48:55 INFO - updating [===========================================> ] 142700/151687 2m19s 00:48:55 INFO - updating [===========================================> ] 142800/151687 2m18s 00:48:55 INFO - updating [===========================================> ] 142900/151687 2m17s 00:48:55 INFO - updating [===========================================> ] 143000/151687 2m15s 00:48:55 INFO - updating [===========================================> ] 143200/151687 2m12s 00:48:55 INFO - updating [===========================================> ] 143400/151687 2m09s 00:48:55 INFO - updating [===========================================> ] 143600/151687 2m05s 00:48:55 INFO - updating [===========================================> ] 143800/151687 2m02s 00:48:55 INFO - updating [===========================================> ] 144000/151687 1m59s 00:48:55 INFO - updating [===========================================> ] 144100/151687 1m58s 00:48:55 INFO - updating [===========================================> ] 144300/151687 1m55s 00:48:55 INFO - updating [===========================================> ] 144400/151687 1m53s 00:48:55 INFO - updating [===========================================> ] 144500/151687 1m52s 00:48:55 INFO - updating [===========================================> ] 144600/151687 1m50s 00:48:55 INFO - updating [===========================================> ] 144800/151687 1m47s 00:48:55 INFO - updating [===========================================> ] 144900/151687 1m46s 00:48:55 INFO - updating [===========================================> ] 145000/151687 1m44s 00:48:55 INFO - updating [===========================================> ] 145200/151687 1m41s 00:48:55 INFO - updating [============================================> ] 145400/151687 1m38s 00:48:55 INFO - updating [============================================> ] 145500/151687 1m37s 00:48:55 INFO - updating [============================================> ] 145600/151687 1m35s 00:48:55 INFO - updating [============================================> ] 145700/151687 1m33s 00:48:55 INFO - updating [============================================> ] 145900/151687 1m30s 00:48:55 INFO - updating [============================================> ] 146000/151687 1m29s 00:48:55 INFO - updating [============================================> ] 146100/151687 1m27s 00:48:55 INFO - updating [============================================> ] 146200/151687 1m26s 00:48:55 INFO - updating [============================================> ] 146400/151687 1m23s 00:48:55 INFO - updating [============================================> ] 146500/151687 1m21s 00:48:55 INFO - updating [============================================> ] 146600/151687 1m19s 00:48:55 INFO - updating [============================================> ] 146700/151687 1m18s 00:48:55 INFO - updating [============================================> ] 146800/151687 1m16s 00:48:55 INFO - updating [============================================> ] 147000/151687 1m13s 00:48:55 INFO - updating [============================================> ] 147200/151687 1m10s 00:48:55 INFO - updating [============================================> ] 147300/151687 1m09s 00:48:55 INFO - updating [============================================> ] 147400/151687 1m07s 00:48:55 INFO - updating [============================================> ] 147700/151687 1m02s 00:48:55 INFO - updating [============================================> ] 147800/151687 1m01s 00:48:55 INFO - updating [==============================================> ] 148000/151687 58s 00:48:55 INFO - updating [==============================================> ] 148200/151687 55s 00:48:55 INFO - updating [==============================================> ] 148300/151687 53s 00:48:55 INFO - updating [==============================================> ] 148400/151687 51s 00:48:55 INFO - updating [==============================================> ] 148500/151687 50s 00:48:55 INFO - updating [===============================================> ] 148600/151687 48s 00:48:55 INFO - updating [===============================================> ] 148800/151687 45s 00:48:55 INFO - updating [===============================================> ] 149000/151687 42s 00:48:55 INFO - updating [===============================================> ] 149100/151687 41s 00:48:55 INFO - updating [===============================================> ] 149200/151687 39s 00:48:55 INFO - updating [===============================================> ] 149300/151687 37s 00:48:55 INFO - updating [===============================================> ] 149500/151687 34s 00:48:55 INFO - updating [===============================================> ] 149700/151687 31s 00:48:55 INFO - updating [===============================================> ] 149800/151687 30s 00:48:55 INFO - updating [===============================================> ] 149900/151687 28s 00:48:55 INFO - updating [===============================================> ] 150000/151687 27s 00:48:55 INFO - updating [===============================================> ] 150100/151687 25s 00:48:55 INFO - updating [===============================================> ] 150200/151687 24s 00:48:55 INFO - updating [===============================================> ] 150300/151687 22s 00:48:55 INFO - updating [===============================================> ] 150500/151687 19s 00:48:55 INFO - updating [===============================================> ] 150700/151687 16s 00:48:55 INFO - updating [===============================================> ] 150800/151687 14s 00:48:55 INFO - updating [===============================================> ] 150900/151687 13s 00:48:55 INFO - updating [===============================================> ] 151100/151687 10s 00:48:55 INFO - updating [===============================================> ] 151200/151687 08s 00:48:55 INFO - updating [===============================================> ] 151400/151687 05s 00:48:55 INFO - updating [================================================>] 151687/151687 01s 00:48:55 INFO - 00:48:55 INFO - 151687 files updated, 0 files merged, 0 files removed, 0 files unresolved 00:48:55 INFO - updated to 21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 00:48:55 INFO - Return code: 0 00:48:55 INFO - Setting buildbot property comments to Bug 1323901 - Force `which` to use lowercase extensions on Windows. r=cmanchester+432261 00:48:55 INFO - Writing buildbot properties ['comments'] to c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\comments 00:48:55 INFO - Writing to file c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\comments 00:48:55 INFO - Contents: 00:48:55 INFO - comments:Bug 1323901 - Force `which` to use lowercase extensions on Windows. r=cmanchester+432261 00:48:55 INFO - Setting buildbot property got_revision to 21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 00:48:55 INFO - Writing buildbot properties ['got_revision'] to c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\got_revision 00:48:55 INFO - Writing to file c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties\got_revision 00:48:55 INFO - Contents: 00:48:55 INFO - got_revision:21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 00:48:55 INFO - [mozharness: 2016-12-28 08:48:55.797000Z] Finished checkout-sources step (success) 00:48:55 INFO - [mozharness: 2016-12-28 08:48:55.797000Z] Skipping setup-mock step. 00:48:55 INFO - [mozharness: 2016-12-28 08:48:55.797000Z] Running build step. 00:48:55 INFO - Running main action method: build 00:48:55 INFO - removing old packages... 00:48:55 INFO - There wasn't any old packages to remove. 00:48:55 INFO - Using in-tree mozconfig 00:48:55 INFO - Reading from file c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\browser/config/mozconfigs/win32/debug 00:48:55 INFO - Contents: 00:48:55 INFO - . "$topsrcdir/build/mozconfig.win-common" 00:48:55 INFO - MOZ_AUTOMATION_L10N_CHECK=0 00:48:55 INFO - . "$topsrcdir/browser/config/mozconfigs/common" 00:48:55 INFO - 00:48:55 INFO - ac_add_options --enable-debug 00:48:55 INFO - ac_add_options --enable-dmd 00:48:55 INFO - ac_add_options --enable-profiling # needed for --enable-dmd to work on Windows 00:48:55 INFO - ac_add_options --enable-verify-mar 00:48:55 INFO - 00:48:55 INFO - # Needed to enable breakpad in application.ini 00:48:55 INFO - export MOZILLA_OFFICIAL=1 00:48:55 INFO - 00:48:55 INFO - # Enable Telemetry 00:48:55 INFO - export MOZ_TELEMETRY_REPORTING=1 00:48:55 INFO - 00:48:55 INFO - . $topsrcdir/build/win32/mozconfig.vs-latest 00:48:55 INFO - 00:48:55 INFO - # Package js shell. 00:48:55 INFO - export MOZ_PACKAGE_JSSHELL=1 00:48:55 INFO - 00:48:55 INFO - ac_add_options --with-branding=browser/branding/nightly 00:48:55 INFO - 00:48:55 INFO - . "$topsrcdir/build/mozconfig.common.override" 00:48:55 INFO - . "$topsrcdir/build/mozconfig.cache" 00:48:55 INFO - mozconfig content: 00:48:55 INFO - . "$topsrcdir/build/mozconfig.win-common" 00:48:55 INFO - MOZ_AUTOMATION_L10N_CHECK=0 00:48:55 INFO - . "$topsrcdir/browser/config/mozconfigs/common" 00:48:55 INFO - 00:48:55 INFO - ac_add_options --enable-debug 00:48:55 INFO - ac_add_options --enable-dmd 00:48:55 INFO - ac_add_options --enable-profiling # needed for --enable-dmd to work on Windows 00:48:55 INFO - ac_add_options --enable-verify-mar 00:48:55 INFO - 00:48:55 INFO - # Needed to enable breakpad in application.ini 00:48:55 INFO - export MOZILLA_OFFICIAL=1 00:48:55 INFO - 00:48:55 INFO - # Enable Telemetry 00:48:55 INFO - export MOZ_TELEMETRY_REPORTING=1 00:48:55 INFO - 00:48:55 INFO - . $topsrcdir/build/win32/mozconfig.vs-latest 00:48:55 INFO - 00:48:55 INFO - # Package js shell. 00:48:55 INFO - export MOZ_PACKAGE_JSSHELL=1 00:48:55 INFO - 00:48:55 INFO - ac_add_options --with-branding=browser/branding/nightly 00:48:55 INFO - 00:48:55 INFO - . "$topsrcdir/build/mozconfig.common.override" 00:48:55 INFO - . "$topsrcdir/build/mozconfig.cache" 00:48:55 INFO - Copying c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\browser/config/mozconfigs/win32/debug to c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\.mozconfig 00:48:55 INFO - ['sh', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\tools\\scripts\\tooltool\\tooltool_wrapper.sh', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'https://api.pub.build.mozilla.org/tooltool/', 'setup.sh', 'c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '--authentication-file', 'c:\\builds\\relengapi.tok', '-c', '/c/builds/tooltool_cache'] 00:48:55 INFO - Running command: ['sh', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\tools\\scripts\\tooltool\\tooltool_wrapper.sh', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'https://api.pub.build.mozilla.org/tooltool/', 'setup.sh', 'c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '--authentication-file', 'c:\\builds\\relengapi.tok', '-c', '/c/builds/tooltool_cache'] in c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src 00:48:55 INFO - Copy/paste: sh c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\tools\scripts\tooltool\tooltool_wrapper.sh c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\browser/config/tooltool-manifests/win32/releng.manifest https://api.pub.build.mozilla.org/tooltool/ setup.sh c:\mozilla-build\python27\python.exe C:/mozilla-build/tooltool.py --authentication-file c:\builds\relengapi.tok -c /c/builds/tooltool_cache 00:49:01 INFO - c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\browser/config/tooltool-manifests/win32/releng.manifest content 00:49:01 INFO - ======================================================= 00:49:01 INFO - [ 00:49:01 INFO - { 00:49:01 INFO - "size": 266240, 00:49:01 INFO - "digest": "bb345b0e700ffab4d09436981f14b5de84da55a3f18a7f09ebc4364a4488acdeab8d46f447b12ac70f2da1444a68b8ce8b8675f0dae2ccf845e966d1df0f0869", 00:49:01 INFO - "algorithm": "sha512", 00:49:01 INFO - "filename": "mozmake.exe" 00:49:01 INFO - }, 00:49:01 INFO - { 00:49:01 INFO - "version": "rustc 1.14.0-beta.2 (e627a2e6e 2016-11-16) repack", 00:49:01 INFO - "size": 64146480, 00:49:01 INFO - "digest": "d3a43d755a9819029421ef7f0f2997f79d4e327b38715878e097b633f858e7fb36882eef19ca59751487cef89692f8d082babf1b8196342e75bfe6b1ad9d8e7f", 00:49:01 INFO - "algorithm": "sha512", 00:49:01 INFO - "filename": "rustc.tar.bz2", 00:49:01 INFO - "unpack": true 00:49:01 INFO - }, 00:49:01 INFO - { 00:49:01 INFO - "version": "sccache rev b21198a7183a2fe226ff49348b1c0b51bae9f4f8", 00:49:01 INFO - "algorithm": "sha512", 00:49:01 INFO - "visibility": "public", 00:49:01 INFO - "filename": "sccache2.tar.bz2", 00:49:01 INFO - "unpack": true, 00:49:01 INFO - "digest": "7dee5c5602b3830cb8ac45ebaa8542714bbac0e50eabbff58a06972a02ceeab75ed7c56ff22a23f760b8317ae8e9a01cdecfaf75a7acbd2a4cdd817967170d2e", 00:49:01 INFO - "size": 1179901 00:49:01 INFO - }, 00:49:01 INFO - { 00:49:01 INFO - "version": "Visual Studio 2015 Update 3 14.0.25425.01 / SDK 10.0.14393.0", 00:49:01 INFO - "size": 326656969, 00:49:01 INFO - "digest": "babc414ffc0457d27f5a1ed24a8e4873afbe2f1c1a4075469a27c005e1babc3b2a788f643f825efedff95b79686664c67ec4340ed535487168a3482e68559bc7", 00:49:01 INFO - "algorithm": "sha512", 00:49:01 INFO - "filename": "vs2015u3.zip", 00:49:01 INFO - "unpack": true 00:49:01 INFO - } 00:49:01 INFO - ] 00:49:01 INFO - ======================================================= 00:49:01 INFO - Fetching... 00:49:02 INFO - Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '--authentication-file', 'c:\\builds\\relengapi.tok', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'] 00:49:02 INFO - INFO - File mozmake.exe not present in local cache folder c:/builds/tooltool_cache 00:49:03 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 00:49:05 INFO - INFO - File mozmake.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\tmptlohkp 00:49:05 INFO - INFO - File rustc.tar.bz2 not present in local cache folder c:/builds/tooltool_cache 00:49:05 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 00:49:16 INFO - INFO - File rustc.tar.bz2 fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\tmp3iia_x 00:49:20 INFO - INFO - File sccache2.tar.bz2 not present in local cache folder c:/builds/tooltool_cache 00:49:20 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 00:49:23 INFO - INFO - File sccache2.tar.bz2 fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\tmp8t1inp 00:49:23 INFO - INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache 00:49:23 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 00:49:35 INFO - INFO - File vs2015u3.zip fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\tmp1n02kv 00:49:47 INFO - INFO - File integrity verified, renaming tmptlohkp to mozmake.exe 00:49:47 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 00:49:48 INFO - INFO - Local cache c:/builds/tooltool_cache updated with mozmake.exe 00:49:49 INFO - INFO - File integrity verified, renaming tmp3iia_x to rustc.tar.bz2 00:49:49 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 00:49:49 INFO - INFO - Local cache c:/builds/tooltool_cache updated with rustc.tar.bz2 00:49:49 INFO - INFO - File integrity verified, renaming tmp8t1inp to sccache2.tar.bz2 00:49:49 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 00:49:49 INFO - INFO - Local cache c:/builds/tooltool_cache updated with sccache2.tar.bz2 00:51:08 INFO - INFO - File integrity verified, renaming tmp1n02kv to vs2015u3.zip 00:51:08 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 00:51:08 INFO - INFO - Local cache c:/builds/tooltool_cache updated with vs2015u3.zip 00:51:08 INFO - INFO - untarring "rustc.tar.bz2" 00:52:55 INFO - INFO - untarring "sccache2.tar.bz2" 00:53:39 INFO - INFO - unzipping "vs2015u3.zip" 00:54:02 INFO - WARNING: Timeout (300) exceeded, killing process 404 00:55:12 INFO - retry: Calling with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '--authentication-file', 'c:\\builds\\relengapi.tok', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #2 00:55:12 INFO - Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '--authentication-file', 'c:\\builds\\relengapi.tok', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'] 00:55:14 INFO - INFO - rm tree: rustc 00:55:15 INFO - INFO - untarring "rustc.tar.bz2" 00:55:41 INFO - INFO - rm tree: sccache2 00:55:41 INFO - INFO - untarring "sccache2.tar.bz2" 00:55:41 INFO - INFO - rm tree: vs2015u3 00:55:45 INFO - INFO - unzipping "vs2015u3.zip" 01:00:12 INFO - WARNING: Timeout (300) exceeded, killing process 596 01:00:59 INFO - retry: Calling with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '--authentication-file', 'c:\\builds\\relengapi.tok', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #3 01:00:59 INFO - Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '--authentication-file', 'c:\\builds\\relengapi.tok', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'] 01:01:02 INFO - INFO - rm tree: rustc 01:01:13 INFO - INFO - untarring "rustc.tar.bz2" 01:01:24 INFO - INFO - rm tree: sccache2 01:01:24 INFO - INFO - untarring "sccache2.tar.bz2" 01:01:24 INFO - INFO - rm tree: vs2015u3 01:01:29 INFO - INFO - unzipping "vs2015u3.zip" 01:01:45 INFO - Return code: 0 01:01:45 INFO - mkdir: c:\builds\moz2_slave\autoland-w32-d-000000000000000\.mozbuild 01:01:45 INFO - Using buildbot properties: 01:01:45 INFO - { 01:01:45 INFO - "project": "", 01:01:45 INFO - "product": "firefox", 01:01:45 INFO - "script_repo_revision": "production", 01:01:45 INFO - "scheduler": "autoland-firefox", 01:01:45 INFO - "repository": "", 01:01:45 INFO - "buildername": "WINNT 5.2 autoland leak test build", 01:01:45 INFO - "buildid": "20161227235136", 01:01:45 INFO - "basedir": "/c/builds/moz2_slave/autoland-w32-d-000000000000000", 01:01:45 INFO - "buildnumber": 685, 01:01:45 INFO - "platform": "win32-debug", 01:01:45 INFO - "master": "http://buildbot-master70.bb.releng.use1.mozilla.com:8001/", 01:01:45 INFO - "branch": "autoland", 01:01:45 INFO - "revision": "21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0", 01:01:45 INFO - "slavename": "b-2008-spot-002", 01:01:45 INFO - "builduid": "e042f36309ad4ae787d4cdca52b6a8af", 01:01:45 INFO - "repo_path": "integration/autoland" 01:01:45 INFO - } 01:01:45 INFO - Copying c:\builds\moz2_slave\autoland-w32-d-000000000000000\buildprops.json to c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\buildprops.json 01:01:45 INFO - Running command: ['c:\\mozilla-build\\python27\\python.exe', 'mach', '--log-no-times', 'build', '-v'] in c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src 01:01:45 INFO - Copy/paste: c:\mozilla-build\python27\python.exe mach --log-no-times build -v 01:01:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:01:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:01:45 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 01:01:45 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 01:01:45 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 01:01:45 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 01:01:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 01:01:45 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 01:01:45 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 01:01:45 INFO - 'COMPUTERNAME': 'B-2008-SPOT-002', 01:01:45 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 01:01:45 INFO - 'CVS_RSH': 'ssh', 01:01:45 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 01:01:45 INFO - 'EDITOR': 'emacs.exe', 01:01:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:01:45 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 01:01:45 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 01:01:45 INFO - 'HOME': 'c:/Users/cltbld', 01:01:45 INFO - 'HOMEDRIVE': 'C:', 01:01:45 INFO - 'HOMEPATH': '\\', 01:01:45 INFO - 'HOSTTYPE': 'i686', 01:01:45 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 01:01:45 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 01:01:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:01:45 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 01:01:45 INFO - 'LOGNAME': 'cltbld', 01:01:45 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-002', 01:01:45 INFO - 'MACHTYPE': 'i686-pc-msys', 01:01:45 INFO - 'MAKE_MODE': 'unix', 01:01:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 01:01:45 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\.mozbuild', 01:01:45 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 01:01:45 INFO - 'MOZ_AUTOMATION': '1', 01:01:45 INFO - 'MOZ_BUILD_DATE': '20161227235136', 01:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:45 INFO - 'MOZ_OBJDIR': 'obj-firefox', 01:01:45 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 01:01:45 INFO - 'MOZ_SIGN_CMD': 'python c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\autoland-w32-d-000000000000000\\\\\\\\build\\\\\\\\tools\\\\\\\\release\\\\\\\\signing\\\\\\\\signtool.py --cachedir c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\autoland-w32-d-000000000000000\\\\\\\\build\\\\\\\\signing_cache -t c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\autoland-w32-d-000000000000000\\\\\\\\token -n c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\autoland-w32-d-000000000000000\\\\\\\\nonce -c c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\autoland-w32-d-000000000000000\\\\\\\\build\\\\\\\\tools\\\\\\\\release\\\\\\\\signing\\\\\\\\host.cert -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:osslsigncode:signcode:mar:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 01:01:45 INFO - 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/integration/autoland', 01:01:45 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 01:01:45 INFO - 'MSYSTEM': 'MINGW32', 01:01:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:01:45 INFO - 'OLDPWD': 'c:/Users/cltbld', 01:01:45 INFO - 'OS': 'Windows_NT', 01:01:45 INFO - 'OSTYPE': 'msys', 01:01:45 INFO - 'OS_VERSION': '2008', 01:01:45 INFO - 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\hg;c:\\mozilla-build\\buildbotve\\Scripts;c:\\ProgramData\\chocolatey\\bin;.;c:\\opt\\runner;c:\\mozilla-build\\buildbotve;c:\\mozilla-build\\python27;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;.;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\moztools-x64\\bin;c:\\mozilla-build\\vim\\vim72', 01:01:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:01:45 INFO - 'POST_UPLOAD_CMD': 'post_upload.py --tinderbox-builds-dir autoland-win32-debug -p firefox -i 20161227235136 --revision 21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 --release-to-tinderbox-dated-builds', 01:01:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:01:45 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 01:01:45 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 01:01:45 INFO - 'PROCESSOR_LEVEL': '6', 01:01:45 INFO - 'PROCESSOR_REVISION': '3f02', 01:01:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:01:45 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 01:01:45 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 01:01:45 INFO - 'PROGRAMW6432': 'C:\\Program Files', 01:01:45 INFO - 'PROMPT': '$P$G', 01:01:45 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\autoland-w32-d-000000000000000\\buildprops.json', 01:01:45 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 01:01:45 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 01:01:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:01:45 INFO - 'PWD': 'c:/Users/cltbld', 01:01:45 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 01:01:45 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 01:01:45 INFO - 'SHLVL': '1', 01:01:45 INFO - 'SSH_AGENT_PID': '4016', 01:01:45 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960', 01:01:45 INFO - 'SYSTEMDRIVE': 'C:', 01:01:45 INFO - 'SYSTEMROOT': 'C:\\Windows', 01:01:45 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 01:01:45 INFO - 'TERM': 'cygwin', 01:01:45 INFO - 'TINDERBOX_OUTPUT': '1', 01:01:45 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 01:01:45 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 01:01:45 INFO - 'TOOLTOOL_HOME': '/c/builds', 01:01:45 INFO - 'UPLOAD_HOST': 'upload.ffxbld.productdelivery.prod.mozaws.net', 01:01:45 INFO - 'UPLOAD_SSH_KEY': '/c/Users/cltbld/.ssh/ffxbld_rsa', 01:01:45 INFO - 'UPLOAD_TO_TEMP': '1', 01:01:45 INFO - 'UPLOAD_USER': 'ffxbld', 01:01:45 INFO - 'USERDOMAIN': 'B-2008-SPOT-002', 01:01:45 INFO - 'USERNAME': 'cltbld', 01:01:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:01:45 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 01:01:45 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 01:01:45 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 01:01:45 INFO - 'WINDIR': 'C:\\Windows', 01:01:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:01:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:01:45 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 01:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 01:01:45 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 01:01:45 INFO - Calling ['c:\\mozilla-build\\python27\\python.exe', 'mach', '--log-no-times', 'build', '-v'] with output_timeout 4800 01:01:49 INFO - c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\mozmake.EXE -f client.mk -s configure 01:01:50 INFO - client.mk:204: c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/obj-firefox/.mozconfig.mk: No such file or directory 01:01:51 INFO - Clobber not needed. 01:01:54 INFO - Generating c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/configure 01:01:54 INFO - Generating c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/js/src/configure 01:01:54 INFO - cd c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/obj-firefox 01:01:54 INFO - c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/configure 01:01:54 INFO - Traceback (most recent call last): 01:01:54 INFO - File "c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/configure.py", line 124, in 01:01:54 INFO - sys.exit(main(sys.argv)) 01:01:54 INFO - File "c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/configure.py", line 29, in main 01:01:54 INFO - sandbox.run(os.path.join(os.path.dirname(__file__), 'moz.configure')) 01:01:54 INFO - File "c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 334, in run 01:01:54 INFO - self.include_file(path) 01:01:54 INFO - File "c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 325, in include_file 01:01:54 INFO - exec_(code, self) 01:01:54 INFO - File "c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\python\mozbuild\mozbuild\util.py", line 59, in exec_ 01:01:54 INFO - exec(object, globals, locals) 01:01:54 INFO - File "c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/moz.configure", line 7, in 01:01:54 INFO - include('build/moz.configure/init.configure') 01:01:54 INFO - File "c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 630, in include_impl 01:01:54 INFO - self.include_file(what) 01:01:54 INFO - File "c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 325, in include_file 01:01:54 INFO - exec_(code, self) 01:01:54 INFO - File "c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\python\mozbuild\mozbuild\util.py", line 59, in exec_ 01:01:54 INFO - exec(object, globals, locals) 01:01:54 INFO - File "c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/build/moz.configure/init.configure", line 7, in 01:01:54 INFO - include('util.configure') 01:01:54 INFO - File "c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 630, in include_impl 01:01:54 INFO - self.include_file(what) 01:01:54 INFO - File "c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 323, in include_file 01:01:54 INFO - code = compile(source, path, 'exec') 01:01:54 INFO - File "c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/build/moz.configure/util.configure", line 157 01:01:54 INFO - if '.exe' not in exts: 01:01:54 INFO - ^ 01:01:54 INFO - IndentationError: unexpected indent 01:01:54 INFO - *** Fix above errors and then restart with\ 01:01:54 INFO - "c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/mozmake.EXE -f client.mk build" 01:01:54 INFO - client.mk:379: recipe for target 'configure' failed 01:01:54 INFO - mozmake.EXE: *** [configure] Error 1 01:01:54 INFO - 2 01:01:54 ERROR - Return code: 1 01:01:54 WARNING - setting return code to 2 01:01:54 FATAL - 'mach build' did not run successfully. Please check log for errors. 01:01:54 FATAL - Running post_fatal callback... 01:01:54 FATAL - Exiting -1 01:01:54 INFO - [mozharness: 2016-12-28 09:01:54.847000Z] Finished build step (failed) 01:01:54 INFO - Running post-run listener: _summarize 01:01:54 ERROR - # TBPL FAILURE # 01:01:54 INFO - [mozharness: 2016-12-28 09:01:54.847000Z] FxDesktopBuild summary: 01:01:54 ERROR - # TBPL FAILURE # 01:01:54 INFO - Running post-run listener: copy_logs_to_upload_dir 01:01:54 INFO - Copying logs to upload dir... 01:01:54 INFO - mkdir: c:\builds\moz2_slave\autoland-w32-d-000000000000000\build\upload\logs program finished with exit code -1 elapsedTime=3390.105000 ========= master_lag: -0.04 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 56 mins, 30 secs) (at 2016-12-28 01:01:56.553707) ========= ========= Started set props: buildid got_revision comments builduid stage_platform (results: 0, elapsed: 0 secs) (at 2016-12-28 01:01:56.568484) ========= 'bash' '-c' 'for file in `ls -1`; do cat $file; done' in dir c:\builds\moz2_slave\autoland-w32-d-000000000000000\properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv BUILDBOT_PATH=C:\mozilla-build\buildbotve CHOCOLATEYINSTALL=C:\ProgramData\chocolatey COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=B-2008-SPOT-002 COMSPEC=C:\Windows\system32\cmd.exe CVS_RSH=ssh DXSDK_DIR=C:\Program Files (x86)\Microsoft DirectX SDK (June 2010)\ EDITOR=emacs.exe FP_NO_HOST_CHECK=NO GIT_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/git-shared HG_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/hg-shared HOME=c:/Users/cltbld HOMEDRIVE=C: HOMEPATH=\ HOSTTYPE=i686 IDLEIZER_HALT_ON_IDLE=true INPUTRC=C:/mozilla-build/msys/etc/inputrc LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOG="c:\runner\buildbot-startup.log" LOGNAME=cltbld LOGONSERVER=\\B-2008-SPOT-002 MACHTYPE=i686-pc-msys MAKE_MODE=unix MOZBUILDDIR=C:\mozilla-build MOZILLABUILD=C:\mozilla-build MOZ_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=8 OLDPWD=c:/Users/cltbld OS=Windows_NT OSTYPE=msys OS_VERSION=2008 PATH=C:\mozilla-build\msys\local\bin;c:\mozilla-build\wget;c:\mozilla-build\7zip;c:\mozilla-build\blat261\full;c:\mozilla-build\python;c:\mozilla-build\svn-win32-1.6.3\bin;c:\mozilla-build\upx203w;c:\mozilla-build\emacs-24.3\bin;c:\mozilla-build\info-zip;c:\mozilla-build\nsis-2.46u;c:\mozilla-build\nsis-3.0a2;c:\mozilla-build\wix-351728;c:\mozilla-build\hg;c:\mozilla-build\python\Scripts;c:\mozilla-build\kdiff3;c:\mozilla-build\yasm;c:\mozilla-build\mozmake;.;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;c:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;c:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;c:\Program Files (x86)\Puppet Labs\Puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;c:\Windows\system32;c:\Windows;c:\Windows\System32\Wbem;c:\Windows\System32\WindowsPowerShell\v1.0\;c:\Program Files\Amazon\cfn-bootstrap\;c:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;c:\Program Files\Microsoft SQL Server\110\Tools\Binn\;c:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;c:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\mozilla-build\msys\bin;c:\mozilla-build\python27\scripts;c:\mozilla-build\wget;c:\mozilla-build\info-zip;c:\mozilla-build;c:\mozilla-build\vim\vim72;c:\mozilla-build\python27;c:\mozilla-build\hg;c:\mozilla-build\buildbotve\Scripts;c:\ProgramData\chocolatey\bin;.;c:\opt\runner;c:\mozilla-build\buildbotve;c:\mozilla-build\python27;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;.;C:\mozilla-build\msys\local\bin;c:\mozilla-build\moztools-x64\bin;c:\mozilla-build\vim\vim72 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 2, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3f02 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PS1=\[\033]0;$MSYSTEM:\w\007 \033[32m\]\u@\h \[\033[33m\w\033[0m\] $ PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files (x86)\AWS Tools\PowerShell\ PUBLIC=C:\Users\Public PWD=c:/Users/cltbld RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg SHELL=C:/mozilla-build/msys/bin/sh SHLVL=1 SSH_AGENT_PID=4016 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TMP=C:/Users/cltbld/AppData/Local/Temp USERDOMAIN=B-2008-SPOT-002 USERNAME=cltbld USERPROFILE=C:\Users\cltbld VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\ VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\ VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\ WINDIR=C:\Windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log WIX_351728_PATH=c:/mozilla-build/wix-351728 _=C:\mozilla-build\buildbotve\Scripts\python using PTY: False buildid:20161227235136 builduid:e042f36309ad4ae787d4cdca52b6a8af comments:Bug 1323901 - Force `which` to use lowercase extensions on Windows. r=cmanchester+432261 got_revision:21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0 stage_platform:win32-debug program finished with exit code 0 elapsedTime=0.107000 buildid: '20161227235136' got_revision: '21e27f6e2770e71e8d3ccae37d334f1e8c5c7af0' comments: 'Bug 1323901 - Force `which` to use lowercase extensions on Windows. r=cmanchester+432261' builduid: 'e042f36309ad4ae787d4cdca52b6a8af' stage_platform: 'win32-debug' ========= master_lag: 0.01 ========= ========= Finished set props: buildid got_revision comments builduid stage_platform (results: 0, elapsed: 0 secs) (at 2016-12-28 01:01:56.683866) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 01:01:56.684665) ========= 'rm' '-f' 'oauth.txt' in dir c:\builds\moz2_slave\autoland-w32-d-000000000000000\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv BUILDBOT_PATH=C:\mozilla-build\buildbotve CHOCOLATEYINSTALL=C:\ProgramData\chocolatey COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=B-2008-SPOT-002 COMSPEC=C:\Windows\system32\cmd.exe CVS_RSH=ssh DXSDK_DIR=C:\Program Files (x86)\Microsoft DirectX SDK (June 2010)\ EDITOR=emacs.exe FP_NO_HOST_CHECK=NO GIT_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/git-shared HG_SHARE_BASE_DIR=C:/mozilla-build/msys/builds/hg-shared HOME=c:/Users/cltbld HOMEDRIVE=C: HOMEPATH=\ HOSTTYPE=i686 IDLEIZER_HALT_ON_IDLE=true INPUTRC=C:/mozilla-build/msys/etc/inputrc LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOG="c:\runner\buildbot-startup.log" LOGNAME=cltbld LOGONSERVER=\\B-2008-SPOT-002 MACHTYPE=i686-pc-msys MAKE_MODE=unix MOZBUILDDIR=C:\mozilla-build MOZILLABUILD=C:\mozilla-build MOZ_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=8 OLDPWD=c:/Users/cltbld OS=Windows_NT OSTYPE=msys OS_VERSION=2008 PATH=C:\mozilla-build\msys\local\bin;c:\mozilla-build\wget;c:\mozilla-build\7zip;c:\mozilla-build\blat261\full;c:\mozilla-build\python;c:\mozilla-build\svn-win32-1.6.3\bin;c:\mozilla-build\upx203w;c:\mozilla-build\emacs-24.3\bin;c:\mozilla-build\info-zip;c:\mozilla-build\nsis-2.46u;c:\mozilla-build\nsis-3.0a2;c:\mozilla-build\wix-351728;c:\mozilla-build\hg;c:\mozilla-build\python\Scripts;c:\mozilla-build\kdiff3;c:\mozilla-build\yasm;c:\mozilla-build\mozmake;.;C:\mozilla-build\msys\local\bin;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;c:\Program Files (x86)\Puppet Labs\Puppet\puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\facter\bin;c:\Program Files (x86)\Puppet Labs\Puppet\hiera\bin;c:\Program Files (x86)\Puppet Labs\Puppet\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\ruby\bin;c:\Program Files (x86)\Puppet Labs\Puppet\sys\tools\bin;c:\Windows\system32;c:\Windows;c:\Windows\System32\Wbem;c:\Windows\System32\WindowsPowerShell\v1.0\;c:\Program Files\Amazon\cfn-bootstrap\;c:\Program Files (x86)\Windows Kits\8.0\Windows Performance Toolkit\;c:\Program Files (x86)\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\Tools\Binn\;c:\Program Files\Microsoft SQL Server\100\DTS\Binn\;c:\Program Files (x86)\Windows Kits\8.1\Windows Performance Toolkit\;c:\Program Files\Microsoft SQL Server\110\Tools\Binn\;c:\Program Files (x86)\Microsoft SDKs\TypeScript\1.0\;c:\Program Files (x86)\Puppet Labs\Puppet\bin;C:\mozilla-build\msys\bin;c:\mozilla-build\python27\scripts;c:\mozilla-build\wget;c:\mozilla-build\info-zip;c:\mozilla-build;c:\mozilla-build\vim\vim72;c:\mozilla-build\python27;c:\mozilla-build\hg;c:\mozilla-build\buildbotve\Scripts;c:\ProgramData\chocolatey\bin;.;c:\opt\runner;c:\mozilla-build\buildbotve;c:\mozilla-build\python27;C:\mozilla-build\msys\mingw\bin;C:\mozilla-build\msys\bin;C:\mozilla-build\msys\local\bin;.;C:\mozilla-build\msys\local\bin;c:\mozilla-build\moztools-x64\bin;c:\mozilla-build\vim\vim72 PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 2, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3f02 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PS1=\[\033]0;$MSYSTEM:\w\007 \033[32m\]\u@\h \[\033[33m\w\033[0m\] $ PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files (x86)\AWS Tools\PowerShell\ PUBLIC=C:\Users\Public PWD=c:/Users/cltbld RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg SHELL=C:/mozilla-build/msys/bin/sh SHLVL=1 SSH_AGENT_PID=4016 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-sRFkTa3960/agent.3960 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TMP=C:/Users/cltbld/AppData/Local/Temp USERDOMAIN=B-2008-SPOT-002 USERNAME=cltbld USERPROFILE=C:\Users\cltbld VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\ VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\ VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\ WINDIR=C:\Windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log WIX_351728_PATH=c:/mozilla-build/wix-351728 _=C:\mozilla-build\buildbotve\Scripts\python using PTY: False program finished with exit code 0 elapsedTime=0.105000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 01:01:56.803234) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 01:01:56.804033) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 01:01:56.804398) ========= ========= Total master_lag: 0.23 =========