builder: mozilla-inbound-win32-debug slave: b-2008-spot-168 starttime: 1476471512.46 results: failure (2) buildid: 20161014113936 builduid: 3524caeb2b2143e09aa5b0a8e455ffef revision: c14157737409 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:32.457898) ========= master: http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:32.458328) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:32.458605) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:32.982446) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-14 11:58:32.982741) ========= '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\m-in-w32-d-0000000000000000000\. (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-168 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-168 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=3976 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920 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-168 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-10-14 11:58:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|: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.63K=1.2s 2016-10-14 11:58:35 (9.63 KB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=3.739000 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-14 11:58:36.846444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:36.847263) ========= 'rm' '-rf' 'scripts' 'properties' in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (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-168 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-168 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=3976 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920 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-168 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 -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:36.961439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-14 11:58:36.962270) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c14157737409a119ef1f256fb1cf5aff0df2c465 --destination scripts --debug' in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c14157737409a119ef1f256fb1cf5aff0df2c465 --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-168 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-168 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=3976 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920 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-168 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-10-14 11:58:36,325 truncating revision to first 12 chars 2016-10-14 11:58:36,325 Setting DEBUG logging. 2016-10-14 11:58:36,325 attempt 1/10 2016-10-14 11:58:36,325 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c14157737409?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 11:58:37,819 unpacking tar archive at: mozilla-inbound-c14157737409/testing/mozharness/ 2016-10-14 11:58:40,648 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=5.427000 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-14 11:58:42.448880) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.449824) ========= script_repo_revision: c14157737409a119ef1f256fb1cf5aff0df2c465 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.450206) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.450476) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.457224) ========= ========= Started remove old nonce (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.457478) ========= 'rm' '-f' 'nonce' in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (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-168 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-168 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=3976 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920 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-168 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 remove old nonce (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.569687) ========= ========= Started downloading to token (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.570575) ========= Slave: b-2008-spot-168 IP: 10.132.52.43 Duration: 25200 URI: https://signing6.srv.releng.scl3.mozilla.com:9110/token ========= Finished downloading to token (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.781067) ========= ========= Started set props: toolsdir (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.781480) ========= 'bash' '-c' 'pwd' in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\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-168 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-168 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=3976 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920 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-168 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/m-in-w32-d-0000000000000000000/scripts program finished with exit code 0 elapsedTime=0.105000 toolsdir: '/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts' ========= master_lag: 0.01 ========= ========= Finished set props: toolsdir (results: 0, elapsed: 0 secs) (at 2016-10-14 11:58:42.894647) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 1 hrs, 55 mins, 25 secs) (at 2016-10-14 11:58:42.895683) ========= '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' 'mozilla-inbound' '--build-pool' 'production' in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (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', 'mozilla-inbound', '--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-168 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-168 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/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/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/m-in-w32-d-0000000000000000000/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=3976 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TINDERBOX_OUTPUT=1 TMP=C:/Users/cltbld/AppData/Local/Temp USERDOMAIN=B-2008-SPOT-168 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 11:58:45 INFO - MultiFileLogger online at 20161014 11:58:45 in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000 11:58:45 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. 11:58:45 INFO - Script was run with '--branch mozilla-inbound' and 'mozilla-inbound' matches a key in 'builds/branch_specifics.py'. Updating self.config with items from that key's value. 11:58:45 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. 11:58:45 INFO - To generate a config file based upon options passed and config files used, run script as before but extend options with "--dump-config" 11:58:45 INFO - For a diff of where self.config got its items, run the script again as before but extend options with: "--dump-config-hierarchy" 11:58:45 INFO - Both --dump-config and --dump-config-hierarchy don't actually run any actions. 11:58:45 INFO - We are running this in buildbot, grab the build props 11:58:45 INFO - Using buildbot properties: 11:58:45 INFO - { 11:58:45 INFO - "project": "", 11:58:45 INFO - "product": "firefox", 11:58:45 INFO - "script_repo_revision": "production", 11:58:45 INFO - "scheduler": "mozilla-inbound-firefox", 11:58:45 INFO - "repository": "", 11:58:45 INFO - "buildername": "WINNT 5.2 mozilla-inbound leak test build", 11:58:45 INFO - "buildid": "20161014113936", 11:58:45 INFO - "basedir": "/c/builds/moz2_slave/m-in-w32-d-0000000000000000000", 11:58:45 INFO - "buildnumber": 2700, 11:58:45 INFO - "platform": "win32-debug", 11:58:45 INFO - "master": "http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/", 11:58:45 INFO - "branch": "mozilla-inbound", 11:58:45 INFO - "revision": "c14157737409a119ef1f256fb1cf5aff0df2c465", 11:58:45 INFO - "slavename": "b-2008-spot-168", 11:58:45 INFO - "builduid": "3524caeb2b2143e09aa5b0a8e455ffef", 11:58:45 INFO - "repo_path": "integration/mozilla-inbound" 11:58:45 INFO - } 11:58:45 INFO - Setting buildbot property stage_platform to win32-debug 11:58:45 INFO - mkdir: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties 11:58:45 INFO - Writing buildbot properties ['stage_platform'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\stage_platform 11:58:45 INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\stage_platform 11:58:45 INFO - Contents: 11:58:45 INFO - stage_platform:win32-debug 11:58:45 WARNING - Try message not found. 11:58:45 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 mozilla-inbound --build-pool production 11:58:45 INFO - Determining buildid from buildbot properties 11:58:45 INFO - Setting buildbot property buildid to 20161014113936 11:58:45 INFO - Writing buildbot properties ['buildid'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\buildid 11:58:45 INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\buildid 11:58:45 INFO - Contents: 11:58:45 INFO - buildid:20161014113936 11:58:45 INFO - Determining builduid from buildbot properties 11:58:45 INFO - Setting buildbot property builduid to 3524caeb2b2143e09aa5b0a8e455ffef 11:58:45 INFO - Writing buildbot properties ['builduid'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\builduid 11:58:45 INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\builduid 11:58:45 INFO - Contents: 11:58:45 INFO - builduid:3524caeb2b2143e09aa5b0a8e455ffef 11:58:45 INFO - Creating virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv 11:58:45 INFO - mkdir: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build 11:58:45 INFO - Running command: ['c:\\mozilla-build\\python27\\python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build 11:58:45 INFO - Copy/paste: c:\mozilla-build\python27\python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv 11:58:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:58:53 INFO - New python executable in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\python.exe 11:58:58 INFO - Installing distribute................................................................................................................................................................................................done. 11:59:20 INFO - Installing pip..................................done. 11:59:20 INFO - Return code: 0 11:59:20 INFO - Getting output from command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', '--version'] 11:59:20 INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip --version 11:59:24 INFO - Reading from file tmpfile_stdout 11:59:24 INFO - Output received: 11:59:24 INFO - pip 8.1.2 from c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\lib\site-packages\pip-8.1.2-py2.7.egg (python 2.7) 11:59:24 INFO - Using _rmtree_windows ... 11:59:24 INFO - Using _rmtree_windows ... 11:59:24 INFO - Installing requests==2.8.1 into virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv 11:59:24 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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 0x0233DC58>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0240E278>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023E4E60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0271D730>, '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 0x0268C958>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02722808>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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\\m-in-w32-d-0000000000000000000\\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-168', '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-168', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-168', '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\\m-in-w32-d-0000000000000000000\\.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': '3976', '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-vJYiBQ3920/agent.3920', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/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 11:59:24 INFO - Running command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'requests==2.8.1'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build 11:59:24 INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip install --timeout 120 requests==2.8.1 11:59:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:59:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:59:24 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 11:59:24 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 11:59:24 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 11:59:24 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 11:59:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 11:59:24 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 11:59:24 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 11:59:24 INFO - 'COMPUTERNAME': 'B-2008-SPOT-168', 11:59:24 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 11:59:24 INFO - 'CVS_RSH': 'ssh', 11:59:24 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 11:59:24 INFO - 'EDITOR': 'emacs.exe', 11:59:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:59:24 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 11:59:24 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 11:59:24 INFO - 'HOME': 'c:/Users/cltbld', 11:59:24 INFO - 'HOMEDRIVE': 'C:', 11:59:24 INFO - 'HOMEPATH': '\\', 11:59:24 INFO - 'HOSTTYPE': 'i686', 11:59:24 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 11:59:24 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 11:59:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:59:24 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 11:59:24 INFO - 'LOGNAME': 'cltbld', 11:59:24 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-168', 11:59:24 INFO - 'MACHTYPE': 'i686-pc-msys', 11:59:24 INFO - 'MAKE_MODE': 'unix', 11:59:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 11:59:24 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild', 11:59:24 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 11:59:24 INFO - 'MOZ_AUTOMATION': '1', 11:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:24 INFO - 'MOZ_OBJDIR': 'obj-firefox', 11:59:24 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', 11:59:24 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/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', 11:59:24 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 11:59:24 INFO - 'MSYSTEM': 'MINGW32', 11:59:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:59:24 INFO - 'OLDPWD': 'c:/Users/cltbld', 11:59:24 INFO - 'OS': 'Windows_NT', 11:59:24 INFO - 'OSTYPE': 'msys', 11:59:24 INFO - 'OS_VERSION': '2008', 11:59:24 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', 11:59:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:59:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:59:24 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 11:59:24 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 11:59:24 INFO - 'PROCESSOR_LEVEL': '6', 11:59:24 INFO - 'PROCESSOR_REVISION': '3f02', 11:59:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:59:24 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 11:59:24 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 11:59:24 INFO - 'PROGRAMW6432': 'C:\\Program Files', 11:59:24 INFO - 'PROMPT': '$P$G', 11:59:24 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json', 11:59:24 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 11:59:24 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 11:59:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:59:24 INFO - 'PWD': 'c:/Users/cltbld', 11:59:24 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 11:59:24 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 11:59:24 INFO - 'SHLVL': '1', 11:59:24 INFO - 'SSH_AGENT_PID': '3976', 11:59:24 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920', 11:59:24 INFO - 'SYSTEMDRIVE': 'C:', 11:59:24 INFO - 'SYSTEMROOT': 'C:\\Windows', 11:59:24 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 11:59:24 INFO - 'TERM': 'cygwin', 11:59:24 INFO - 'TINDERBOX_OUTPUT': '1', 11:59:24 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 11:59:24 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 11:59:24 INFO - 'TOOLTOOL_HOME': '/c/builds', 11:59:24 INFO - 'USERDOMAIN': 'B-2008-SPOT-168', 11:59:24 INFO - 'USERNAME': 'cltbld', 11:59:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:59:24 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 11:59:24 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 11:59:24 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 11:59:24 INFO - 'WINDIR': 'C:\\Windows', 11:59:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:59:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:59:24 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 11:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 11:59:24 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 11:59:24 INFO - Collecting requests==2.8.1 11:59:25 INFO - Downloading requests-2.8.1-py2.py3-none-any.whl (497kB) 11:59:26 INFO - Installing collected packages: requests 11:59:26 INFO - Successfully installed requests-2.8.1 11:59:27 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\lib\site-packages\pip-8.1.2-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.org/en/latest/security.html#snimissingwarning. 11:59:27 INFO - SNIMissingWarning 11:59:27 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\lib\site-packages\pip-8.1.2-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.org/en/latest/security.html#insecureplatformwarning. 11:59:27 INFO - InsecurePlatformWarning 11:59:27 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\lib\site-packages\pip-8.1.2-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.org/en/latest/security.html#insecureplatformwarning. 11:59:27 INFO - InsecurePlatformWarning 11:59:27 INFO - Return code: 0 11:59:27 INFO - Installing PyHawk-with-a-single-extra-commit==0.1.5 into virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv 11:59:27 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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 0x0233DC58>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0240E278>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023E4E60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0271D730>, '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 0x0268C958>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02722808>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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\\m-in-w32-d-0000000000000000000\\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-168', '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-168', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-168', '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\\m-in-w32-d-0000000000000000000\\.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': '3976', '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-vJYiBQ3920/agent.3920', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/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 11:59:27 INFO - Running command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'PyHawk-with-a-single-extra-commit==0.1.5'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build 11:59:27 INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip install --timeout 120 PyHawk-with-a-single-extra-commit==0.1.5 11:59:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:59:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:59:27 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 11:59:27 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 11:59:27 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 11:59:27 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 11:59:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 11:59:27 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 11:59:27 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 11:59:27 INFO - 'COMPUTERNAME': 'B-2008-SPOT-168', 11:59:27 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 11:59:27 INFO - 'CVS_RSH': 'ssh', 11:59:27 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 11:59:27 INFO - 'EDITOR': 'emacs.exe', 11:59:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:59:27 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 11:59:27 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 11:59:27 INFO - 'HOME': 'c:/Users/cltbld', 11:59:27 INFO - 'HOMEDRIVE': 'C:', 11:59:27 INFO - 'HOMEPATH': '\\', 11:59:27 INFO - 'HOSTTYPE': 'i686', 11:59:27 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 11:59:27 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 11:59:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:59:27 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 11:59:27 INFO - 'LOGNAME': 'cltbld', 11:59:27 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-168', 11:59:27 INFO - 'MACHTYPE': 'i686-pc-msys', 11:59:27 INFO - 'MAKE_MODE': 'unix', 11:59:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 11:59:27 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild', 11:59:27 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 11:59:27 INFO - 'MOZ_AUTOMATION': '1', 11:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:27 INFO - 'MOZ_OBJDIR': 'obj-firefox', 11:59:27 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', 11:59:27 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/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', 11:59:27 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 11:59:27 INFO - 'MSYSTEM': 'MINGW32', 11:59:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:59:27 INFO - 'OLDPWD': 'c:/Users/cltbld', 11:59:27 INFO - 'OS': 'Windows_NT', 11:59:27 INFO - 'OSTYPE': 'msys', 11:59:27 INFO - 'OS_VERSION': '2008', 11:59:27 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', 11:59:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:59:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:59:27 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 11:59:27 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 11:59:27 INFO - 'PROCESSOR_LEVEL': '6', 11:59:27 INFO - 'PROCESSOR_REVISION': '3f02', 11:59:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:59:27 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 11:59:27 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 11:59:27 INFO - 'PROGRAMW6432': 'C:\\Program Files', 11:59:27 INFO - 'PROMPT': '$P$G', 11:59:27 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json', 11:59:27 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 11:59:27 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 11:59:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:59:27 INFO - 'PWD': 'c:/Users/cltbld', 11:59:27 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 11:59:27 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 11:59:27 INFO - 'SHLVL': '1', 11:59:27 INFO - 'SSH_AGENT_PID': '3976', 11:59:27 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920', 11:59:27 INFO - 'SYSTEMDRIVE': 'C:', 11:59:27 INFO - 'SYSTEMROOT': 'C:\\Windows', 11:59:27 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 11:59:27 INFO - 'TERM': 'cygwin', 11:59:27 INFO - 'TINDERBOX_OUTPUT': '1', 11:59:27 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 11:59:27 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 11:59:27 INFO - 'TOOLTOOL_HOME': '/c/builds', 11:59:27 INFO - 'USERDOMAIN': 'B-2008-SPOT-168', 11:59:27 INFO - 'USERNAME': 'cltbld', 11:59:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:59:27 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 11:59:27 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 11:59:27 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 11:59:27 INFO - 'WINDIR': 'C:\\Windows', 11:59:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:59:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:59:27 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 11:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 11:59:27 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 11:59:27 INFO - Collecting PyHawk-with-a-single-extra-commit==0.1.5 11:59:28 INFO - Downloading PyHawk-with-a-single-extra-commit-0.1.5.tar.gz 11:59:28 INFO - Installing collected packages: PyHawk-with-a-single-extra-commit 11:59:28 INFO - Running setup.py install for PyHawk-with-a-single-extra-commit: started 11:59:28 INFO - Running setup.py install for PyHawk-with-a-single-extra-commit: finished with status 'done' 11:59:28 INFO - Successfully installed PyHawk-with-a-single-extra-commit-0.1.5 11:59:29 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\lib\site-packages\pip-8.1.2-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.org/en/latest/security.html#snimissingwarning. 11:59:29 INFO - SNIMissingWarning 11:59:29 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\lib\site-packages\pip-8.1.2-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.org/en/latest/security.html#insecureplatformwarning. 11:59:29 INFO - InsecurePlatformWarning 11:59:29 INFO - Return code: 0 11:59:29 INFO - Installing taskcluster==0.0.26 into virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv 11:59:29 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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 0x0233DC58>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0240E278>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x023E4E60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0271D730>, '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 0x0268C958>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x02722808>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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\\m-in-w32-d-0000000000000000000\\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-168', '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-168', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-168', '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\\m-in-w32-d-0000000000000000000\\.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': '3976', '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-vJYiBQ3920/agent.3920', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/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 11:59:29 INFO - Running command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'taskcluster==0.0.26'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build 11:59:29 INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip install --timeout 120 taskcluster==0.0.26 11:59:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:59:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:59:29 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 11:59:29 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 11:59:29 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 11:59:29 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 11:59:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 11:59:29 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 11:59:29 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 11:59:29 INFO - 'COMPUTERNAME': 'B-2008-SPOT-168', 11:59:29 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 11:59:29 INFO - 'CVS_RSH': 'ssh', 11:59:29 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 11:59:29 INFO - 'EDITOR': 'emacs.exe', 11:59:29 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:59:29 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 11:59:29 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 11:59:29 INFO - 'HOME': 'c:/Users/cltbld', 11:59:29 INFO - 'HOMEDRIVE': 'C:', 11:59:29 INFO - 'HOMEPATH': '\\', 11:59:29 INFO - 'HOSTTYPE': 'i686', 11:59:29 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 11:59:29 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 11:59:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:59:29 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 11:59:29 INFO - 'LOGNAME': 'cltbld', 11:59:29 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-168', 11:59:29 INFO - 'MACHTYPE': 'i686-pc-msys', 11:59:29 INFO - 'MAKE_MODE': 'unix', 11:59:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 11:59:29 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild', 11:59:29 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 11:59:29 INFO - 'MOZ_AUTOMATION': '1', 11:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:29 INFO - 'MOZ_OBJDIR': 'obj-firefox', 11:59:29 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', 11:59:29 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/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', 11:59:29 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 11:59:29 INFO - 'MSYSTEM': 'MINGW32', 11:59:29 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:59:29 INFO - 'OLDPWD': 'c:/Users/cltbld', 11:59:29 INFO - 'OS': 'Windows_NT', 11:59:29 INFO - 'OSTYPE': 'msys', 11:59:29 INFO - 'OS_VERSION': '2008', 11:59:29 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', 11:59:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:59:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:59:29 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 11:59:29 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 11:59:29 INFO - 'PROCESSOR_LEVEL': '6', 11:59:29 INFO - 'PROCESSOR_REVISION': '3f02', 11:59:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:59:29 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 11:59:29 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 11:59:29 INFO - 'PROGRAMW6432': 'C:\\Program Files', 11:59:29 INFO - 'PROMPT': '$P$G', 11:59:29 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json', 11:59:29 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 11:59:29 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 11:59:29 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:59:29 INFO - 'PWD': 'c:/Users/cltbld', 11:59:29 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 11:59:29 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 11:59:29 INFO - 'SHLVL': '1', 11:59:29 INFO - 'SSH_AGENT_PID': '3976', 11:59:29 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920', 11:59:29 INFO - 'SYSTEMDRIVE': 'C:', 11:59:29 INFO - 'SYSTEMROOT': 'C:\\Windows', 11:59:29 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 11:59:29 INFO - 'TERM': 'cygwin', 11:59:29 INFO - 'TINDERBOX_OUTPUT': '1', 11:59:29 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 11:59:29 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 11:59:29 INFO - 'TOOLTOOL_HOME': '/c/builds', 11:59:29 INFO - 'USERDOMAIN': 'B-2008-SPOT-168', 11:59:29 INFO - 'USERNAME': 'cltbld', 11:59:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:59:29 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 11:59:29 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 11:59:29 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 11:59:29 INFO - 'WINDIR': 'C:\\Windows', 11:59:29 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:59:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:59:29 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 11:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 11:59:29 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 11:59:29 INFO - Collecting taskcluster==0.0.26 11:59:30 INFO - Downloading taskcluster-0.0.26.tar.gz 11:59:30 INFO - Collecting requests<=2.7.0,>=2.4.3 (from taskcluster==0.0.26) 11:59:30 INFO - Downloading requests-2.7.0-py2.py3-none-any.whl (470kB) 11:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): PyHawk_with_a_single_extra_commit==0.1.5 in .\venv\lib\site-packages (from taskcluster==0.0.26) 11:59:31 INFO - Collecting slugid (from taskcluster==0.0.26) 11:59:31 INFO - Downloading slugid-1.0.7.tar.gz 11:59:31 INFO - Installing collected packages: requests, slugid, taskcluster 11:59:31 INFO - Found existing installation: requests 2.8.1 11:59:31 INFO - Uninstalling requests-2.8.1: 11:59:32 INFO - Successfully uninstalled requests-2.8.1 11:59:33 INFO - Running setup.py install for slugid: started 11:59:33 INFO - Running setup.py install for slugid: finished with status 'done' 11:59:33 INFO - Running setup.py install for taskcluster: started 11:59:33 INFO - Running setup.py install for taskcluster: finished with status 'done' 11:59:34 INFO - Successfully installed requests-2.7.0 slugid-1.0.7 taskcluster-0.0.26 11:59:34 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\lib\site-packages\pip-8.1.2-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.org/en/latest/security.html#snimissingwarning. 11:59:34 INFO - SNIMissingWarning 11:59:34 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\lib\site-packages\pip-8.1.2-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.org/en/latest/security.html#insecureplatformwarning. 11:59:34 INFO - InsecurePlatformWarning 11:59:34 INFO - Return code: 0 11:59:34 INFO - Done creating virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv. 11:59:34 INFO - Getting output from command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'freeze'] 11:59:34 INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip freeze 11:59:35 INFO - Reading from file tmpfile_stdout 11:59:35 INFO - Using _rmtree_windows ... 11:59:35 INFO - Using _rmtree_windows ... 11:59:35 INFO - Current package versions: 11:59:35 INFO - PyHawk-with-a-single-extra-commit == 0.1.5 11:59:35 INFO - requests == 2.7.0 11:59:35 INFO - slugid == 1.0.7 11:59:35 INFO - taskcluster == 0.0.26 11:59:35 INFO - Running pre-run listener: influxdb_recording_init 11:59:35 INFO - build_resources.json path: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src/obj-firefox/.mozbuild/build_resources.json 11:59:35 INFO - rmtree: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src/obj-firefox/.mozbuild/build_resources.json 11:59:35 INFO - Using _rmtree_windows ... 11:59:35 INFO - retry: Calling _rmtree_windows with args: ('c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src/obj-firefox/.mozbuild/build_resources.json',), kwargs: {}, attempt #1 11:59:35 INFO - Getting output from command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\python', '-c', 'from distutils.sysconfig import get_python_lib; print(get_python_lib())'] 11:59:35 INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\python -c "from distutils.sysconfig import get_python_lib; print(get_python_lib())" 11:59:35 INFO - Reading from file tmpfile_stdout 11:59:35 INFO - Output received: 11:59:35 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Lib\site-packages 11:59:35 INFO - Using _rmtree_windows ... 11:59:35 INFO - Using _rmtree_windows ... 11:59:35 INFO - Dumping config to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\logs\localconfig.json. 11:59:35 INFO - {'app_ini_path': '%(obj_dir)s/dist/bin/application.ini', 11:59:35 INFO - 'append_to_log': False, 11:59:35 INFO - 'balrog_credentials_file': 'oauth.txt', 11:59:35 INFO - 'balrog_servers': ({'balrog_api_root': 'https://aus4-admin.mozilla.org/api', 11:59:35 INFO - 'balrog_usernames': {'Fennec': 'ffxbld', 11:59:35 INFO - 'firefox': 'ffxbld', 11:59:35 INFO - 'mobile': 'ffxbld', 11:59:35 INFO - 'thunderbird': 'tbirdbld'}, 11:59:35 INFO - 'ignore_failures': False, 11:59:35 INFO - 'url_replacements': (('http://archive.mozilla.org/pub', 11:59:35 INFO - 'http://download.cdn.mozilla.net/pub'),)},), 11:59:35 INFO - 'base_name': 'WINNT_5.2_%(branch)s', 11:59:35 INFO - 'base_work_dir': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000', 11:59:35 INFO - 'branch': 'mozilla-inbound', 11:59:35 INFO - 'build_pool': 'production', 11:59:35 INFO - 'build_resources_path': '%(abs_src_dir)s/obj-firefox/.mozbuild/build_resources.json', 11:59:35 INFO - 'build_variant': 'debug', 11:59:35 INFO - 'buildbot_json_path': 'buildprops.json', 11:59:35 INFO - 'check_test_env': {'MINIDUMP_SAVE_PATH': '%(base_work_dir)s/minidumps', 11:59:35 INFO - 'MINIDUMP_STACKWALK': '%(abs_tools_dir)s/breakpad/win32/minidump_stackwalk.exe'}, 11:59:35 INFO - 'clobberer_url': 'https://api.pub.build.mozilla.org/clobberer/lastclobber', 11:59:35 INFO - 'clone_by_revision': False, 11:59:35 INFO - 'clone_with_purge': False, 11:59:35 INFO - 'config_files': ('builds/releng_base_windows_32_builds.py', 11:59:35 INFO - 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\scripts\\scripts\\..\\configs\\builds/releng_sub_windows_configs/32_debug.py', 11:59:35 INFO - 'balrog/production.py', 11:59:35 INFO - 'builds/branch_specifics.py', 11:59:35 INFO - 'builds/build_pool_specifics.py'), 11:59:35 INFO - 'debug_build': True, 11:59:35 INFO - 'default_actions': ('clobber', 11:59:35 INFO - 'clone-tools', 11:59:35 INFO - 'checkout-sources', 11:59:35 INFO - 'build', 11:59:35 INFO - 'upload-files', 11:59:35 INFO - 'sendchange', 11:59:35 INFO - 'check-test', 11:59:35 INFO - 'generate-build-stats', 11:59:35 INFO - 'update'), 11:59:35 INFO - 'enable_ccache': False, 11:59:35 INFO - 'enable_count_ctors': False, 11:59:35 INFO - 'enable_pymake': True, 11:59:35 INFO - 'enable_signing': True, 11:59:35 INFO - 'enable_talos_sendchange': False, 11:59:35 INFO - 'enable_unittest_sendchange': True, 11:59:35 INFO - 'env': {'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 11:59:35 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 11:59:35 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild', 11:59:35 INFO - 'MOZ_AUTOMATION': '1', 11:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:35 INFO - 'MOZ_OBJDIR': 'obj-firefox', 11:59:35 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', 11:59:35 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json', 11:59:35 INFO - 'TINDERBOX_OUTPUT': '1', 11:59:35 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 11:59:35 INFO - 'TOOLTOOL_HOME': '/c/builds', 11:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort'}, 11:59:35 INFO - 'exes': {'buildbot': ('c:\\mozilla-build\\python27\\python.exe', 11:59:35 INFO - 'c:\\mozilla-build\\buildbotve\\scripts\\buildbot'), 11:59:35 INFO - 'make': ('c:\\mozilla-build\\python27\\python.exe', 11:59:35 INFO - 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\build\\pymake\\make.py'), 11:59:35 INFO - 'python2.7': 'c:\\mozilla-build\\python27\\python.exe', 11:59:35 INFO - 'virtualenv': ('c:\\mozilla-build\\python27\\python.exe', 11:59:35 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 11:59:35 INFO - 'graph_selector': '/server/collect.cgi', 11:59:35 INFO - 'graph_server': 'graphs.mozilla.org', 11:59:35 INFO - 'include_post_upload_builddir': False, 11:59:35 INFO - 'influx_credentials_file': 'oauth.txt', 11:59:35 INFO - 'is_automation': True, 11:59:35 INFO - 'latest_mar_dir': '/pub/mozilla.org/firefox/nightly/latest-%(branch)s', 11:59:35 INFO - 'log_level': 'info', 11:59:35 INFO - 'log_to_console': True, 11:59:35 INFO - 'max_build_output_timeout': 4800, 11:59:35 INFO - 'nightly_build': False, 11:59:35 INFO - 'nightly_promotion_branches': ('mozilla-central', 'mozilla-aurora'), 11:59:35 INFO - 'objdir': 'obj-firefox', 11:59:35 INFO - 'old_packages': ('%(objdir)s/dist/firefox-*', 11:59:35 INFO - '%(objdir)s/dist/fennec*', 11:59:35 INFO - '%(objdir)s/dist/seamonkey*', 11:59:35 INFO - '%(objdir)s/dist/thunderbird*', 11:59:35 INFO - '%(objdir)s/dist/install/sea/*.exe'), 11:59:35 INFO - 'opt_config_files': (), 11:59:35 INFO - 'periodic_clobber': 168, 11:59:35 INFO - 'pgo_build': False, 11:59:35 INFO - 'pgo_platforms': ('linux', 'linux64', 'win32', 'win64'), 11:59:35 INFO - 'platform': 'win32', 11:59:35 INFO - 'platform_supports_post_upload_to_latest': True, 11:59:35 INFO - 'publish_nightly_en_US_routes': True, 11:59:35 INFO - 'release_to_try_builds': False, 11:59:35 INFO - 'repo_base': 'https://hg.mozilla.org', 11:59:35 INFO - 'repo_path': 'integration/mozilla-inbound', 11:59:35 INFO - 'scm_level': 1, 11:59:35 INFO - 'sendchange_masters': ('buildbot-master81.build.mozilla.org:9301',), 11:59:35 INFO - 'src_mozconfig': 'browser/config/mozconfigs/win32/debug', 11:59:35 INFO - 'stage_platform': 'win32-debug', 11:59:35 INFO - 'stage_product': 'firefox', 11:59:35 INFO - 'stage_server': 'upload.ffxbld.productdelivery.prod.mozaws.net', 11:59:35 INFO - 'stage_ssh_key': 'ffxbld_rsa', 11:59:35 INFO - 'stage_username': 'ffxbld', 11:59:35 INFO - 'taskcluster_credentials_file': 'oauth.txt', 11:59:35 INFO - 'taskcluster_index': 'index', 11:59:35 INFO - 'tinderbox_build_dir': None, 11:59:35 INFO - 'to_tinderbox_dated': True, 11:59:35 INFO - 'tools_repo': 'https://hg.mozilla.org/build/tools', 11:59:35 INFO - 'tooltool_bootstrap': 'setup.sh', 11:59:35 INFO - 'tooltool_manifest_src': 'browser/config/tooltool-manifests/win32/releng.manifest', 11:59:35 INFO - 'tooltool_script': ('c:\\mozilla-build\\python27\\python.exe', 11:59:35 INFO - 'C:/mozilla-build/tooltool.py'), 11:59:35 INFO - 'tooltool_url': 'https://api.pub.build.mozilla.org/tooltool/', 11:59:35 INFO - 'upload_env': {'UPLOAD_HOST': '%(stage_server)s', 11:59:35 INFO - 'UPLOAD_SSH_KEY': '/c/Users/cltbld/.ssh/%(stage_ssh_key)s', 11:59:35 INFO - 'UPLOAD_TO_TEMP': '1', 11:59:35 INFO - 'UPLOAD_USER': '%(stage_username)s'}, 11:59:35 INFO - 'use_clobberer': True, 11:59:35 INFO - 'vcs_share_base': 'C:/builds/hg-shared', 11:59:35 INFO - 'virtualenv_modules': ('requests==2.8.1', 11:59:35 INFO - 'PyHawk-with-a-single-extra-commit==0.1.5', 11:59:35 INFO - 'taskcluster==0.0.26'), 11:59:35 INFO - 'virtualenv_path': 'venv', 11:59:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:59:35 INFO - 'who': '', 11:59:35 INFO - 'work_dir': 'build'} 11:59:35 INFO - [mozharness: 2016-10-14 18:59:35.269000Z] Skipping get-secrets step. 11:59:35 INFO - [mozharness: 2016-10-14 18:59:35.269000Z] Running clobber step. 11:59:35 INFO - Running pre-action listener: influxdb_recording_pre_action 11:59:35 INFO - Running main action method: clobber 11:59:35 INFO - retry: Calling run_command with args: [['python', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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'mozilla-inbound', u'WINNT 5.2 mozilla-inbound leak test build', 'm-in-w32-d-0000000000000000000', u'b-2008-spot-168', u'http://buildbot-master73.bb.releng.usw2.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 11:59:35 INFO - Running command: ['python', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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'mozilla-inbound', u'WINNT 5.2 mozilla-inbound leak test build', 'm-in-w32-d-0000000000000000000', u'b-2008-spot-168', u'http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/'] in c:\builds\moz2_slave 11:59:35 INFO - Copy/paste: python c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\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 mozilla-inbound "WINNT 5.2 mozilla-inbound leak test build" m-in-w32-d-0000000000000000000 b-2008-spot-168 http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/ 11:59:35 INFO - Checking clobber URL: https://api.pub.build.mozilla.org/clobberer/lastclobber?master=http%3A%2F%2Fbuildbot-master73.bb.releng.usw2.mozilla.com%3A8001%2F&slave=b-2008-spot-168&builddir=m-in-w32-d-0000000000000000000&branch=mozilla-inbound&buildername=WINNT+5.2+mozilla-inbound+leak+test+build 11:59:35 INFO - m-in-w32-d-0000000000000000000:Our last clobber date: None 11:59:35 INFO - m-in-w32-d-0000000000000000000:Server clobber date: 2016-09-24 10:33:20 11:59:35 INFO - TinderboxPrint: purged clobber 11:59:35 INFO - Return code: 0 11:59:35 INFO - Running post-action listener: influxdb_recording_post_action 11:59:35 INFO - Starting new HTTPS connection (1): goldiewilson-onepointtwentyone-1.c.influxdb.com c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Lib\site-packages\requests\packages\urllib3\util\ssl_.py:90: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. For more information, see https://urllib3.readthedocs.org/en/latest/security.html#insecureplatformwarning. InsecurePlatformWarning 11:59:36 INFO - [mozharness: 2016-10-14 18:59:36.183000Z] Finished clobber step (success) 11:59:36 INFO - [mozharness: 2016-10-14 18:59:36.183000Z] Running clone-tools step. 11:59:36 INFO - Running pre-action listener: influxdb_recording_pre_action 11:59:36 INFO - Running main action method: clone_tools 11:59:36 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\tools'), kwargs: {}, attempt #1 11:59:36 WARNING - did not specify revision or branch; assuming "default" 11:59:36 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 11:59:36 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 11:59:39 INFO - Mercurial Distributed SCM (version 3.9.1) 11:59:39 INFO - (see https://mercurial-scm.org for more information) 11:59:39 INFO - Copyright (C) 2005-2016 Matt Mackall and others 11:59:39 INFO - This is free software; see the source for copying conditions. There is NO 11:59:39 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 11:59:39 INFO - Return code: 0 11:59:39 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 11:59:39 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 11:59:39 INFO - checking encoding (cp1252)... 11:59:39 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 11:59:39 INFO - checking Python version (2.7.12) 11:59:39 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 11:59:39 INFO - checking Mercurial version (3.9.1) 11:59:39 INFO - checking Mercurial custom build () 11:59:39 INFO - checking module policy (c) 11:59:39 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 11:59:39 INFO - checking templates (c:\mozilla-build\hg\templates)... 11:59:39 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 11:59:39 INFO - checking commit editor... (notepad) 11:59:39 INFO - checking username (Mozilla Release Engineering ) 11:59:39 INFO - no problems detected 11:59:39 INFO - Return code: 0 11:59:39 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/build/tools', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\tools', '--sharebase', 'C:/builds/hg-shared', '--branch', 'default'] 11:59:39 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/build/tools c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\tools --sharebase C:/builds/hg-shared --branch default 11:59:46 INFO - 11:59:46 INFO - clone [============================> ] 17811132/28696640 02s 11:59:46 INFO - clone [===============================> ] 19223643/28696640 02s 11:59:46 INFO - 11:59:46 INFO - ensuring https://hg.mozilla.org/build/tools@default is available at c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\tools 11:59:46 INFO - (sharing from new pooled repository 7ae7fb134bf7aec6ec96a062ff47a69053dd2973) 11:59:46 INFO - applying clone bundle from https://s3-us-west-2.amazonaws.com/moz-hg-bundles-us-west-2/build/tools/5e27e3753eff6ac11631192797b35a942e23f783.packed1.hg 11:59:46 INFO - 2021 files to transfer, 27.4 MB of data 11:59:46 INFO - transferred 27.4 MB in 4.7 seconds (5.82 MB/sec) 11:59:46 INFO - finished applying clone bundle 11:59:46 INFO - searching for changes 11:59:46 INFO - adding changesets 11:59:46 INFO - adding manifests 11:59:46 INFO - adding file changes 11:59:46 INFO - added 1 changesets with 3 changes to 3 files 11:59:47 INFO - searching for changes 11:59:47 INFO - no changes found 11:59:47 INFO - (pulling to obtain default) 11:59:47 INFO - (remote resolved default to 78748858da198ca43c54765522a91ecebee0e075; result is not deterministic) 11:59:47 INFO - (revision already present locally; not pulling) 11:59:55 INFO - 11:59:55 INFO - updating [===> ] 100/1144 11:59:55 INFO - updating [=======================> ] 500/1144 11:59:55 INFO - updating [==========================> ] 600/1144 05s 11:59:55 INFO - updating [====================================> ] 800/1144 03s 11:59:55 INFO - 11:59:55 INFO - 1144 files updated, 0 files merged, 0 files removed, 0 files unresolved 11:59:55 INFO - updated to 78748858da198ca43c54765522a91ecebee0e075 11:59:55 INFO - Return code: 0 11:59:55 INFO - Running post-action listener: influxdb_recording_post_action 11:59:55 INFO - Resetting dropped connection: goldiewilson-onepointtwentyone-1.c.influxdb.com c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Lib\site-packages\requests\packages\urllib3\util\ssl_.py:90: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. For more information, see https://urllib3.readthedocs.org/en/latest/security.html#insecureplatformwarning. InsecurePlatformWarning 11:59:55 INFO - [mozharness: 2016-10-14 18:59:55.409000Z] Finished clone-tools step (success) 11:59:55 INFO - [mozharness: 2016-10-14 18:59:55.409000Z] Running checkout-sources step. 11:59:55 INFO - Running pre-action listener: influxdb_recording_pre_action 11:59:55 INFO - Running main action method: checkout_sources 11:59:55 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src'), kwargs: {}, attempt #1 11:59:55 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 11:59:55 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 11:59:55 INFO - Mercurial Distributed SCM (version 3.9.1) 11:59:55 INFO - (see https://mercurial-scm.org for more information) 11:59:55 INFO - Copyright (C) 2005-2016 Matt Mackall and others 11:59:55 INFO - This is free software; see the source for copying conditions. There is NO 11:59:55 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 11:59:55 INFO - Return code: 0 11:59:55 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 11:59:55 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 11:59:55 INFO - checking encoding (cp1252)... 11:59:55 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 11:59:55 INFO - checking Python version (2.7.12) 11:59:55 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 11:59:55 INFO - checking Mercurial version (3.9.1) 11:59:55 INFO - checking Mercurial custom build () 11:59:55 INFO - checking module policy (c) 11:59:55 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 11:59:55 INFO - checking templates (c:\mozilla-build\hg\templates)... 11:59:55 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 11:59:55 INFO - checking commit editor... (notepad) 11:59:55 INFO - checking username (Mozilla Release Engineering ) 11:59:55 INFO - no problems detected 11:59:55 INFO - Return code: 0 11:59:55 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/integration/mozilla-inbound', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src', '--sharebase', 'C:/builds/hg-shared', '--revision', 'c14157737409a119ef1f256fb1cf5aff0df2c465'] 11:59:55 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/integration/mozilla-inbound c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src --sharebase C:/builds/hg-shared --revision c14157737409a119ef1f256fb1cf5aff0df2c465 12:01:35 INFO - 12:01:35 INFO - manifests [ ] 1/67 12:01:35 INFO - 12:01:35 INFO - 12:01:35 INFO - files [ ] 2/301 13m45s 12:01:35 INFO - files [> ] 6/301 4m03s 12:01:35 INFO - files [=> ] 14/301 1m58s 12:01:35 INFO - files [===> ] 26/301 1m15s 12:01:35 INFO - files [=====> ] 33/301 1m07s 12:01:35 INFO - files [=======> ] 46/301 52s 12:01:35 INFO - files [=========> ] 52/301 50s 12:01:35 INFO - files [=========> ] 56/301 53s 12:01:35 INFO - files [===========> ] 67/301 46s 12:01:35 INFO - files [=============> ] 73/301 45s 12:01:35 INFO - files [================> ] 90/301 36s 12:01:35 INFO - files [==================> ] 100/301 33s 12:01:35 INFO - files [===================> ] 106/301 32s 12:01:35 INFO - files [============================> ] 155/301 18s 12:01:35 INFO - files [=============================> ] 156/301 19s 12:01:35 INFO - files [==============================> ] 162/301 19s 12:01:35 INFO - files [==============================> ] 166/301 20s 12:01:35 INFO - files [=================================> ] 178/301 18s 12:01:35 INFO - files [==================================> ] 182/301 19s 12:01:35 INFO - files [==================================> ] 184/301 19s 12:01:35 INFO - files [===================================> ] 187/301 19s 12:01:35 INFO - 12:01:35 INFO - ensuring https://hg.mozilla.org/integration/mozilla-inbound@c14157737409a119ef1f256fb1cf5aff0df2c465 is available at c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src 12:01:35 INFO - (sharing from existing pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29) 12:01:35 INFO - searching for changes 12:01:35 INFO - adding changesets 12:01:35 INFO - adding manifests 12:01:35 INFO - adding file changes 12:01:35 INFO - added 67 changesets with 280 changes to 192 files (-1 heads) 12:23:28 INFO - 12:23:28 INFO - updating [ ] 100/148552 12:23:28 INFO - updating [ ] 200/148552 12:23:28 INFO - updating [ ] 300/148552 1h13m 12:23:28 INFO - updating [ ] 500/148552 54m12s 12:23:28 INFO - updating [ ] 600/148552 59m26s 12:23:28 INFO - updating [ ] 700/148552 1h02m 12:23:28 INFO - updating [ ] 800/148552 1h14m 12:23:28 INFO - updating [ ] 900/148552 1h16m 12:23:28 INFO - updating [ ] 1000/148552 1h11m 12:23:28 INFO - updating [ ] 1100/148552 1h15m 12:23:28 INFO - updating [ ] 1200/148552 1h12m 12:23:28 INFO - updating [ ] 1300/148552 1h13m 12:23:28 INFO - updating [ ] 1400/148552 1h12m 12:23:28 INFO - updating [ ] 1500/148552 1h09m 12:23:28 INFO - updating [ ] 1600/148552 1h06m 12:23:28 INFO - updating [ ] 1800/148552 59m50s 12:23:28 INFO - updating [ ] 1900/148552 58m33s 12:23:28 INFO - updating [ ] 2000/148552 1h02m 12:23:28 INFO - updating [ ] 2100/148552 1h01m 12:23:28 INFO - updating [ ] 2200/148552 59m09s 12:23:28 INFO - updating [ ] 2300/148552 59m51s 12:23:28 INFO - updating [ ] 2400/148552 1h02m 12:23:28 INFO - updating [ ] 2500/148552 1h01m 12:23:28 INFO - updating [ ] 2800/148552 54m45s 12:23:28 INFO - updating [ ] 2900/148552 55m37s 12:23:28 INFO - updating [ ] 3100/148552 53m46s 12:23:28 INFO - updating [ ] 3200/148552 56m34s 12:23:28 INFO - updating [> ] 3300/148552 58m54s 12:23:28 INFO - updating [> ] 3400/148552 59m57s 12:23:28 INFO - updating [> ] 3500/148552 59m06s 12:23:28 INFO - updating [> ] 3600/148552 58m21s 12:23:28 INFO - updating [> ] 3700/148552 58m03s 12:23:28 INFO - updating [> ] 3800/148552 57m55s 12:23:28 INFO - updating [> ] 3900/148552 58m13s 12:23:28 INFO - updating [> ] 4000/148552 57m23s 12:23:28 INFO - updating [> ] 4100/148552 57m01s 12:23:28 INFO - updating [> ] 4200/148552 57m10s 12:23:28 INFO - updating [> ] 4400/148552 55m22s 12:23:28 INFO - updating [> ] 4500/148552 56m32s 12:23:28 INFO - updating [> ] 4700/148552 55m14s 12:23:28 INFO - updating [> ] 4900/148552 54m03s 12:23:28 INFO - updating [> ] 5000/148552 54m09s 12:23:28 INFO - updating [> ] 5100/148552 54m00s 12:23:28 INFO - updating [> ] 5200/148552 54m44s 12:23:28 INFO - updating [> ] 5300/148552 54m35s 12:23:28 INFO - updating [> ] 5400/148552 55m13s 12:23:28 INFO - updating [> ] 5500/148552 55m38s 12:23:28 INFO - updating [> ] 5600/148552 55m29s 12:23:28 INFO - updating [> ] 5800/148552 54m45s 12:23:28 INFO - updating [> ] 5900/148552 54m19s 12:23:28 INFO - updating [> ] 6000/148552 54m08s 12:23:28 INFO - updating [> ] 6100/148552 54m14s 12:23:28 INFO - updating [> ] 6300/148552 53m13s 12:23:28 INFO - updating [> ] 6400/148552 52m52s 12:23:28 INFO - updating [=> ] 6600/148552 51m53s 12:23:28 INFO - updating [=> ] 6700/148552 52m25s 12:23:28 INFO - updating [=> ] 6800/148552 53m45s 12:23:28 INFO - updating [=> ] 7000/148552 55m26s 12:23:28 INFO - updating [=> ] 7100/148552 55m26s 12:23:28 INFO - updating [=> ] 7200/148552 57m14s 12:23:28 INFO - updating [=> ] 7300/148552 56m49s 12:23:28 INFO - updating [=> ] 7500/148552 56m28s 12:23:28 INFO - updating [=> ] 7700/148552 55m28s 12:23:28 INFO - updating [=> ] 7800/148552 55m05s 12:23:28 INFO - updating [=> ] 7900/148552 54m41s 12:23:28 INFO - updating [=> ] 8100/148552 53m52s 12:23:28 INFO - updating [=> ] 8300/148552 53m07s 12:23:28 INFO - updating [=> ] 8600/148552 51m52s 12:23:28 INFO - updating [=> ] 8700/148552 51m40s 12:23:28 INFO - updating [=> ] 8800/148552 51m25s 12:23:28 INFO - updating [=> ] 8900/148552 51m32s 12:23:28 INFO - updating [=> ] 9000/148552 51m23s 12:23:28 INFO - updating [=> ] 9200/148552 50m38s 12:23:28 INFO - updating [=> ] 9300/148552 50m45s 12:23:28 INFO - updating [=> ] 9500/148552 49m58s 12:23:28 INFO - updating [=> ] 9600/148552 49m42s 12:23:28 INFO - updating [==> ] 9700/148552 49m34s 12:23:28 INFO - updating [==> ] 9800/148552 49m48s 12:23:28 INFO - updating [==> ] 10100/148552 48m53s 12:23:28 INFO - updating [==> ] 10300/148552 48m05s 12:23:28 INFO - updating [==> ] 10500/148552 47m34s 12:23:28 INFO - updating [==> ] 10700/148552 46m54s 12:23:28 INFO - updating [==> ] 11000/148552 45m50s 12:23:28 INFO - updating [==> ] 11100/148552 45m48s 12:23:28 INFO - updating [==> ] 11300/148552 45m09s 12:23:28 INFO - updating [==> ] 11400/148552 45m00s 12:23:28 INFO - updating [==> ] 11600/148552 44m30s 12:23:28 INFO - updating [==> ] 11700/148552 44m28s 12:23:28 INFO - updating [==> ] 11900/148552 44m05s 12:23:28 INFO - updating [==> ] 12200/148552 43m40s 12:23:28 INFO - updating [==> ] 12400/148552 43m10s 12:23:28 INFO - updating [==> ] 12500/148552 43m12s 12:23:28 INFO - updating [==> ] 12700/148552 42m45s 12:23:28 INFO - updating [==> ] 12900/148552 42m26s 12:23:28 INFO - updating [===> ] 13000/148552 42m24s 12:23:28 INFO - updating [===> ] 13300/148552 41m43s 12:23:28 INFO - updating [===> ] 13400/148552 41m40s 12:23:28 INFO - updating [===> ] 13700/148552 40m51s 12:23:28 INFO - updating [===> ] 13900/148552 40m26s 12:23:28 INFO - updating [===> ] 14100/148552 40m07s 12:23:28 INFO - updating [===> ] 14400/148552 39m38s 12:23:28 INFO - updating [===> ] 14600/148552 39m26s 12:23:28 INFO - updating [===> ] 14700/148552 39m33s 12:23:28 INFO - updating [===> ] 14900/148552 39m29s 12:23:28 INFO - updating [===> ] 15100/148552 39m10s 12:23:28 INFO - updating [===> ] 15400/148552 38m54s 12:23:28 INFO - updating [===> ] 15600/148552 38m42s 12:23:28 INFO - updating [===> ] 15900/148552 38m05s 12:23:28 INFO - updating [===> ] 16000/148552 38m09s 12:23:28 INFO - updating [===> ] 16100/148552 38m09s 12:23:28 INFO - updating [====> ] 16200/148552 38m01s 12:23:28 INFO - updating [====> ] 16400/148552 37m43s 12:23:28 INFO - updating [====> ] 16500/148552 37m42s 12:23:28 INFO - updating [====> ] 16700/148552 37m22s 12:23:28 INFO - updating [====> ] 16900/148552 37m12s 12:23:28 INFO - updating [====> ] 17100/148552 36m52s 12:23:28 INFO - updating [====> ] 17400/148552 36m21s 12:23:28 INFO - updating [====> ] 17600/148552 36m03s 12:23:28 INFO - updating [====> ] 17800/148552 35m46s 12:23:28 INFO - updating [====> ] 17900/148552 35m41s 12:23:28 INFO - updating [====> ] 18200/148552 35m16s 12:23:28 INFO - updating [====> ] 18300/148552 35m11s 12:23:28 INFO - updating [====> ] 18400/148552 35m06s 12:23:28 INFO - updating [====> ] 18900/148552 34m12s 12:23:28 INFO - updating [====> ] 19000/148552 34m11s 12:23:28 INFO - updating [====> ] 19300/148552 33m45s 12:23:28 INFO - updating [=====> ] 19400/148552 33m40s 12:23:28 INFO - updating [=====> ] 19500/148552 33m37s 12:23:28 INFO - updating [=====> ] 19600/148552 33m33s 12:23:28 INFO - updating [=====> ] 19800/148552 33m16s 12:23:28 INFO - updating [=====> ] 20200/148552 32m53s 12:23:28 INFO - updating [=====> ] 20300/148552 32m50s 12:23:28 INFO - updating [=====> ] 20500/148552 32m38s 12:23:28 INFO - updating [=====> ] 21100/148552 31m40s 12:23:28 INFO - updating [=====> ] 21400/148552 31m17s 12:23:28 INFO - updating [=====> ] 21800/148552 30m44s 12:23:28 INFO - updating [=====> ] 22000/148552 30m31s 12:23:28 INFO - updating [=====> ] 22200/148552 30m18s 12:23:28 INFO - updating [=====> ] 22400/148552 30m07s 12:23:28 INFO - updating [=====> ] 22600/148552 29m59s 12:23:28 INFO - updating [======> ] 22800/148552 30m00s 12:23:28 INFO - updating [======> ] 23000/148552 29m51s 12:23:28 INFO - updating [======> ] 23500/148552 29m12s 12:23:28 INFO - updating [======> ] 23900/148552 28m57s 12:23:28 INFO - updating [======> ] 24100/148552 28m47s 12:23:28 INFO - updating [======> ] 24300/148552 28m40s 12:23:28 INFO - updating [======> ] 24500/148552 28m29s 12:23:28 INFO - updating [======> ] 24700/148552 28m21s 12:23:28 INFO - updating [======> ] 24900/148552 28m10s 12:23:28 INFO - updating [======> ] 25000/148552 28m07s 12:23:28 INFO - updating [======> ] 25200/148552 28m02s 12:23:28 INFO - updating [======> ] 25400/148552 27m53s 12:23:28 INFO - updating [======> ] 25600/148552 27m43s 12:23:28 INFO - updating [======> ] 25800/148552 27m35s 12:23:28 INFO - updating [=======> ] 26000/148552 27m36s 12:23:28 INFO - updating [=======> ] 26200/148552 27m26s 12:23:28 INFO - updating [=======> ] 26300/148552 27m23s 12:23:28 INFO - updating [=======> ] 26500/148552 27m17s 12:23:28 INFO - updating [=======> ] 26600/148552 27m21s 12:23:28 INFO - updating [=======> ] 26700/148552 27m39s 12:23:28 INFO - updating [=======> ] 27100/148552 27m22s 12:23:28 INFO - updating [=======> ] 27400/148552 27m05s 12:23:28 INFO - updating [=======> ] 27600/148552 27m30s 12:23:28 INFO - updating [=======> ] 27700/148552 27m32s 12:23:28 INFO - updating [=======> ] 28000/148552 27m16s 12:23:28 INFO - updating [=======> ] 28100/148552 27m13s 12:23:28 INFO - updating [=======> ] 28300/148552 27m07s 12:23:28 INFO - updating [=======> ] 28600/148552 26m50s 12:23:28 INFO - updating [=======> ] 28800/148552 26m42s 12:23:28 INFO - updating [=======> ] 29000/148552 26m33s 12:23:28 INFO - updating [========> ] 29400/148552 26m11s 12:23:28 INFO - updating [========> ] 29600/148552 26m04s 12:23:28 INFO - updating [========> ] 29900/148552 25m49s 12:23:28 INFO - updating [========> ] 30000/148552 25m53s 12:23:28 INFO - updating [========> ] 30200/148552 25m49s 12:23:28 INFO - updating [========> ] 30500/148552 25m41s 12:23:28 INFO - updating [========> ] 30700/148552 25m35s 12:23:28 INFO - updating [========> ] 31000/148552 25m22s 12:23:28 INFO - updating [========> ] 31300/148552 25m07s 12:23:28 INFO - updating [========> ] 31600/148552 24m53s 12:23:28 INFO - updating [========> ] 31800/148552 24m45s 12:23:28 INFO - updating [========> ] 32100/148552 24m33s 12:23:28 INFO - updating [=========> ] 32400/148552 24m24s 12:23:28 INFO - updating [=========> ] 32600/148552 24m17s 12:23:28 INFO - updating [=========> ] 32900/148552 24m06s 12:23:28 INFO - updating [=========> ] 33000/148552 24m04s 12:23:28 INFO - updating [=========> ] 33300/148552 23m52s 12:23:28 INFO - updating [=========> ] 33500/148552 23m45s 12:23:28 INFO - updating [=========> ] 33700/148552 23m39s 12:23:28 INFO - updating [=========> ] 34100/148552 23m28s 12:23:28 INFO - updating [=========> ] 34200/148552 23m27s 12:23:28 INFO - updating [=========> ] 34500/148552 23m15s 12:23:28 INFO - updating [=========> ] 34900/148552 22m58s 12:23:28 INFO - updating [=========> ] 35000/148552 23m01s 12:23:28 INFO - updating [=========> ] 35300/148552 22m50s 12:23:28 INFO - updating [=========> ] 35500/148552 22m44s 12:23:28 INFO - updating [==========> ] 35800/148552 22m33s 12:23:28 INFO - updating [==========> ] 36000/148552 22m28s 12:23:28 INFO - updating [==========> ] 36400/148552 22m11s 12:23:28 INFO - updating [==========> ] 36600/148552 22m08s 12:23:28 INFO - updating [==========> ] 36700/148552 22m07s 12:23:28 INFO - updating [==========> ] 36800/148552 22m06s 12:23:28 INFO - updating [==========> ] 36900/148552 22m04s 12:23:28 INFO - updating [==========> ] 37100/148552 21m59s 12:23:28 INFO - updating [==========> ] 37600/148552 21m42s 12:23:28 INFO - updating [==========> ] 37800/148552 21m39s 12:23:28 INFO - updating [==========> ] 37900/148552 21m39s 12:23:28 INFO - updating [==========> ] 38100/148552 21m37s 12:23:28 INFO - updating [==========> ] 38300/148552 21m34s 12:23:28 INFO - updating [==========> ] 38400/148552 21m33s 12:23:28 INFO - updating [==========> ] 38700/148552 21m24s 12:23:28 INFO - updating [===========> ] 38800/148552 21m27s 12:23:28 INFO - updating [===========> ] 38900/148552 21m26s 12:23:28 INFO - updating [===========> ] 39000/148552 21m31s 12:23:28 INFO - updating [===========> ] 39100/148552 21m33s 12:23:28 INFO - updating [===========> ] 39200/148552 21m33s 12:23:28 INFO - updating [===========> ] 39300/148552 21m34s 12:23:28 INFO - updating [===========> ] 39400/148552 21m36s 12:23:28 INFO - updating [===========> ] 39500/148552 21m36s 12:23:28 INFO - updating [===========> ] 39700/148552 21m33s 12:23:28 INFO - updating [===========> ] 39800/148552 21m33s 12:23:28 INFO - updating [===========> ] 40100/148552 21m32s 12:23:28 INFO - updating [===========> ] 40200/148552 21m31s 12:23:28 INFO - updating [===========> ] 40300/148552 21m30s 12:23:28 INFO - updating [===========> ] 40500/148552 21m25s 12:23:28 INFO - updating [===========> ] 40700/148552 21m21s 12:23:28 INFO - updating [===========> ] 41000/148552 21m11s 12:23:28 INFO - updating [===========> ] 41100/148552 21m11s 12:23:28 INFO - updating [===========> ] 41300/148552 21m08s 12:23:28 INFO - updating [===========> ] 41500/148552 21m04s 12:23:28 INFO - updating [===========> ] 41600/148552 21m03s 12:23:28 INFO - updating [===========> ] 41800/148552 20m58s 12:23:28 INFO - updating [============> ] 42000/148552 20m53s 12:23:28 INFO - updating [============> ] 42100/148552 20m53s 12:23:28 INFO - updating [============> ] 42200/148552 20m56s 12:23:28 INFO - updating [============> ] 42400/148552 20m51s 12:23:28 INFO - updating [============> ] 42500/148552 20m53s 12:23:28 INFO - updating [============> ] 42600/148552 21m00s 12:23:28 INFO - updating [============> ] 42700/148552 21m01s 12:23:28 INFO - updating [============> ] 42900/148552 20m57s 12:23:28 INFO - updating [============> ] 43000/148552 20m57s 12:23:28 INFO - updating [============> ] 43200/148552 20m51s 12:23:28 INFO - updating [============> ] 43500/148552 20m42s 12:23:28 INFO - updating [============> ] 43800/148552 20m32s 12:23:28 INFO - updating [============> ] 44000/148552 20m30s 12:23:28 INFO - updating [============> ] 44100/148552 20m30s 12:23:28 INFO - updating [============> ] 44200/148552 20m33s 12:23:28 INFO - updating [============> ] 44400/148552 20m29s 12:23:28 INFO - updating [============> ] 44500/148552 20m32s 12:23:28 INFO - updating [============> ] 44700/148552 20m30s 12:23:28 INFO - updating [============> ] 45000/148552 20m26s 12:23:28 INFO - updating [============> ] 45200/148552 20m21s 12:23:28 INFO - updating [=============> ] 45300/148552 20m21s 12:23:28 INFO - updating [=============> ] 45600/148552 20m13s 12:23:28 INFO - updating [=============> ] 45800/148552 20m10s 12:23:28 INFO - updating [=============> ] 46100/148552 20m13s 12:23:28 INFO - updating [=============> ] 46300/148552 20m09s 12:23:28 INFO - updating [=============> ] 46600/148552 20m04s 12:23:28 INFO - updating [=============> ] 46800/148552 20m07s 12:23:28 INFO - updating [=============> ] 46900/148552 20m07s 12:23:28 INFO - updating [=============> ] 47100/148552 20m07s 12:23:28 INFO - updating [=============> ] 47400/148552 20m03s 12:23:28 INFO - updating [=============> ] 47500/148552 20m04s 12:23:28 INFO - updating [=============> ] 47700/148552 19m59s 12:23:28 INFO - updating [=============> ] 47900/148552 19m53s 12:23:28 INFO - updating [=============> ] 48000/148552 19m53s 12:23:28 INFO - updating [=============> ] 48200/148552 19m50s 12:23:28 INFO - updating [=============> ] 48400/148552 19m47s 12:23:28 INFO - updating [==============> ] 48600/148552 19m42s 12:23:28 INFO - updating [==============> ] 49000/148552 19m35s 12:23:28 INFO - updating [==============> ] 49100/148552 19m39s 12:23:28 INFO - updating [==============> ] 49200/148552 19m39s 12:23:28 INFO - updating [==============> ] 49300/148552 19m38s 12:23:28 INFO - updating [==============> ] 49500/148552 19m34s 12:23:28 INFO - updating [==============> ] 49600/148552 19m37s 12:23:28 INFO - updating [==============> ] 49700/148552 19m36s 12:23:28 INFO - updating [==============> ] 50100/148552 19m24s 12:23:28 INFO - updating [==============> ] 50200/148552 19m26s 12:23:28 INFO - updating [==============> ] 50800/148552 19m07s 12:23:28 INFO - updating [==============> ] 51000/148552 19m03s 12:23:28 INFO - updating [==============> ] 51500/148552 18m48s 12:23:28 INFO - updating [===============> ] 51700/148552 18m44s 12:23:28 INFO - updating [===============> ] 52000/148552 18m36s 12:23:28 INFO - updating [===============> ] 52100/148552 18m35s 12:23:28 INFO - updating [===============> ] 52200/148552 18m33s 12:23:28 INFO - updating [===============> ] 52600/148552 18m22s 12:23:28 INFO - updating [===============> ] 53100/148552 18m09s 12:23:28 INFO - updating [===============> ] 53500/148552 17m58s 12:23:28 INFO - updating [===============> ] 53900/148552 17m48s 12:23:28 INFO - updating [===============> ] 54500/148552 17m32s 12:23:28 INFO - updating [===============> ] 54800/148552 17m25s 12:23:28 INFO - updating [================> ] 55100/148552 17m19s 12:23:28 INFO - updating [================> ] 55200/148552 17m20s 12:23:28 INFO - updating [================> ] 55300/148552 17m19s 12:23:28 INFO - updating [================> ] 55400/148552 17m18s 12:23:28 INFO - updating [================> ] 55500/148552 17m21s 12:23:28 INFO - updating [================> ] 55700/148552 17m17s 12:23:28 INFO - updating [================> ] 55800/148552 17m31s 12:23:28 INFO - updating [================> ] 55900/148552 17m34s 12:23:28 INFO - updating [================> ] 56200/148552 17m27s 12:23:28 INFO - updating [================> ] 56400/148552 17m25s 12:23:28 INFO - updating [================> ] 56600/148552 17m25s 12:23:28 INFO - updating [================> ] 56900/148552 17m18s 12:23:28 INFO - updating [================> ] 57100/148552 17m14s 12:23:28 INFO - updating [================> ] 57400/148552 17m09s 12:23:28 INFO - updating [================> ] 57800/148552 17m00s 12:23:28 INFO - updating [================> ] 58100/148552 16m54s 12:23:28 INFO - updating [=================> ] 58500/148552 16m46s 12:23:28 INFO - updating [=================> ] 58800/148552 16m39s 12:23:28 INFO - updating [=================> ] 58900/148552 16m38s 12:23:28 INFO - updating [=================> ] 59300/148552 16m29s 12:23:28 INFO - updating [=================> ] 59600/148552 16m22s 12:23:28 INFO - updating [=================> ] 60000/148552 16m13s 12:23:28 INFO - updating [=================> ] 60300/148552 16m06s 12:23:28 INFO - updating [=================> ] 60700/148552 15m57s 12:23:28 INFO - updating [=================> ] 61000/148552 15m52s 12:23:28 INFO - updating [==================> ] 61500/148552 15m41s 12:23:28 INFO - updating [==================> ] 62100/148552 15m27s 12:23:28 INFO - updating [==================> ] 62500/148552 15m19s 12:23:28 INFO - updating [==================> ] 62800/148552 15m14s 12:23:28 INFO - updating [==================> ] 63000/148552 15m10s 12:23:28 INFO - updating [==================> ] 63400/148552 15m02s 12:23:28 INFO - updating [==================> ] 63700/148552 14m56s 12:23:28 INFO - updating [==================> ] 63900/148552 14m53s 12:23:28 INFO - updating [==================> ] 64000/148552 14m54s 12:23:28 INFO - updating [==================> ] 64100/148552 14m55s 12:23:28 INFO - updating [==================> ] 64200/148552 14m56s 12:23:28 INFO - updating [==================> ] 64400/148552 14m53s 12:23:28 INFO - updating [===================> ] 64600/148552 14m51s 12:23:28 INFO - updating [===================> ] 65100/148552 14m41s 12:23:28 INFO - updating [===================> ] 65200/148552 14m42s 12:23:28 INFO - updating [===================> ] 65500/148552 14m37s 12:23:28 INFO - updating [===================> ] 65700/148552 14m33s 12:23:28 INFO - updating [===================> ] 65800/148552 14m32s 12:23:28 INFO - updating [===================> ] 65900/148552 14m31s 12:23:28 INFO - updating [===================> ] 66100/148552 14m28s 12:23:28 INFO - updating [===================> ] 66500/148552 14m24s 12:23:28 INFO - updating [===================> ] 66600/148552 14m24s 12:23:28 INFO - updating [===================> ] 66800/148552 14m22s 12:23:28 INFO - updating [===================> ] 67200/148552 14m15s 12:23:28 INFO - updating [===================> ] 67500/148552 14m10s 12:23:28 INFO - updating [===================> ] 67700/148552 14m07s 12:23:28 INFO - updating [====================> ] 68000/148552 14m02s 12:23:28 INFO - updating [====================> ] 68500/148552 13m53s 12:23:28 INFO - updating [====================> ] 68900/148552 13m46s 12:23:28 INFO - updating [====================> ] 69000/148552 13m45s 12:23:28 INFO - updating [====================> ] 69500/148552 13m36s 12:23:28 INFO - updating [====================> ] 70300/148552 13m19s 12:23:28 INFO - updating [====================> ] 70700/148552 13m12s 12:23:28 INFO - updating [====================> ] 71000/148552 13m07s 12:23:28 INFO - updating [=====================> ] 71400/148552 13m01s 12:23:28 INFO - updating [=====================> ] 71600/148552 12m58s 12:23:28 INFO - updating [=====================> ] 72100/148552 12m49s 12:23:28 INFO - updating [=====================> ] 72500/148552 12m42s 12:23:28 INFO - updating [=====================> ] 73000/148552 12m33s 12:23:28 INFO - updating [=====================> ] 73300/148552 12m29s 12:23:28 INFO - updating [=====================> ] 73600/148552 12m24s 12:23:28 INFO - updating [=====================> ] 74000/148552 12m18s 12:23:28 INFO - updating [=====================> ] 74200/148552 12m15s 12:23:28 INFO - updating [======================> ] 74500/148552 12m10s 12:23:28 INFO - updating [======================> ] 74900/148552 12m04s 12:23:28 INFO - updating [======================> ] 75100/148552 12m01s 12:23:28 INFO - updating [======================> ] 75500/148552 11m55s 12:23:28 INFO - updating [======================> ] 75800/148552 11m50s 12:23:28 INFO - updating [======================> ] 76000/148552 11m52s 12:23:28 INFO - updating [======================> ] 76200/148552 11m53s 12:23:28 INFO - updating [======================> ] 76300/148552 11m52s 12:23:28 INFO - updating [======================> ] 76600/148552 11m47s 12:23:28 INFO - updating [======================> ] 76900/148552 11m43s 12:23:28 INFO - updating [======================> ] 77200/148552 11m39s 12:23:28 INFO - updating [=======================> ] 77600/148552 11m32s 12:23:28 INFO - updating [=======================> ] 78000/148552 11m26s 12:23:28 INFO - updating [=======================> ] 78400/148552 11m20s 12:23:28 INFO - updating [=======================> ] 78900/148552 11m11s 12:23:28 INFO - updating [=======================> ] 79100/148552 11m09s 12:23:28 INFO - updating [=======================> ] 79200/148552 11m09s 12:23:28 INFO - updating [=======================> ] 79600/148552 11m02s 12:23:28 INFO - updating [=======================> ] 79700/148552 11m02s 12:23:28 INFO - updating [=======================> ] 80200/148552 10m54s 12:23:28 INFO - updating [=======================> ] 80600/148552 10m47s 12:23:28 INFO - updating [========================> ] 81000/148552 10m42s 12:23:28 INFO - updating [========================> ] 81200/148552 10m40s 12:23:28 INFO - updating [========================> ] 81700/148552 10m32s 12:23:28 INFO - updating [========================> ] 82100/148552 10m26s 12:23:28 INFO - updating [========================> ] 82500/148552 10m21s 12:23:28 INFO - updating [========================> ] 82900/148552 10m15s 12:23:28 INFO - updating [========================> ] 83100/148552 10m13s 12:23:28 INFO - updating [========================> ] 83500/148552 10m07s 12:23:28 INFO - updating [========================> ] 83800/148552 10m03s 12:23:28 INFO - updating [=========================> ] 84100/148552 9m59s 12:23:28 INFO - updating [=========================> ] 84400/148552 9m55s 12:23:28 INFO - updating [=========================> ] 84700/148552 9m52s 12:23:28 INFO - updating [=========================> ] 84900/148552 9m50s 12:23:28 INFO - updating [=========================> ] 85100/148552 9m48s 12:23:28 INFO - updating [==========================> ] 85400/148552 9m44s 12:23:28 INFO - updating [==========================> ] 85700/148552 9m40s 12:23:28 INFO - updating [==========================> ] 86000/148552 9m36s 12:23:28 INFO - updating [==========================> ] 86300/148552 9m32s 12:23:28 INFO - updating [==========================> ] 86600/148552 9m28s 12:23:28 INFO - updating [==========================> ] 86900/148552 9m25s 12:23:28 INFO - updating [==========================> ] 87200/148552 9m21s 12:23:28 INFO - updating [==========================> ] 87500/148552 9m17s 12:23:28 INFO - updating [==========================> ] 87600/148552 9m16s 12:23:28 INFO - updating [==========================> ] 87700/148552 9m18s 12:23:28 INFO - updating [==========================> ] 87800/148552 9m19s 12:23:28 INFO - updating [==========================> ] 88000/148552 9m16s 12:23:28 INFO - updating [==========================> ] 88200/148552 9m14s 12:23:28 INFO - updating [===========================> ] 88500/148552 9m10s 12:23:28 INFO - updating [===========================> ] 88700/148552 9m08s 12:23:28 INFO - updating [===========================> ] 89000/148552 9m04s 12:23:28 INFO - updating [===========================> ] 89200/148552 9m03s 12:23:28 INFO - updating [===========================> ] 89400/148552 9m00s 12:23:28 INFO - updating [===========================> ] 89500/148552 9m00s 12:23:28 INFO - updating [===========================> ] 89700/148552 8m58s 12:23:28 INFO - updating [===========================> ] 90000/148552 8m56s 12:23:28 INFO - updating [===========================> ] 90100/148552 8m56s 12:23:28 INFO - updating [===========================> ] 90500/148552 8m52s 12:23:28 INFO - updating [===========================> ] 90700/148552 8m50s 12:23:28 INFO - updating [===========================> ] 90800/148552 8m50s 12:23:28 INFO - updating [===========================> ] 90900/148552 8m49s 12:23:28 INFO - updating [===========================> ] 91000/148552 8m48s 12:23:28 INFO - updating [===========================> ] 91100/148552 8m48s 12:23:28 INFO - updating [===========================> ] 91500/148552 8m43s 12:23:28 INFO - updating [===========================> ] 91600/148552 8m43s 12:23:28 INFO - updating [============================> ] 91900/148552 8m40s 12:23:28 INFO - updating [============================> ] 92000/148552 8m40s 12:23:28 INFO - updating [============================> ] 92300/148552 8m39s 12:23:28 INFO - updating [============================> ] 92400/148552 8m39s 12:23:28 INFO - updating [============================> ] 92500/148552 8m38s 12:23:28 INFO - updating [============================> ] 92900/148552 8m33s 12:23:28 INFO - updating [============================> ] 93200/148552 8m30s 12:23:28 INFO - updating [============================> ] 93600/148552 8m25s 12:23:28 INFO - updating [============================> ] 94000/148552 8m20s 12:23:28 INFO - updating [============================> ] 94100/148552 8m20s 12:23:28 INFO - updating [============================> ] 94200/148552 8m19s 12:23:28 INFO - updating [============================> ] 94300/148552 8m18s 12:23:28 INFO - updating [============================> ] 94400/148552 8m17s 12:23:28 INFO - updating [============================> ] 94500/148552 8m17s 12:23:28 INFO - updating [============================> ] 94700/148552 8m15s 12:23:28 INFO - updating [=============================> ] 94900/148552 8m13s 12:23:28 INFO - updating [=============================> ] 95100/148552 8m11s 12:23:28 INFO - updating [=============================> ] 95300/148552 8m09s 12:23:28 INFO - updating [=============================> ] 95500/148552 8m07s 12:23:28 INFO - updating [=============================> ] 95800/148552 8m04s 12:23:28 INFO - updating [=============================> ] 95900/148552 8m03s 12:23:28 INFO - updating [=============================> ] 96100/148552 8m01s 12:23:28 INFO - updating [=============================> ] 96200/148552 8m01s 12:23:28 INFO - updating [=============================> ] 96400/148552 7m59s 12:23:28 INFO - updating [=============================> ] 96600/148552 7m57s 12:23:28 INFO - updating [=============================> ] 96800/148552 7m55s 12:23:28 INFO - updating [=============================> ] 97000/148552 7m53s 12:23:28 INFO - updating [=============================> ] 97200/148552 7m52s 12:23:28 INFO - updating [=============================> ] 97400/148552 7m50s 12:23:28 INFO - updating [=============================> ] 97600/148552 7m47s 12:23:28 INFO - updating [=============================> ] 97700/148552 7m48s 12:23:28 INFO - updating [=============================> ] 97900/148552 7m46s 12:23:28 INFO - updating [==============================> ] 98100/148552 7m44s 12:23:28 INFO - updating [==============================> ] 98200/148552 7m44s 12:23:28 INFO - updating [==============================> ] 98400/148552 7m42s 12:23:28 INFO - updating [==============================> ] 98600/148552 7m40s 12:23:28 INFO - updating [==============================> ] 98900/148552 7m38s 12:23:28 INFO - updating [==============================> ] 99000/148552 7m38s 12:23:28 INFO - updating [==============================> ] 99100/148552 7m37s 12:23:28 INFO - updating [==============================> ] 99200/148552 7m38s 12:23:28 INFO - updating [==============================> ] 99400/148552 7m36s 12:23:28 INFO - updating [==============================> ] 99600/148552 7m34s 12:23:28 INFO - updating [==============================> ] 99800/148552 7m33s 12:23:28 INFO - updating [==============================> ] 99900/148552 7m32s 12:23:28 INFO - updating [==============================> ] 100100/148552 7m30s 12:23:28 INFO - updating [==============================> ] 100200/148552 7m29s 12:23:28 INFO - updating [==============================> ] 100500/148552 7m26s 12:23:28 INFO - updating [==============================> ] 100700/148552 7m24s 12:23:28 INFO - updating [==============================> ] 100800/148552 7m23s 12:23:28 INFO - updating [==============================> ] 100900/148552 7m23s 12:23:28 INFO - updating [===============================> ] 101300/148552 7m19s 12:23:28 INFO - updating [===============================> ] 101500/148552 7m17s 12:23:28 INFO - updating [===============================> ] 101800/148552 7m14s 12:23:28 INFO - updating [===============================> ] 101900/148552 7m13s 12:23:28 INFO - updating [===============================> ] 102100/148552 7m11s 12:23:28 INFO - updating [===============================> ] 102200/148552 7m10s 12:23:28 INFO - updating [===============================> ] 102500/148552 7m07s 12:23:28 INFO - updating [===============================> ] 102700/148552 7m05s 12:23:28 INFO - updating [===============================> ] 102900/148552 7m03s 12:23:28 INFO - updating [===============================> ] 103100/148552 7m02s 12:23:28 INFO - updating [===============================> ] 103400/148552 6m59s 12:23:28 INFO - updating [===============================> ] 103500/148552 6m59s 12:23:28 INFO - updating [===============================> ] 103600/148552 6m58s 12:23:28 INFO - updating [===============================> ] 103800/148552 6m56s 12:23:28 INFO - updating [===============================> ] 104000/148552 6m54s 12:23:28 INFO - updating [===============================> ] 104100/148552 6m54s 12:23:28 INFO - updating [================================> ] 104400/148552 6m51s 12:23:28 INFO - updating [================================> ] 104600/148552 6m49s 12:23:28 INFO - updating [================================> ] 104800/148552 6m47s 12:23:28 INFO - updating [================================> ] 105100/148552 6m44s 12:23:28 INFO - updating [================================> ] 105400/148552 6m40s 12:23:28 INFO - updating [================================> ] 105500/148552 6m40s 12:23:28 INFO - updating [================================> ] 105600/148552 6m40s 12:23:28 INFO - updating [================================> ] 105800/148552 6m38s 12:23:28 INFO - updating [================================> ] 106100/148552 6m35s 12:23:28 INFO - updating [================================> ] 106200/148552 6m34s 12:23:28 INFO - updating [================================> ] 106500/148552 6m31s 12:23:28 INFO - updating [================================> ] 106600/148552 6m30s 12:23:28 INFO - updating [================================> ] 106900/148552 6m27s 12:23:28 INFO - updating [================================> ] 107200/148552 6m24s 12:23:28 INFO - updating [================================> ] 107400/148552 6m22s 12:23:28 INFO - updating [=================================> ] 107700/148552 6m19s 12:23:28 INFO - updating [=================================> ] 107900/148552 6m17s 12:23:28 INFO - updating [=================================> ] 108100/148552 6m15s 12:23:28 INFO - updating [=================================> ] 108300/148552 6m14s 12:23:28 INFO - updating [=================================> ] 108400/148552 6m13s 12:23:28 INFO - updating [=================================> ] 108600/148552 6m11s 12:23:28 INFO - updating [=================================> ] 108700/148552 6m10s 12:23:28 INFO - updating [=================================> ] 109100/148552 6m05s 12:23:28 INFO - updating [=================================> ] 109300/148552 6m03s 12:23:28 INFO - updating [=================================> ] 109700/148552 5m59s 12:23:28 INFO - updating [=================================> ] 110100/148552 5m55s 12:23:28 INFO - updating [=================================> ] 110400/148552 5m51s 12:23:28 INFO - updating [=================================> ] 110500/148552 5m51s 12:23:28 INFO - updating [==================================> ] 110800/148552 5m47s 12:23:28 INFO - updating [==================================> ] 111000/148552 5m46s 12:23:28 INFO - updating [==================================> ] 111200/148552 5m44s 12:23:28 INFO - updating [==================================> ] 111300/148552 5m43s 12:23:28 INFO - updating [==================================> ] 111500/148552 5m41s 12:23:28 INFO - updating [==================================> ] 111700/148552 5m39s 12:23:28 INFO - updating [==================================> ] 111900/148552 5m39s 12:23:28 INFO - updating [==================================> ] 112100/148552 5m37s 12:23:28 INFO - updating [==================================> ] 112300/148552 5m35s 12:23:28 INFO - updating [==================================> ] 112500/148552 5m33s 12:23:28 INFO - updating [==================================> ] 112600/148552 5m32s 12:23:28 INFO - updating [==================================> ] 112800/148552 5m31s 12:23:28 INFO - updating [==================================> ] 113000/148552 5m29s 12:23:28 INFO - updating [==================================> ] 113200/148552 5m27s 12:23:28 INFO - updating [==================================> ] 113400/148552 5m25s 12:23:28 INFO - updating [==================================> ] 113500/148552 5m25s 12:23:28 INFO - updating [===================================> ] 113900/148552 5m21s 12:23:28 INFO - updating [===================================> ] 114000/148552 5m20s 12:23:28 INFO - updating [===================================> ] 114100/148552 5m19s 12:23:28 INFO - updating [===================================> ] 114400/148552 5m16s 12:23:28 INFO - updating [===================================> ] 114800/148552 5m12s 12:23:28 INFO - updating [===================================> ] 114900/148552 5m11s 12:23:28 INFO - updating [===================================> ] 115300/148552 5m06s 12:23:28 INFO - updating [===================================> ] 115500/148552 5m05s 12:23:28 INFO - updating [===================================> ] 115600/148552 5m05s 12:23:28 INFO - updating [===================================> ] 115700/148552 5m05s 12:23:28 INFO - updating [===================================> ] 115800/148552 5m04s 12:23:28 INFO - updating [===================================> ] 116000/148552 5m02s 12:23:28 INFO - updating [===================================> ] 116300/148552 4m59s 12:23:28 INFO - updating [===================================> ] 116700/148552 4m55s 12:23:28 INFO - updating [====================================> ] 117000/148552 4m52s 12:23:28 INFO - updating [====================================> ] 117200/148552 4m50s 12:23:28 INFO - updating [====================================> ] 117300/148552 4m49s 12:23:28 INFO - updating [====================================> ] 117500/148552 4m47s 12:23:28 INFO - updating [====================================> ] 118100/148552 4m40s 12:23:28 INFO - updating [====================================> ] 118500/148552 4m36s 12:23:28 INFO - updating [====================================> ] 118900/148552 4m32s 12:23:28 INFO - updating [====================================> ] 119100/148552 4m30s 12:23:28 INFO - updating [====================================> ] 119400/148552 4m27s 12:23:28 INFO - updating [====================================> ] 119700/148552 4m24s 12:23:28 INFO - updating [====================================> ] 119800/148552 4m23s 12:23:28 INFO - updating [====================================> ] 120100/148552 4m20s 12:23:28 INFO - updating [=====================================> ] 120400/148552 4m17s 12:23:28 INFO - updating [=====================================> ] 120500/148552 4m16s 12:23:28 INFO - updating [=====================================> ] 121000/148552 4m11s 12:23:28 INFO - updating [=====================================> ] 121400/148552 4m06s 12:23:28 INFO - updating [=====================================> ] 121700/148552 4m03s 12:23:28 INFO - updating [=====================================> ] 122000/148552 4m00s 12:23:28 INFO - updating [=====================================> ] 122300/148552 3m57s 12:23:28 INFO - updating [=====================================> ] 122800/148552 3m52s 12:23:28 INFO - updating [======================================> ] 123400/148552 3m46s 12:23:28 INFO - updating [======================================> ] 123900/148552 3m41s 12:23:28 INFO - updating [======================================> ] 124400/148552 3m36s 12:23:28 INFO - updating [======================================> ] 124500/148552 3m35s 12:23:28 INFO - updating [======================================> ] 124700/148552 3m33s 12:23:28 INFO - updating [======================================> ] 125300/148552 3m27s 12:23:28 INFO - updating [======================================> ] 125600/148552 3m24s 12:23:28 INFO - updating [======================================> ] 125900/148552 3m21s 12:23:28 INFO - updating [======================================> ] 126100/148552 3m19s 12:23:28 INFO - updating [======================================> ] 126400/148552 3m16s 12:23:28 INFO - updating [=======================================> ] 126600/148552 3m15s 12:23:28 INFO - updating [=======================================> ] 127100/148552 3m10s 12:23:28 INFO - updating [=======================================> ] 127500/148552 3m06s 12:23:28 INFO - updating [=======================================> ] 127900/148552 3m02s 12:23:28 INFO - updating [=======================================> ] 128400/148552 2m57s 12:23:28 INFO - updating [=======================================> ] 128600/148552 2m55s 12:23:28 INFO - updating [=======================================> ] 129000/148552 2m51s 12:23:28 INFO - updating [=======================================> ] 129300/148552 2m48s 12:23:28 INFO - updating [========================================> ] 129600/148552 2m45s 12:23:28 INFO - updating [========================================> ] 129900/148552 2m43s 12:23:28 INFO - updating [========================================> ] 130200/148552 2m40s 12:23:28 INFO - updating [========================================> ] 130600/148552 2m37s 12:23:28 INFO - updating [========================================> ] 130900/148552 2m34s 12:23:28 INFO - updating [========================================> ] 131200/148552 2m31s 12:23:28 INFO - updating [========================================> ] 131500/148552 2m28s 12:23:28 INFO - updating [========================================> ] 131800/148552 2m26s 12:23:28 INFO - updating [========================================> ] 132100/148552 2m23s 12:23:28 INFO - updating [========================================> ] 132300/148552 2m21s 12:23:28 INFO - updating [========================================> ] 132600/148552 2m18s 12:23:28 INFO - updating [=========================================> ] 132900/148552 2m16s 12:23:28 INFO - updating [=========================================> ] 133100/148552 2m14s 12:23:28 INFO - updating [=========================================> ] 133200/148552 2m13s 12:23:28 INFO - updating [=========================================> ] 133400/148552 2m11s 12:23:28 INFO - updating [=========================================> ] 133700/148552 2m09s 12:23:28 INFO - updating [=========================================> ] 133900/148552 2m07s 12:23:28 INFO - updating [=========================================> ] 134100/148552 2m05s 12:23:28 INFO - updating [=========================================> ] 134300/148552 2m03s 12:23:28 INFO - updating [=========================================> ] 134600/148552 2m01s 12:23:28 INFO - updating [=========================================> ] 134900/148552 1m58s 12:23:28 INFO - updating [=========================================> ] 135200/148552 1m56s 12:23:28 INFO - updating [=========================================> ] 135600/148552 1m52s 12:23:28 INFO - updating [=========================================> ] 135700/148552 1m51s 12:23:28 INFO - updating [=========================================> ] 135900/148552 1m49s 12:23:28 INFO - updating [==========================================> ] 136200/148552 1m47s 12:23:28 INFO - updating [==========================================> ] 136500/148552 1m44s 12:23:28 INFO - updating [==========================================> ] 136700/148552 1m42s 12:23:28 INFO - updating [==========================================> ] 137100/148552 1m39s 12:23:28 INFO - updating [==========================================> ] 137300/148552 1m37s 12:23:28 INFO - updating [==========================================> ] 137600/148552 1m34s 12:23:28 INFO - updating [==========================================> ] 137800/148552 1m33s 12:23:28 INFO - updating [==========================================> ] 137900/148552 1m32s 12:23:28 INFO - updating [==========================================> ] 138000/148552 1m31s 12:23:28 INFO - updating [==========================================> ] 138200/148552 1m29s 12:23:28 INFO - updating [==========================================> ] 138400/148552 1m28s 12:23:28 INFO - updating [==========================================> ] 138600/148552 1m26s 12:23:28 INFO - updating [==========================================> ] 138900/148552 1m23s 12:23:28 INFO - updating [===========================================> ] 139300/148552 1m20s 12:23:28 INFO - updating [===========================================> ] 139500/148552 1m18s 12:23:28 INFO - updating [===========================================> ] 139700/148552 1m16s 12:23:28 INFO - updating [===========================================> ] 139800/148552 1m15s 12:23:28 INFO - updating [===========================================> ] 139900/148552 1m15s 12:23:28 INFO - updating [===========================================> ] 140100/148552 1m13s 12:23:28 INFO - updating [===========================================> ] 140300/148552 1m11s 12:23:28 INFO - updating [===========================================> ] 140500/148552 1m10s 12:23:28 INFO - updating [===========================================> ] 140800/148552 1m07s 12:23:28 INFO - updating [===========================================> ] 140900/148552 1m06s 12:23:28 INFO - updating [===========================================> ] 141100/148552 1m05s 12:23:28 INFO - updating [===========================================> ] 141200/148552 1m04s 12:23:28 INFO - updating [===========================================> ] 141400/148552 1m02s 12:23:28 INFO - updating [===========================================> ] 141600/148552 1m01s 12:23:28 INFO - updating [===========================================> ] 141700/148552 1m00s 12:23:28 INFO - updating [=============================================> ] 141800/148552 59s 12:23:28 INFO - updating [=============================================> ] 142000/148552 58s 12:23:28 INFO - updating [=============================================> ] 142200/148552 56s 12:23:28 INFO - updating [=============================================> ] 142400/148552 54s 12:23:28 INFO - updating [==============================================> ] 142700/148552 51s 12:23:28 INFO - updating [==============================================> ] 142800/148552 51s 12:23:28 INFO - updating [==============================================> ] 142900/148552 50s 12:23:28 INFO - updating [==============================================> ] 143000/148552 49s 12:23:28 INFO - updating [==============================================> ] 143200/148552 47s 12:23:28 INFO - updating [==============================================> ] 143400/148552 45s 12:23:28 INFO - updating [==============================================> ] 143600/148552 44s 12:23:28 INFO - updating [==============================================> ] 143800/148552 42s 12:23:28 INFO - updating [==============================================> ] 144000/148552 40s 12:23:28 INFO - updating [==============================================> ] 144200/148552 38s 12:23:28 INFO - updating [==============================================> ] 144600/148552 35s 12:23:28 INFO - updating [==============================================> ] 144800/148552 33s 12:23:28 INFO - updating [==============================================> ] 145100/148552 31s 12:23:28 INFO - updating [==============================================> ] 145300/148552 29s 12:23:28 INFO - updating [==============================================> ] 145500/148552 27s 12:23:28 INFO - updating [===============================================> ] 145800/148552 24s 12:23:28 INFO - updating [===============================================> ] 145900/148552 24s 12:23:28 INFO - updating [===============================================> ] 146200/148552 21s 12:23:28 INFO - updating [===============================================> ] 146400/148552 19s 12:23:28 INFO - updating [===============================================> ] 146700/148552 17s 12:23:28 INFO - updating [===============================================> ] 146900/148552 15s 12:23:28 INFO - updating [===============================================> ] 147100/148552 13s 12:23:28 INFO - updating [===============================================> ] 147200/148552 12s 12:23:28 INFO - updating [===============================================> ] 147400/148552 11s 12:23:28 INFO - updating [===============================================> ] 147600/148552 09s 12:23:28 INFO - updating [===============================================> ] 147900/148552 06s 12:23:28 INFO - updating [===============================================> ] 148100/148552 04s 12:23:28 INFO - updating [===============================================> ] 148200/148552 04s 12:23:28 INFO - updating [===============================================> ] 148400/148552 02s 12:23:28 INFO - updating [================================================>] 148552/148552 01s 12:23:28 INFO - 12:23:28 INFO - 148552 files updated, 0 files merged, 0 files removed, 0 files unresolved 12:23:28 INFO - updated to c14157737409a119ef1f256fb1cf5aff0df2c465 12:23:28 INFO - Return code: 0 12:23:28 INFO - Setting buildbot property comments to Bug 1201124: Protect SIMD on arm to prevent bustage; r=me 12:23:28 INFO - Writing buildbot properties ['comments'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\comments 12:23:28 INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\comments 12:23:28 INFO - Contents: 12:23:28 INFO - comments:Bug 1201124: Protect SIMD on arm to prevent bustage; r=me 12:23:28 INFO - Setting buildbot property got_revision to c14157737409 12:23:28 INFO - Writing buildbot properties ['got_revision'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\got_revision 12:23:28 INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\got_revision 12:23:28 INFO - Contents: 12:23:28 INFO - got_revision:c14157737409 12:23:28 INFO - Running post-action listener: influxdb_recording_post_action 12:23:28 INFO - Resetting dropped connection: goldiewilson-onepointtwentyone-1.c.influxdb.com c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Lib\site-packages\requests\packages\urllib3\util\ssl_.py:90: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. For more information, see https://urllib3.readthedocs.org/en/latest/security.html#insecureplatformwarning. InsecurePlatformWarning 12:23:28 INFO - [mozharness: 2016-10-14 19:23:28.859000Z] Finished checkout-sources step (success) 12:23:28 INFO - [mozharness: 2016-10-14 19:23:28.859000Z] Skipping setup-mock step. 12:23:28 INFO - [mozharness: 2016-10-14 19:23:28.859000Z] Running build step. 12:23:28 INFO - Running pre-action listener: influxdb_recording_pre_action 12:23:28 INFO - Running main action method: build 12:23:28 INFO - removing old packages... 12:23:28 INFO - There wasn't any old packages to remove. 12:23:28 INFO - Using in-tree mozconfig 12:23:28 INFO - Reading from file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\browser/config/mozconfigs/win32/debug 12:23:28 INFO - Contents: 12:23:28 INFO - . "$topsrcdir/build/mozconfig.win-common" 12:23:28 INFO - MOZ_AUTOMATION_L10N_CHECK=0 12:23:28 INFO - . "$topsrcdir/browser/config/mozconfigs/common" 12:23:28 INFO - 12:23:28 INFO - ac_add_options --enable-debug 12:23:28 INFO - ac_add_options --enable-dmd 12:23:28 INFO - ac_add_options --enable-profiling # needed for --enable-dmd to work on Windows 12:23:28 INFO - ac_add_options --enable-verify-mar 12:23:28 INFO - ac_add_options --enable-require-all-d3dc-versions 12:23:28 INFO - 12:23:28 INFO - # Needed to enable breakpad in application.ini 12:23:28 INFO - export MOZILLA_OFFICIAL=1 12:23:28 INFO - 12:23:28 INFO - # Enable Telemetry 12:23:28 INFO - export MOZ_TELEMETRY_REPORTING=1 12:23:28 INFO - 12:23:28 INFO - . $topsrcdir/build/win32/mozconfig.vs-latest 12:23:28 INFO - 12:23:28 INFO - # Package js shell. 12:23:28 INFO - export MOZ_PACKAGE_JSSHELL=1 12:23:28 INFO - 12:23:28 INFO - ac_add_options --with-branding=browser/branding/nightly 12:23:28 INFO - 12:23:28 INFO - . "$topsrcdir/build/mozconfig.rust" 12:23:28 INFO - 12:23:28 INFO - . "$topsrcdir/build/mozconfig.common.override" 12:23:28 INFO - . "$topsrcdir/build/mozconfig.cache" 12:23:28 INFO - mozconfig content: 12:23:28 INFO - . "$topsrcdir/build/mozconfig.win-common" 12:23:28 INFO - MOZ_AUTOMATION_L10N_CHECK=0 12:23:28 INFO - . "$topsrcdir/browser/config/mozconfigs/common" 12:23:28 INFO - 12:23:28 INFO - ac_add_options --enable-debug 12:23:28 INFO - ac_add_options --enable-dmd 12:23:28 INFO - ac_add_options --enable-profiling # needed for --enable-dmd to work on Windows 12:23:28 INFO - ac_add_options --enable-verify-mar 12:23:28 INFO - ac_add_options --enable-require-all-d3dc-versions 12:23:28 INFO - 12:23:28 INFO - # Needed to enable breakpad in application.ini 12:23:28 INFO - export MOZILLA_OFFICIAL=1 12:23:28 INFO - 12:23:28 INFO - # Enable Telemetry 12:23:28 INFO - export MOZ_TELEMETRY_REPORTING=1 12:23:28 INFO - 12:23:28 INFO - . $topsrcdir/build/win32/mozconfig.vs-latest 12:23:28 INFO - 12:23:28 INFO - # Package js shell. 12:23:28 INFO - export MOZ_PACKAGE_JSSHELL=1 12:23:28 INFO - 12:23:28 INFO - ac_add_options --with-branding=browser/branding/nightly 12:23:28 INFO - 12:23:28 INFO - . "$topsrcdir/build/mozconfig.rust" 12:23:28 INFO - 12:23:28 INFO - . "$topsrcdir/build/mozconfig.common.override" 12:23:28 INFO - . "$topsrcdir/build/mozconfig.cache" 12:23:28 INFO - Copying c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\browser/config/mozconfigs/win32/debug to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\.mozconfig 12:23:28 INFO - ['sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\tools\\scripts\\tooltool\\tooltool_wrapper.sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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'] 12:23:28 INFO - Running command: ['sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\tools\\scripts\\tooltool\\tooltool_wrapper.sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\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\m-in-w32-d-0000000000000000000\build\src 12:23:28 INFO - Copy/paste: sh c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\tools\scripts\tooltool\tooltool_wrapper.sh c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\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 12:23:29 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\browser/config/tooltool-manifests/win32/releng.manifest content 12:23:29 INFO - ======================================================= 12:23:29 INFO - [ 12:23:29 INFO - { 12:23:29 INFO - "size": 266240, 12:23:29 INFO - "digest": "bb345b0e700ffab4d09436981f14b5de84da55a3f18a7f09ebc4364a4488acdeab8d46f447b12ac70f2da1444a68b8ce8b8675f0dae2ccf845e966d1df0f0869", 12:23:29 INFO - "algorithm": "sha512", 12:23:29 INFO - "filename": "mozmake.exe" 12:23:29 INFO - }, 12:23:29 INFO - { 12:23:29 INFO - "version": "rustc 1.11.0 (9b21dcd6a 2016-08-15) repack", 12:23:29 INFO - "size": 86199150, 12:23:29 INFO - "digest": "fec209dc85a098817c892655fbfda2bd6961199b1c28422994a50daddcb219608673b87dde30b3380555400cf4484863a12d431a6a25ef01cb9b1b32bef48f8b", 12:23:29 INFO - "algorithm": "sha512", 12:23:29 INFO - "filename": "rustc.tar.bz2", 12:23:29 INFO - "unpack": true 12:23:29 INFO - }, 12:23:29 INFO - { 12:23:29 INFO - "version": "cargo 0.13.0-nightly (e713e7f 2016-08-31)", 12:23:29 INFO - "size": 2402000, 12:23:29 INFO - "digest": "56f12f7ac437742ed717ce0ccfb0b4134160948e45d73016e48d9033567e5b01a171ac95dd7965eb007702c31da73274b5913281655f461f611ddeee37181ecc", 12:23:29 INFO - "algorithm": "sha512", 12:23:29 INFO - "filename": "cargo.tar.bz2", 12:23:29 INFO - "unpack": true 12:23:29 INFO - }, 12:23:29 INFO - { 12:23:29 INFO - "size": 167175, 12:23:29 INFO - "digest": "0b71a936edf5bd70cf274aaa5d7abc8f77fe8e7b5593a208f805cc9436fac646b9c4f0b43c2b10de63ff3da671497d35536077ecbc72dba7f8159a38b580f831", 12:23:29 INFO - "algorithm": "sha512", 12:23:29 INFO - "filename": "sccache.tar.bz2", 12:23:29 INFO - "unpack": true 12:23:29 INFO - }, 12:23:29 INFO - { 12:23:29 INFO - "version": "Visual Studio 2015 Update 3 14.0.25425.01 / SDK 10.0.14393.0", 12:23:29 INFO - "size": 326656969, 12:23:29 INFO - "digest": "babc414ffc0457d27f5a1ed24a8e4873afbe2f1c1a4075469a27c005e1babc3b2a788f643f825efedff95b79686664c67ec4340ed535487168a3482e68559bc7", 12:23:29 INFO - "algorithm": "sha512", 12:23:29 INFO - "filename": "vs2015u3.zip", 12:23:29 INFO - "unpack": true 12:23:29 INFO - } 12:23:29 INFO - ] 12:23:29 INFO - ======================================================= 12:23:29 INFO - Fetching... 12:23:33 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\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch'] 12:23:33 INFO - INFO - File mozmake.exe not present in local cache folder c:/builds/tooltool_cache 12:23:33 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 12:23:33 INFO - INFO - File mozmake.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\tmplxwnsm 12:23:33 INFO - INFO - File rustc.tar.bz2 not present in local cache folder c:/builds/tooltool_cache 12:23:33 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 12:23:38 INFO - INFO - File rustc.tar.bz2 fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\tmpjdpl1j 12:23:46 INFO - INFO - File cargo.tar.bz2 not present in local cache folder c:/builds/tooltool_cache 12:23:46 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 12:23:47 INFO - INFO - File cargo.tar.bz2 fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\tmpao8rag 12:23:47 INFO - INFO - File sccache.tar.bz2 not present in local cache folder c:/builds/tooltool_cache 12:23:47 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 12:23:48 INFO - INFO - File sccache.tar.bz2 fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\tmpbod1od 12:23:48 INFO - INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache 12:23:48 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 12:23:53 INFO - INFO - File vs2015u3.zip fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\tmpcracsh 12:24:03 INFO - INFO - File integrity verified, renaming tmplxwnsm to mozmake.exe 12:24:03 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 12:24:03 INFO - INFO - Local cache c:/builds/tooltool_cache updated with mozmake.exe 12:24:11 INFO - INFO - File integrity verified, renaming tmpjdpl1j to rustc.tar.bz2 12:24:11 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 12:24:12 INFO - INFO - Local cache c:/builds/tooltool_cache updated with rustc.tar.bz2 12:24:12 INFO - INFO - File integrity verified, renaming tmpao8rag to cargo.tar.bz2 12:24:12 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 12:24:13 INFO - INFO - Local cache c:/builds/tooltool_cache updated with cargo.tar.bz2 12:24:13 INFO - INFO - File integrity verified, renaming tmpbod1od to sccache.tar.bz2 12:24:13 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 12:24:13 INFO - INFO - Local cache c:/builds/tooltool_cache updated with sccache.tar.bz2 12:24:48 INFO - INFO - File integrity verified, renaming tmpcracsh to vs2015u3.zip 12:24:48 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 12:25:14 INFO - INFO - Local cache c:/builds/tooltool_cache updated with vs2015u3.zip 12:25:24 INFO - INFO - untarring "rustc.tar.bz2" 12:26:59 INFO - INFO - untarring "cargo.tar.bz2" 12:27:00 INFO - INFO - untarring "sccache.tar.bz2" 12:27:05 INFO - INFO - unzipping "vs2015u3.zip" 12:28:33 INFO - WARNING: Timeout (300) exceeded, killing process 4044 12:29: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\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch'], 300, None, None, False, True), kwargs: {}, attempt #2 12:29: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\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch'] 12:30:01 INFO - INFO - rm tree: rustc 12:30:01 INFO - INFO - untarring "rustc.tar.bz2" 12:30:26 INFO - INFO - rm tree: cargo 12:30:27 INFO - INFO - untarring "cargo.tar.bz2" 12:30:27 INFO - INFO - rm tree: sccache 12:30:27 INFO - INFO - untarring "sccache.tar.bz2" 12:30:28 INFO - INFO - rm tree: vs2015u3 12:30:31 INFO - INFO - unzipping "vs2015u3.zip" 12:32:02 INFO - Return code: 0 12:32:02 INFO - mkdir: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\.mozbuild 12:32:02 INFO - Using buildbot properties: 12:32:02 INFO - { 12:32:02 INFO - "project": "", 12:32:02 INFO - "product": "firefox", 12:32:02 INFO - "script_repo_revision": "production", 12:32:02 INFO - "scheduler": "mozilla-inbound-firefox", 12:32:02 INFO - "repository": "", 12:32:02 INFO - "buildername": "WINNT 5.2 mozilla-inbound leak test build", 12:32:02 INFO - "buildid": "20161014113936", 12:32:02 INFO - "basedir": "/c/builds/moz2_slave/m-in-w32-d-0000000000000000000", 12:32:02 INFO - "buildnumber": 2700, 12:32:02 INFO - "platform": "win32-debug", 12:32:02 INFO - "master": "http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/", 12:32:02 INFO - "branch": "mozilla-inbound", 12:32:02 INFO - "revision": "c14157737409a119ef1f256fb1cf5aff0df2c465", 12:32:02 INFO - "slavename": "b-2008-spot-168", 12:32:02 INFO - "builduid": "3524caeb2b2143e09aa5b0a8e455ffef", 12:32:02 INFO - "repo_path": "integration/mozilla-inbound" 12:32:02 INFO - } 12:32:02 INFO - Copying c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\buildprops.json to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\buildprops.json 12:32:02 INFO - Running command: ['c:\\mozilla-build\\python27\\python.exe', 'mach', '--log-no-times', 'build', '-v'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src 12:32:02 INFO - Copy/paste: c:\mozilla-build\python27\python.exe mach --log-no-times build -v 12:32:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 12:32:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 12:32:02 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 12:32:02 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 12:32:02 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 12:32:02 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 12:32:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 12:32:02 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 12:32:02 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 12:32:02 INFO - 'COMPUTERNAME': 'B-2008-SPOT-168', 12:32:02 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 12:32:02 INFO - 'CVS_RSH': 'ssh', 12:32:02 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 12:32:02 INFO - 'EDITOR': 'emacs.exe', 12:32:02 INFO - 'FP_NO_HOST_CHECK': 'NO', 12:32:02 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 12:32:02 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 12:32:02 INFO - 'HOME': 'c:/Users/cltbld', 12:32:02 INFO - 'HOMEDRIVE': 'C:', 12:32:02 INFO - 'HOMEPATH': '\\', 12:32:02 INFO - 'HOSTTYPE': 'i686', 12:32:02 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 12:32:02 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 12:32:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 12:32:02 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 12:32:02 INFO - 'LOGNAME': 'cltbld', 12:32:02 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-168', 12:32:02 INFO - 'MACHTYPE': 'i686-pc-msys', 12:32:02 INFO - 'MAKE_MODE': 'unix', 12:32:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 12:32:02 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild', 12:32:02 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 12:32:02 INFO - 'MOZ_AUTOMATION': '1', 12:32:02 INFO - 'MOZ_BUILD_DATE': '20161014113936', 12:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:02 INFO - 'MOZ_OBJDIR': 'obj-firefox', 12:32:02 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', 12:32:02 INFO - 'MOZ_SIGN_CMD': 'python c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\m-in-w32-d-0000000000000000000\\\\\\\\build\\\\\\\\tools\\\\\\\\release\\\\\\\\signing\\\\\\\\signtool.py --cachedir c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\m-in-w32-d-0000000000000000000\\\\\\\\build\\\\\\\\signing_cache -t c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\m-in-w32-d-0000000000000000000\\\\\\\\token -n c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\m-in-w32-d-0000000000000000000\\\\\\\\nonce -c c:\\\\\\\\builds\\\\\\\\moz2_slave\\\\\\\\m-in-w32-d-0000000000000000000\\\\\\\\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', 12:32:02 INFO - 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/integration/mozilla-inbound', 12:32:02 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 12:32:02 INFO - 'MSYSTEM': 'MINGW32', 12:32:02 INFO - 'NUMBER_OF_PROCESSORS': '8', 12:32:02 INFO - 'OLDPWD': 'c:/Users/cltbld', 12:32:02 INFO - 'OS': 'Windows_NT', 12:32:02 INFO - 'OSTYPE': 'msys', 12:32:02 INFO - 'OS_VERSION': '2008', 12:32:02 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', 12:32:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 12:32:02 INFO - 'POST_UPLOAD_CMD': 'post_upload.py --tinderbox-builds-dir mozilla-inbound-win32-debug -p firefox -i 20161014113936 --revision c14157737409a119ef1f256fb1cf5aff0df2c465 --release-to-tinderbox-dated-builds', 12:32:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 12:32:02 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 12:32:02 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 12:32:02 INFO - 'PROCESSOR_LEVEL': '6', 12:32:02 INFO - 'PROCESSOR_REVISION': '3f02', 12:32:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 12:32:02 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 12:32:02 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 12:32:02 INFO - 'PROGRAMW6432': 'C:\\Program Files', 12:32:02 INFO - 'PROMPT': '$P$G', 12:32:02 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json', 12:32:02 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 12:32:02 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 12:32:02 INFO - 'PUBLIC': 'C:\\Users\\Public', 12:32:02 INFO - 'PWD': 'c:/Users/cltbld', 12:32:02 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 12:32:02 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 12:32:02 INFO - 'SHLVL': '1', 12:32:02 INFO - 'SSH_AGENT_PID': '3976', 12:32:02 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920', 12:32:02 INFO - 'SYSTEMDRIVE': 'C:', 12:32:02 INFO - 'SYSTEMROOT': 'C:\\Windows', 12:32:02 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 12:32:02 INFO - 'TERM': 'cygwin', 12:32:02 INFO - 'TINDERBOX_OUTPUT': '1', 12:32:02 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 12:32:02 INFO - 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 12:32:02 INFO - 'TOOLTOOL_HOME': '/c/builds', 12:32:02 INFO - 'UPLOAD_HOST': 'upload.ffxbld.productdelivery.prod.mozaws.net', 12:32:02 INFO - 'UPLOAD_SSH_KEY': '/c/Users/cltbld/.ssh/ffxbld_rsa', 12:32:02 INFO - 'UPLOAD_TO_TEMP': '1', 12:32:02 INFO - 'UPLOAD_USER': 'ffxbld', 12:32:02 INFO - 'USERDOMAIN': 'B-2008-SPOT-168', 12:32:02 INFO - 'USERNAME': 'cltbld', 12:32:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 12:32:02 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 12:32:02 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 12:32:02 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 12:32:02 INFO - 'WINDIR': 'C:\\Windows', 12:32:02 INFO - 'WINDOWS_TRACING_FLAGS': '3', 12:32:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 12:32:02 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 12:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 12:32:02 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 12:32:02 INFO - Calling ['c:\\mozilla-build\\python27\\python.exe', 'mach', '--log-no-times', 'build', '-v'] with output_timeout 4800 12:33:10 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\mozmake.EXE -f client.mk -s configure 12:33:20 INFO - client.mk:202: c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/obj-firefox/.mozconfig.mk: No such file or directory 12:33:22 INFO - Clobber not needed. 12:33:24 INFO - Generating c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/configure 12:33:24 INFO - Generating c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/js/src/configure 12:33:24 INFO - cd c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/obj-firefox 12:33:24 INFO - c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/configure 12:33:25 INFO - Creating Python environment 12:33:40 INFO - New python executable in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\Scripts\python2.7.exe 12:33:40 INFO - Also creating executable in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\Scripts\python.exe 12:33:40 INFO - Installing setuptools, pip, wheel...done. 12:34:06 INFO - running build_ext 12:34:06 INFO - building 'psutil._psutil_windows' extension 12:34:06 INFO - creating build 12:34:06 INFO - creating build\temp.win32-2.7 12:34:06 INFO - creating build\temp.win32-2.7\Release 12:34:06 INFO - creating build\temp.win32-2.7\Release\psutil 12:34:06 INFO - creating build\temp.win32-2.7\Release\psutil\arch 12:34:06 INFO - creating build\temp.win32-2.7\Release\psutil\arch\windows 12:34:06 INFO - c:\Program Files (x86)\Microsoft Visual Studio 10.0\VC\BIN\cl.exe /c /nologo /Ox /MD /W3 /GS- /DNDEBUG -DPSUTIL_VERSION=311 -D_WIN32_WINNT=0x0601 -D_AVAIL_WINVER_=0x0601 -D_CRT_SECURE_NO_WARNINGS -DPSAPI_VERSION=1 -Ic:\mozilla-build\python27\include -Ic:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\PC /Tcpsutil/_psutil_windows.c /Fobuild\temp.win32-2.7\Release\psutil/_psutil_windows.obj 12:34:06 INFO - _psutil_windows.c 12:34:06 INFO - c:\Program Files (x86)\Microsoft Visual Studio 10.0\VC\BIN\cl.exe /c /nologo /Ox /MD /W3 /GS- /DNDEBUG -DPSUTIL_VERSION=311 -D_WIN32_WINNT=0x0601 -D_AVAIL_WINVER_=0x0601 -D_CRT_SECURE_NO_WARNINGS -DPSAPI_VERSION=1 -Ic:\mozilla-build\python27\include -Ic:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\PC /Tcpsutil/_psutil_common.c /Fobuild\temp.win32-2.7\Release\psutil/_psutil_common.obj 12:34:06 INFO - _psutil_common.c 12:34:06 INFO - c:\Program Files (x86)\Microsoft Visual Studio 10.0\VC\BIN\cl.exe /c /nologo /Ox /MD /W3 /GS- /DNDEBUG -DPSUTIL_VERSION=311 -D_WIN32_WINNT=0x0601 -D_AVAIL_WINVER_=0x0601 -D_CRT_SECURE_NO_WARNINGS -DPSAPI_VERSION=1 -Ic:\mozilla-build\python27\include -Ic:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\PC /Tcpsutil/arch/windows/process_info.c /Fobuild\temp.win32-2.7\Release\psutil/arch/windows/process_info.obj 12:34:06 INFO - process_info.c 12:34:06 INFO - c:\Program Files (x86)\Microsoft Visual Studio 10.0\VC\BIN\cl.exe /c /nologo /Ox /MD /W3 /GS- /DNDEBUG -DPSUTIL_VERSION=311 -D_WIN32_WINNT=0x0601 -D_AVAIL_WINVER_=0x0601 -D_CRT_SECURE_NO_WARNINGS -DPSAPI_VERSION=1 -Ic:\mozilla-build\python27\include -Ic:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\PC /Tcpsutil/arch/windows/process_handles.c /Fobuild\temp.win32-2.7\Release\psutil/arch/windows/process_handles.obj 12:34:06 INFO - process_handles.c 12:34:06 INFO - c:\Program Files (x86)\Microsoft Visual Studio 10.0\VC\BIN\cl.exe /c /nologo /Ox /MD /W3 /GS- /DNDEBUG -DPSUTIL_VERSION=311 -D_WIN32_WINNT=0x0601 -D_AVAIL_WINVER_=0x0601 -D_CRT_SECURE_NO_WARNINGS -DPSAPI_VERSION=1 -Ic:\mozilla-build\python27\include -Ic:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\PC /Tcpsutil/arch/windows/security.c /Fobuild\temp.win32-2.7\Release\psutil/arch/windows/security.obj 12:34:06 INFO - security.c 12:34:06 INFO - c:\Program Files (x86)\Microsoft Visual Studio 10.0\VC\BIN\cl.exe /c /nologo /Ox /MD /W3 /GS- /DNDEBUG -DPSUTIL_VERSION=311 -D_WIN32_WINNT=0x0601 -D_AVAIL_WINVER_=0x0601 -D_CRT_SECURE_NO_WARNINGS -DPSAPI_VERSION=1 -Ic:\mozilla-build\python27\include -Ic:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\PC /Tcpsutil/arch/windows/inet_ntop.c /Fobuild\temp.win32-2.7\Release\psutil/arch/windows/inet_ntop.obj 12:34:06 INFO - inet_ntop.c 12:34:06 INFO - creating build\lib.win32-2.7 12:34:06 INFO - creating build\lib.win32-2.7\psutil 12:34:06 INFO - c:\Program Files (x86)\Microsoft Visual Studio 10.0\VC\BIN\link.exe /DLL /nologo /INCREMENTAL:NO /LIBPATH:c:\mozilla-build\python27\Libs /LIBPATH:c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\libs /LIBPATH:c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\obj-firefox\_virtualenv\PCbuild psapi.lib kernel32.lib advapi32.lib shell32.lib netapi32.lib iphlpapi.lib wtsapi32.lib ws2_32.lib /EXPORT:init_psutil_windows build\temp.win32-2.7\Release\psutil/_psutil_windows.obj build\temp.win32-2.7\Release\psutil/_psutil_common.obj build\temp.win32-2.7\Release\psutil/arch/windows/process_info.obj build\temp.win32-2.7\Release\psutil/arch/windows/process_handles.obj build\temp.win32-2.7\Release\psutil/arch/windows/security.obj build\temp.win32-2.7\Release\psutil/arch/windows/inet_ntop.obj /OUT:build\lib.win32-2.7\psutil\_psutil_windows.pyd /IMPLIB:build\temp.win32-2.7\Release\psutil\_psutil_windows.lib /MANIFESTFILE:build\temp.win32-2.7\Release\psutil\_psutil_windows.pyd.manifest 12:34:06 INFO - Creating library build\temp.win32-2.7\Release\psutil\_psutil_windows.lib and object build\temp.win32-2.7\Release\psutil\_psutil_windows.exp 12:34:06 INFO - copying build\lib.win32-2.7\psutil\_psutil_windows.pyd -> psutil 12:34:06 INFO - c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\virtualenv.py:376: UserWarning: Hacking environment to allow binary Python extensions to build. You can make this warning go away by installing Visual Studio 2008. You can download the Express Edition installer from http://go.microsoft.com/?linkid=7729279 12:34:06 INFO - warnings.warn('Hacking environment to allow binary Python ' 12:34:06 INFO - Reexecuting in the virtualenv 12:34:06 INFO - Adding configure options from c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\.mozconfig 12:34:06 INFO - --enable-crashreporter 12:34:06 INFO - --enable-release 12:34:06 INFO - --enable-js-shell 12:34:06 INFO - --enable-debug 12:34:06 INFO - --enable-dmd 12:34:06 INFO - --enable-profiling 12:34:06 INFO - --enable-verify-mar 12:34:06 INFO - --enable-require-all-d3dc-versions 12:34:06 INFO - --with-branding=browser/branding/nightly 12:34:06 INFO - --enable-rust 12:34:06 INFO - --with-compiler-wrapper=python2.7 c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/sccache/sccache.py 12:34:06 INFO - MOZ_PACKAGE_JSSHELL=1 12:34:06 INFO - _DEPEND_CFLAGS=-deps$(MDDEPDIR)/$(@F).pp 12:34:06 INFO - MOZILLA_OFFICIAL=1 12:34:06 INFO - MOZ_TELEMETRY_REPORTING=1 12:34:06 INFO - SOCORRO_SYMBOL_UPLOAD_TOKEN_FILE=c:/builds/crash-stats-api.token 12:34:06 INFO - PATH=/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/redist/x86/Microsoft.VC140.CRT:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/redist/x64/Microsoft.VC140.CRT:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/Redist/ucrt/DLLs/x86:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/Redist/ucrt/DLLs/x64:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/bin/amd64_x86:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/bin/amd64:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/bin:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/bin/x86:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/bin/x64:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/DIA SDK/bin:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/redist/x86/Microsoft.VC140.CRT:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/redist/x64/Microsoft.VC140.CRT:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/Redist/ucrt/DLLs/x86:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/Redist/ucrt/DLLs/x64:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/bin/amd64_x86:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/bin/amd64:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/bin:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/bin/x86:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/bin/x64:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/DIA SDK/bin:/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:/usr/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:.:/usr/local/bin:/usr/mingw/bin:/usr/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:/usr/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:/usr/mingw/bin:/usr/bin:/usr/local/bin:.:/usr/local/bin:/c/mozilla-build/moztools-x64/bin:/c/mozilla-build/vim/vim72 12:34:06 INFO - WIN_UCRT_REDIST_DIR=/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/Redist/ucrt/DLLs/x86 12:34:06 INFO - INCLUDE=/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/include:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/atlmfc/include:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/Include/10.0.14393.0/ucrt:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/Include/10.0.14393.0/shared:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/Include/10.0.14393.0/um:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/Include/10.0.14393.0/winrt:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/DIA SDK/include 12:34:06 INFO - LIB=/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/lib:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/atlmfc/lib:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/lib/10.0.14393.0/ucrt/x86:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/SDK/lib/10.0.14393.0/um/x86:/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/DIA SDK/lib 12:34:06 INFO - WIN32_REDIST_DIR=/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3/VC/redist/x86/Microsoft.VC140.CRT 12:34:06 INFO - CARGO=c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/cargo/bin/cargo 12:34:06 INFO - MOZ_ADDON_SIGNING=1 12:34:06 INFO - MOZ_REQUIRE_SIGNING=0 12:34:06 INFO - VSPATH=/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3 12:34:06 INFO - TOOLTOOL_DIR=c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src 12:34:06 INFO - VSWINPATH=c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/vs2015u3 12:34:06 INFO - RUSTC=c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/rustc/bin/rustc 12:34:07 INFO - checking for a shell... C:/mozilla-build/msys/bin/sh.exe 12:34:07 INFO - checking for host system type... c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/build/moz.configure/../autoconf/config.guess: line 35: sed: No such file or directory 12:34:07 INFO - c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/build/moz.configure/../autoconf/config.guess: line 1408: cat: No such file or directory 12:34:07 INFO - Traceback (most recent call last): 12:34:07 INFO - File "c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/configure.py", line 107, in 12:34:07 INFO - sys.exit(main(sys.argv)) 12:34:07 INFO - File "c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/configure.py", line 26, in main 12:34:07 INFO - sandbox.run(os.path.join(os.path.dirname(__file__), 'moz.configure')) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 241, in run 12:34:07 INFO - self._value_for(option) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 305, in _value_for 12:34:07 INFO - return self._value_for_option(obj) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\util.py", line 924, in method_call 12:34:07 INFO - cache[args] = self.func(instance, *args) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 340, in _value_for_option 12:34:07 INFO - need_help_dependency=False) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 297, in _resolve 12:34:07 INFO - return self._value_for(arg) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 302, in _value_for 12:34:07 INFO - return self._value_for_depends(obj) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\util.py", line 924, in method_call 12:34:07 INFO - cache[args] = self.func(instance, *args) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 328, in _value_for_depends 12:34:07 INFO - resolved_args = [self._value_for(d) for d in dependencies] 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 302, in _value_for 12:34:07 INFO - return self._value_for_depends(obj) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\util.py", line 924, in method_call 12:34:07 INFO - cache[args] = self.func(instance, *args) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 328, in _value_for_depends 12:34:07 INFO - resolved_args = [self._value_for(d) for d in dependencies] 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 302, in _value_for 12:34:07 INFO - return self._value_for_depends(obj) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\util.py", line 924, in method_call 12:34:07 INFO - cache[args] = self.func(instance, *args) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 329, in _value_for_depends 12:34:07 INFO - return func(*resolved_args) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 773, in wrapped 12:34:07 INFO - return new_func(*args, **kwargs) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 518, in wrapper 12:34:07 INFO - ret = template(*args, **kwargs) 12:34:07 INFO - File "c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/build/moz.configure/checks.configure", line 53, in wrapped 12:34:07 INFO - ret = func(*args, **kwargs) 12:34:07 INFO - File "c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\python\mozbuild\mozbuild\configure\__init__.py", line 773, in wrapped 12:34:07 INFO - return new_func(*args, **kwargs) 12:34:07 INFO - File "c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/build/moz.configure/init.configure", line 452, in host 12:34:07 INFO - host = subprocess.check_output([shell, config_guess]).strip() 12:34:07 INFO - File "c:\mozilla-build\python27\Lib\subprocess.py", line 575, in check_output 12:34:07 INFO - raise CalledProcessError(retcode, cmd, output=output) 12:34:07 INFO - subprocess.CalledProcessError: Command '[u'C:/mozilla-build/msys/bin/sh.exe', u'c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/build/moz.configure/../autoconf/config.guess']' returned non-zero exit status 1 12:34:07 INFO - *** Fix above errors and then restart with\ 12:34:07 INFO - "c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/src/mozmake.EXE -f client.mk build" 12:34:07 INFO - client.mk:373: recipe for target 'configure' failed 12:34:07 INFO - mozmake.EXE: *** [configure] Error 1 13:54:07 INFO - Automation Error: mozprocess timed out after 4800 seconds running ['c:\\mozilla-build\\python27\\python.exe', 'mach', '--log-no-times', 'build', '-v'] 13:54:07 ERROR - timed out after 4800 seconds of no output 13:54:07 ERROR - Return code: 572 13:54:07 WARNING - setting return code to 2 13:54:07 FATAL - 'mach build' did not run successfully. Please check log for errors. 13:54:07 FATAL - Running post_fatal callback... 13:54:07 FATAL - Exiting -1 13:54:07 INFO - Running post-action listener: influxdb_recording_post_action 13:54:07 INFO - Resetting dropped connection: goldiewilson-onepointtwentyone-1.c.influxdb.com c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Lib\site-packages\requests\packages\urllib3\util\ssl_.py:90: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. For more information, see https://urllib3.readthedocs.org/en/latest/security.html#insecureplatformwarning. InsecurePlatformWarning 13:54:07 INFO - Running post-action listener: record_mach_stats 13:54:07 INFO - No build_resources.json found, not logging stats 13:54:07 INFO - [mozharness: 2016-10-14 20:54:07.645000Z] Finished build step (failed) 13:54:07 INFO - Running post-run listener: _summarize 13:54:07 ERROR - # TBPL FAILURE # 13:54:07 INFO - [mozharness: 2016-10-14 20:54:07.646000Z] FxDesktopBuild summary: 13:54:07 ERROR - # TBPL FAILURE # 13:54:07 INFO - Running post-run listener: copy_logs_to_upload_dir 13:54:07 INFO - Copying logs to upload dir... 13:54:07 INFO - mkdir: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\upload\logs program finished with exit code -1 elapsedTime=6925.927000 ========= master_lag: 0.03 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 1 hrs, 55 mins, 25 secs) (at 2016-10-14 13:54:08.853148) ========= ========= Started set props: buildid got_revision comments builduid stage_platform (results: 0, elapsed: 0 secs) (at 2016-10-14 13:54:08.856106) ========= 'bash' '-c' 'for file in `ls -1`; do cat $file; done' in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\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-168 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-168 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=3976 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920 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-168 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:20161014113936 builduid:3524caeb2b2143e09aa5b0a8e455ffef comments:Bug 1201124: Protect SIMD on arm to prevent bustage; r=me got_revision:c14157737409 stage_platform:win32-debug program finished with exit code 0 elapsedTime=0.110000 buildid: '20161014113936' got_revision: 'c14157737409' comments: 'Bug 1201124: Protect SIMD on arm to prevent bustage; r=me' builduid: '3524caeb2b2143e09aa5b0a8e455ffef' 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-10-14 13:54:08.975063) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 13:54:08.976050) ========= 'rm' '-f' 'oauth.txt' in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (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-168 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-168 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=3976 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-vJYiBQ3920/agent.3920 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-168 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 -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 13:54:09.088650) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 13:54:09.089702) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 13:54:09.090099) ========= ========= Total master_lag: 0.25 =========