builder: mozilla-beta-win32-devedition slave: b-2008-spot-118 starttime: 1494289299.02 results: cancelled (6) buildid: 20170508140042 builduid: 72b4262d94a94c868f01ca2ea13b1629 revision: 798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:39.019765) ========= master: http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:39.020185) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:39.020461) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:39.288534) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-05-08 17:21:39.288807) ========= '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-beta-w32-devedition-00000000\. (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-118 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-118 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=16 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\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=3184 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056 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-118 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 --2017-05-08 17:21:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 11.2K=1.1s 2017-05-08 17:21:40 (11.2 KB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=4.194000 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-05-08 17:21:43.494637) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:43.495533) ========= 'rm' '-rf' 'scripts' 'properties' in dir c:\builds\moz2_slave\m-beta-w32-devedition-00000000\. (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-118 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-118 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=16 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\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=3184 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056 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-118 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 2017-05-08 17:21:43.609909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-08 17:21:43.610822) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 --destination scripts --debug' in dir c:\builds\moz2_slave\m-beta-w32-devedition-00000000\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 --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-118 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-118 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=16 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\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=3184 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056 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-118 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 2017-05-08 17:21:41,440 truncating revision to first 12 chars 2017-05-08 17:21:41,440 Setting DEBUG logging. 2017-05-08 17:21:41,440 attempt 1/10 2017-05-08 17:21:41,440 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/798ee0e6f73e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-08 17:21:42,628 unpacking tar archive at: mozilla-beta-798ee0e6f73e/testing/mozharness/ 2017-05-08 17:21:43,331 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=3.036000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-08 17:21:46.663411) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:46.664325) ========= script_repo_revision: 798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:46.664712) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:46.665003) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:46.675504) ========= ========= Started remove old nonce (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:46.675830) ========= 'rm' '-f' 'nonce' in dir c:\builds\moz2_slave\m-beta-w32-devedition-00000000\. (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-118 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-118 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=16 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\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=3184 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056 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-118 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 2017-05-08 17:21:46.789751) ========= ========= Started downloading to token (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:46.790617) ========= Slave: b-2008-spot-118 IP: 10.132.54.113 Duration: 25200 URI: https://signing4.srv.releng.scl3.mozilla.com:9100/token ========= Finished downloading to token (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:47.009189) ========= ========= Started set props: toolsdir (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:47.009598) ========= 'bash' '-c' 'pwd' in dir c:\builds\moz2_slave\m-beta-w32-devedition-00000000\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-118 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-118 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=16 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\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=3184 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056 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-118 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-beta-w32-devedition-00000000/scripts program finished with exit code 0 elapsedTime=0.105000 toolsdir: '/c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts' ========= master_lag: 0.01 ========= ========= Finished set props: toolsdir (results: 0, elapsed: 0 secs) (at 2017-05-08 17:21:47.124105) ========= ========= Started run_script exception (results: 4, elapsed: 16 mins, 35 secs) (at 2017-05-08 17:21:47.125017) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/fx_desktop_build.py' '--config' 'builds/releng_base_windows_32_builds.py' '--config' 'balrog/production.py' '--custom-build-variant-cfg' 'devedition' '--branch' 'mozilla-beta' '--build-pool' 'production' in dir c:\builds\moz2_slave\m-beta-w32-devedition-00000000\. (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', '--config', 'balrog/production.py', '--custom-build-variant-cfg', 'devedition', '--branch', 'mozilla-beta', '--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-118 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-118 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:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100 MOZ_SIGN_CMD=python /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-beta-w32-devedition-00000000/signing_cache -t /c/builds/moz2_slave/m-beta-w32-devedition-00000000/token -n /c/builds/moz2_slave/m-beta-w32-devedition-00000000/nonce -c /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100 MOZ_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=16 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\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 PDBSTR_PATH=/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe 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-beta-w32-devedition-00000000/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=3184 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TINDERBOX_OUTPUT=1 TMP=C:/Users/cltbld/AppData/Local/Temp TOOLTOOL_CACHE=/c/builds/tooltool_cache TOOLTOOL_HOME=/c/builds USERDOMAIN=B-2008-SPOT-118 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 17:21:46 INFO - MultiFileLogger online at 20170508 17:21:46 in c:\builds\moz2_slave\m-beta-w32-devedition-00000000 17:21:46 INFO - Script was run with '--custom-build-variant-cfg devedition' and 'devedition' matches a key in 'builds/releng_sub_windows_configs/32_devedition.py'. Updating self.config with items from that key's value. 17:21:46 INFO - Script was run with '--branch mozilla-beta' and 'mozilla-beta' matches a key in 'builds/branch_specifics.py'. Updating self.config with items from that key's value. 17:21:46 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. 17:21:46 INFO - The branch 'mozilla-beta' has custom behavior for the platform 'win32-devedition'. Updating self.config with the following from 'platform_overrides' found in 'builds/branch_specifics.py': 17:21:46 INFO - {'update_channel': 'aurora'} 17:21:46 INFO - To generate a config file based upon options passed and config files used, run script as before but extend options with "--dump-config" 17:21:46 INFO - For a diff of where self.config got its items, run the script again as before but extend options with: "--dump-config-hierarchy" 17:21:46 INFO - Both --dump-config and --dump-config-hierarchy don't actually run any actions. 17:21:46 INFO - We are running this in buildbot, grab the build props 17:21:46 INFO - Using buildbot properties: 17:21:46 INFO - { 17:21:46 INFO - "project": "", 17:21:46 INFO - "product": "devedition", 17:21:46 INFO - "script_repo_revision": "production", 17:21:46 INFO - "scheduler": "mozilla-beta-devedition", 17:21:46 INFO - "repository": "", 17:21:46 INFO - "buildername": "WINNT 5.2 mozilla-beta devedition build", 17:21:46 INFO - "buildid": "20170508140042", 17:21:46 INFO - "basedir": "/c/builds/moz2_slave/m-beta-w32-devedition-00000000", 17:21:46 INFO - "buildnumber": 7, 17:21:46 INFO - "platform": "win32-devedition", 17:21:46 INFO - "master": "http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/", 17:21:46 INFO - "branch": "mozilla-beta", 17:21:46 INFO - "revision": "798ee0e6f73eaed68e3f3968a20e9ae5c7a82718", 17:21:46 INFO - "slavename": "b-2008-spot-118", 17:21:46 INFO - "builduid": "72b4262d94a94c868f01ca2ea13b1629", 17:21:46 INFO - "repo_path": "releases/mozilla-beta" 17:21:46 INFO - } 17:21:46 INFO - Setting buildbot property stage_platform to win32-devedition 17:21:46 INFO - mkdir: c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties 17:21:46 INFO - Writing buildbot properties ['stage_platform'] to c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\stage_platform 17:21:46 INFO - Writing to file c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\stage_platform 17:21:46 INFO - Contents: 17:21:46 INFO - stage_platform:win32-devedition 17:21:46 WARNING - Try message not found. 17:21:46 INFO - Run as scripts/scripts/fx_desktop_build.py --config builds/releng_base_windows_32_builds.py --config balrog/production.py --custom-build-variant-cfg devedition --branch mozilla-beta --build-pool production 17:21:46 INFO - Determining buildid from buildbot properties 17:21:46 INFO - Setting buildbot property buildid to 20170508140042 17:21:46 INFO - Writing buildbot properties ['buildid'] to c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\buildid 17:21:46 INFO - Writing to file c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\buildid 17:21:46 INFO - Contents: 17:21:46 INFO - buildid:20170508140042 17:21:46 INFO - Determining builduid from buildbot properties 17:21:46 INFO - Setting buildbot property builduid to 72b4262d94a94c868f01ca2ea13b1629 17:21:46 INFO - Writing buildbot properties ['builduid'] to c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\builduid 17:21:46 INFO - Writing to file c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\builduid 17:21:46 INFO - Contents: 17:21:46 INFO - builduid:72b4262d94a94c868f01ca2ea13b1629 17:21:46 INFO - Release promotion update channel: aurora 17:21:46 INFO - Creating virtualenv c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv 17:21:46 INFO - mkdir: c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build 17:21:46 INFO - Running command: ['c:\\mozilla-build\\python27\\python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\venv'] in c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build 17:21:46 INFO - Copy/paste: c:\mozilla-build\python27\python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv 17:21:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:21:50 INFO - New python executable in c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\Scripts\python.exe 17:21:54 INFO - Installing distribute................................................................................................................................................................................................done. 17:22:06 INFO - Installing pip....................................done. 17:22:06 INFO - Return code: 0 17:22:06 INFO - Getting output from command: ['c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\venv\\Scripts\\pip', '--version'] 17:22:06 INFO - Copy/paste: c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\Scripts\pip --version 17:22:08 INFO - Reading from file tmpfile_stdout 17:22:08 INFO - Output received: 17:22:08 INFO - pip 9.0.1 from c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg (python 2.7) 17:22:08 INFO - Using _rmtree_windows ... 17:22:08 INFO - Using _rmtree_windows ... 17:22:08 INFO - Installing requests==2.8.1 into virtualenv c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv 17:22:08 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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 0x02652DC8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02746020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02506210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0277BC48>, '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 0x02271388>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0226DA28>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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', '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-beta-w32-devedition-00000000\\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-118', 'OS_VERSION': '2008', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'COMPUTERNAME': 'B-2008-SPOT-118', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-118', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 'HOME': 'c:/Users/cltbld', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', '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-beta-w32-devedition-00000000\\.mozbuild', 'NUMBER_OF_PROCESSORS': '16', '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.01;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\\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': '3184', '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-LUJiAs4056/agent.4056', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-beta-w32-devedition-00000000/signing_cache -t /c/builds/moz2_slave/m-beta-w32-devedition-00000000/token -n /c/builds/moz2_slave/m-beta-w32-devedition-00000000/nonce -c /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', '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 17:22:08 INFO - Running command: ['c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'requests==2.8.1'] in c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build 17:22:08 INFO - Copy/paste: c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\Scripts\pip install --timeout 120 requests==2.8.1 17:22:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:22:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:22:08 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 17:22:08 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 17:22:08 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 17:22:08 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 17:22:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 17:22:08 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 17:22:08 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 17:22:08 INFO - 'COMPUTERNAME': 'B-2008-SPOT-118', 17:22:08 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 17:22:08 INFO - 'CVS_RSH': 'ssh', 17:22:08 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 17:22:08 INFO - 'EDITOR': 'emacs.exe', 17:22:08 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:22:08 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 17:22:08 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 17:22:08 INFO - 'HOME': 'c:/Users/cltbld', 17:22:08 INFO - 'HOMEDRIVE': 'C:', 17:22:08 INFO - 'HOMEPATH': '\\', 17:22:08 INFO - 'HOSTTYPE': 'i686', 17:22:08 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 17:22:08 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 17:22:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:22:08 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 17:22:08 INFO - 'LOGNAME': 'cltbld', 17:22:08 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-118', 17:22:08 INFO - 'MACHTYPE': 'i686-pc-msys', 17:22:08 INFO - 'MAKE_MODE': 'unix', 17:22:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 17:22:08 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\.mozbuild', 17:22:08 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 17:22:08 INFO - 'MOZ_AUTOMATION': '1', 17:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:08 INFO - 'MOZ_OBJDIR': 'obj-firefox', 17:22:08 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', 17:22:08 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-beta-w32-devedition-00000000/signing_cache -t /c/builds/moz2_slave/m-beta-w32-devedition-00000000/token -n /c/builds/moz2_slave/m-beta-w32-devedition-00000000/nonce -c /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', 17:22:08 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 17:22:08 INFO - 'MSYSTEM': 'MINGW32', 17:22:08 INFO - 'NUMBER_OF_PROCESSORS': '16', 17:22:08 INFO - 'OLDPWD': 'c:/Users/cltbld', 17:22:08 INFO - 'OS': 'Windows_NT', 17:22:08 INFO - 'OSTYPE': 'msys', 17:22:08 INFO - 'OS_VERSION': '2008', 17:22:08 INFO - 'PATH': 'C:/mozilla-build/nsis-3.01;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\\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', 17:22:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:22:08 INFO - 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 17:22:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:22:08 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 17:22:08 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 17:22:08 INFO - 'PROCESSOR_LEVEL': '6', 17:22:08 INFO - 'PROCESSOR_REVISION': '3f02', 17:22:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:22:08 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 17:22:08 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 17:22:08 INFO - 'PROGRAMW6432': 'C:\\Program Files', 17:22:08 INFO - 'PROMPT': '$P$G', 17:22:08 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\buildprops.json', 17:22:08 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 17:22:08 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 17:22:08 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:22:08 INFO - 'PWD': 'c:/Users/cltbld', 17:22:08 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 17:22:08 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 17:22:08 INFO - 'SHLVL': '1', 17:22:08 INFO - 'SSH_AGENT_PID': '3184', 17:22:08 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056', 17:22:08 INFO - 'SYSTEMDRIVE': 'C:', 17:22:08 INFO - 'SYSTEMROOT': 'C:\\Windows', 17:22:08 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 17:22:08 INFO - 'TERM': 'cygwin', 17:22:08 INFO - 'TINDERBOX_OUTPUT': '1', 17:22:08 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 17:22:08 INFO - 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 17:22:08 INFO - 'TOOLTOOL_HOME': '/c/builds', 17:22:08 INFO - 'USERDOMAIN': 'B-2008-SPOT-118', 17:22:08 INFO - 'USERNAME': 'cltbld', 17:22:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:22:08 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 17:22:08 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 17:22:08 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 17:22:08 INFO - 'WINDIR': 'C:\\Windows', 17:22:08 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:22:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:22:08 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 17:22:08 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 17:22:09 INFO - Collecting requests==2.8.1 17:22:10 INFO - Downloading requests-2.8.1-py2.py3-none-any.whl (497kB) 17:22:10 INFO - Installing collected packages: requests 17:22:11 INFO - Successfully installed requests-2.8.1 17:22:12 INFO - c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:318: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#snimissingwarning. 17:22:12 INFO - SNIMissingWarning 17:22:12 INFO - c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning. 17:22:12 INFO - InsecurePlatformWarning 17:22:12 INFO - c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning. 17:22:12 INFO - InsecurePlatformWarning 17:22:12 INFO - Return code: 0 17:22:12 INFO - Installing PyHawk-with-a-single-extra-commit==0.1.5 into virtualenv c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv 17:22:12 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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 0x02652DC8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02746020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02506210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0277BC48>, '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 0x02271388>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0226DA28>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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', '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-beta-w32-devedition-00000000\\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-118', 'OS_VERSION': '2008', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'COMPUTERNAME': 'B-2008-SPOT-118', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-118', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 'HOME': 'c:/Users/cltbld', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', '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-beta-w32-devedition-00000000\\.mozbuild', 'NUMBER_OF_PROCESSORS': '16', '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.01;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\\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': '3184', '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-LUJiAs4056/agent.4056', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-beta-w32-devedition-00000000/signing_cache -t /c/builds/moz2_slave/m-beta-w32-devedition-00000000/token -n /c/builds/moz2_slave/m-beta-w32-devedition-00000000/nonce -c /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', '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 17:22:12 INFO - Running command: ['c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'PyHawk-with-a-single-extra-commit==0.1.5'] in c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build 17:22:12 INFO - Copy/paste: c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\Scripts\pip install --timeout 120 PyHawk-with-a-single-extra-commit==0.1.5 17:22:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:22:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:22:12 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 17:22:12 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 17:22:12 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 17:22:12 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 17:22:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 17:22:12 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 17:22:12 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 17:22:12 INFO - 'COMPUTERNAME': 'B-2008-SPOT-118', 17:22:12 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 17:22:12 INFO - 'CVS_RSH': 'ssh', 17:22:12 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 17:22:12 INFO - 'EDITOR': 'emacs.exe', 17:22:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:22:12 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 17:22:12 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 17:22:12 INFO - 'HOME': 'c:/Users/cltbld', 17:22:12 INFO - 'HOMEDRIVE': 'C:', 17:22:12 INFO - 'HOMEPATH': '\\', 17:22:12 INFO - 'HOSTTYPE': 'i686', 17:22:12 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 17:22:12 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 17:22:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:22:12 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 17:22:12 INFO - 'LOGNAME': 'cltbld', 17:22:12 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-118', 17:22:12 INFO - 'MACHTYPE': 'i686-pc-msys', 17:22:12 INFO - 'MAKE_MODE': 'unix', 17:22:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 17:22:12 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\.mozbuild', 17:22:12 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 17:22:12 INFO - 'MOZ_AUTOMATION': '1', 17:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:12 INFO - 'MOZ_OBJDIR': 'obj-firefox', 17:22:12 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', 17:22:12 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-beta-w32-devedition-00000000/signing_cache -t /c/builds/moz2_slave/m-beta-w32-devedition-00000000/token -n /c/builds/moz2_slave/m-beta-w32-devedition-00000000/nonce -c /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', 17:22:12 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 17:22:12 INFO - 'MSYSTEM': 'MINGW32', 17:22:12 INFO - 'NUMBER_OF_PROCESSORS': '16', 17:22:12 INFO - 'OLDPWD': 'c:/Users/cltbld', 17:22:12 INFO - 'OS': 'Windows_NT', 17:22:12 INFO - 'OSTYPE': 'msys', 17:22:12 INFO - 'OS_VERSION': '2008', 17:22:12 INFO - 'PATH': 'C:/mozilla-build/nsis-3.01;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\\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', 17:22:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:22:12 INFO - 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 17:22:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:22:12 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 17:22:12 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 17:22:12 INFO - 'PROCESSOR_LEVEL': '6', 17:22:12 INFO - 'PROCESSOR_REVISION': '3f02', 17:22:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:22:12 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 17:22:12 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 17:22:12 INFO - 'PROGRAMW6432': 'C:\\Program Files', 17:22:12 INFO - 'PROMPT': '$P$G', 17:22:12 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\buildprops.json', 17:22:12 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 17:22:12 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 17:22:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:22:12 INFO - 'PWD': 'c:/Users/cltbld', 17:22:12 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 17:22:12 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 17:22:12 INFO - 'SHLVL': '1', 17:22:12 INFO - 'SSH_AGENT_PID': '3184', 17:22:12 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056', 17:22:12 INFO - 'SYSTEMDRIVE': 'C:', 17:22:12 INFO - 'SYSTEMROOT': 'C:\\Windows', 17:22:12 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 17:22:12 INFO - 'TERM': 'cygwin', 17:22:12 INFO - 'TINDERBOX_OUTPUT': '1', 17:22:12 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 17:22:12 INFO - 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 17:22:12 INFO - 'TOOLTOOL_HOME': '/c/builds', 17:22:12 INFO - 'USERDOMAIN': 'B-2008-SPOT-118', 17:22:12 INFO - 'USERNAME': 'cltbld', 17:22:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:22:12 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 17:22:12 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 17:22:12 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 17:22:12 INFO - 'WINDIR': 'C:\\Windows', 17:22:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:22:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:22:12 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 17:22:12 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 17:22:12 INFO - Collecting PyHawk-with-a-single-extra-commit==0.1.5 17:22:13 INFO - Downloading PyHawk-with-a-single-extra-commit-0.1.5.tar.gz 17:22:13 INFO - Installing collected packages: PyHawk-with-a-single-extra-commit 17:22:13 INFO - Running setup.py install for PyHawk-with-a-single-extra-commit: started 17:22:13 INFO - Running setup.py install for PyHawk-with-a-single-extra-commit: finished with status 'done' 17:22:13 INFO - Successfully installed PyHawk-with-a-single-extra-commit-0.1.5 17:22:13 INFO - c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:318: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#snimissingwarning. 17:22:13 INFO - SNIMissingWarning 17:22:13 INFO - c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning. 17:22:13 INFO - InsecurePlatformWarning 17:22:13 INFO - Return code: 0 17:22:13 INFO - Installing taskcluster==0.0.26 into virtualenv c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv 17:22:13 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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 0x02652DC8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02746020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02506210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0277BC48>, '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 0x02271388>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0226DA28>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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', '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-beta-w32-devedition-00000000\\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-118', 'OS_VERSION': '2008', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'COMPUTERNAME': 'B-2008-SPOT-118', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-118', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 'HOME': 'c:/Users/cltbld', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', '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-beta-w32-devedition-00000000\\.mozbuild', 'NUMBER_OF_PROCESSORS': '16', '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.01;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\\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': '3184', '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-LUJiAs4056/agent.4056', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-beta-w32-devedition-00000000/signing_cache -t /c/builds/moz2_slave/m-beta-w32-devedition-00000000/token -n /c/builds/moz2_slave/m-beta-w32-devedition-00000000/nonce -c /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', '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 17:22:13 INFO - Running command: ['c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'taskcluster==0.0.26'] in c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build 17:22:13 INFO - Copy/paste: c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\Scripts\pip install --timeout 120 taskcluster==0.0.26 17:22:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:22:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:22:13 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 17:22:13 INFO - 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 17:22:13 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 17:22:13 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 17:22:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 17:22:13 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 17:22:13 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 17:22:13 INFO - 'COMPUTERNAME': 'B-2008-SPOT-118', 17:22:13 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 17:22:13 INFO - 'CVS_RSH': 'ssh', 17:22:13 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 17:22:13 INFO - 'EDITOR': 'emacs.exe', 17:22:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:22:13 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 17:22:13 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 17:22:13 INFO - 'HOME': 'c:/Users/cltbld', 17:22:13 INFO - 'HOMEDRIVE': 'C:', 17:22:13 INFO - 'HOMEPATH': '\\', 17:22:13 INFO - 'HOSTTYPE': 'i686', 17:22:13 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 17:22:13 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 17:22:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:22:13 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 17:22:13 INFO - 'LOGNAME': 'cltbld', 17:22:13 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-118', 17:22:13 INFO - 'MACHTYPE': 'i686-pc-msys', 17:22:13 INFO - 'MAKE_MODE': 'unix', 17:22:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 17:22:13 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\.mozbuild', 17:22:13 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 17:22:13 INFO - 'MOZ_AUTOMATION': '1', 17:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:13 INFO - 'MOZ_OBJDIR': 'obj-firefox', 17:22:13 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', 17:22:13 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-beta-w32-devedition-00000000/signing_cache -t /c/builds/moz2_slave/m-beta-w32-devedition-00000000/token -n /c/builds/moz2_slave/m-beta-w32-devedition-00000000/nonce -c /c/builds/moz2_slave/m-beta-w32-devedition-00000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9100 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9100 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9100 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9100', 17:22:13 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 17:22:13 INFO - 'MSYSTEM': 'MINGW32', 17:22:13 INFO - 'NUMBER_OF_PROCESSORS': '16', 17:22:13 INFO - 'OLDPWD': 'c:/Users/cltbld', 17:22:13 INFO - 'OS': 'Windows_NT', 17:22:13 INFO - 'OSTYPE': 'msys', 17:22:13 INFO - 'OS_VERSION': '2008', 17:22:13 INFO - 'PATH': 'C:/mozilla-build/nsis-3.01;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\\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', 17:22:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:22:13 INFO - 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 17:22:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:22:13 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 17:22:13 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 63 Stepping 2, GenuineIntel', 17:22:13 INFO - 'PROCESSOR_LEVEL': '6', 17:22:13 INFO - 'PROCESSOR_REVISION': '3f02', 17:22:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:22:13 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 17:22:13 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 17:22:13 INFO - 'PROGRAMW6432': 'C:\\Program Files', 17:22:13 INFO - 'PROMPT': '$P$G', 17:22:13 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\buildprops.json', 17:22:13 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 17:22:13 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 17:22:13 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:22:13 INFO - 'PWD': 'c:/Users/cltbld', 17:22:13 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 17:22:13 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 17:22:13 INFO - 'SHLVL': '1', 17:22:13 INFO - 'SSH_AGENT_PID': '3184', 17:22:13 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056', 17:22:13 INFO - 'SYSTEMDRIVE': 'C:', 17:22:13 INFO - 'SYSTEMROOT': 'C:\\Windows', 17:22:13 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 17:22:13 INFO - 'TERM': 'cygwin', 17:22:13 INFO - 'TINDERBOX_OUTPUT': '1', 17:22:13 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 17:22:13 INFO - 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 17:22:13 INFO - 'TOOLTOOL_HOME': '/c/builds', 17:22:13 INFO - 'USERDOMAIN': 'B-2008-SPOT-118', 17:22:13 INFO - 'USERNAME': 'cltbld', 17:22:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:22:13 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 17:22:13 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 17:22:13 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 17:22:13 INFO - 'WINDIR': 'C:\\Windows', 17:22:13 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:22:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:22:13 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 17:22:13 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 17:22:14 INFO - Collecting taskcluster==0.0.26 17:22:14 INFO - Downloading taskcluster-0.0.26.tar.gz 17:22:15 INFO - Collecting requests<=2.7.0,>=2.4.3 (from taskcluster==0.0.26) 17:22:15 INFO - Downloading requests-2.7.0-py2.py3-none-any.whl (470kB) 17:22:15 INFO - Requirement already satisfied: PyHawk_with_a_single_extra_commit==0.1.5 in .\venv\lib\site-packages (from taskcluster==0.0.26) 17:22:15 INFO - Collecting slugid (from taskcluster==0.0.26) 17:22:15 INFO - Downloading slugid-1.0.7.tar.gz 17:22:16 INFO - Installing collected packages: requests, slugid, taskcluster 17:22:16 INFO - Found existing installation: requests 2.8.1 17:22:16 INFO - Uninstalling requests-2.8.1: 17:22:16 INFO - Successfully uninstalled requests-2.8.1 17:22:17 INFO - Running setup.py install for slugid: started 17:22:17 INFO - Running setup.py install for slugid: finished with status 'done' 17:22:18 INFO - Running setup.py install for taskcluster: started 17:22:18 INFO - Running setup.py install for taskcluster: finished with status 'done' 17:22:18 INFO - Successfully installed requests-2.7.0 slugid-1.0.7 taskcluster-0.0.26 17:22:18 INFO - c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:318: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#snimissingwarning. 17:22:18 INFO - SNIMissingWarning 17:22:18 INFO - c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg\pip\_vendor\requests\packages\urllib3\util\ssl_.py:122: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/security.html#insecureplatformwarning. 17:22:18 INFO - InsecurePlatformWarning 17:22:18 INFO - Return code: 0 17:22:18 INFO - Done creating virtualenv c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv. 17:22:18 INFO - Getting output from command: ['c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\venv\\Scripts\\pip', 'freeze'] 17:22:18 INFO - Copy/paste: c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\venv\Scripts\pip freeze 17:22:19 INFO - Reading from file tmpfile_stdout 17:22:19 INFO - Using _rmtree_windows ... 17:22:19 INFO - Using _rmtree_windows ... 17:22:19 INFO - Current package versions: 17:22:19 INFO - PyHawk-with-a-single-extra-commit == 0.1.5 17:22:19 INFO - requests == 2.7.0 17:22:19 INFO - slugid == 1.0.7 17:22:19 INFO - taskcluster == 0.0.26 17:22:19 INFO - Running pre-run listener: suppress_windows_modal_dialogs 17:22:19 INFO - Dumping config to c:\builds\moz2_slave\m-beta-w32-devedition-00000000\logs\localconfig.json. 17:22:19 INFO - {'app_ini_path': '%(obj_dir)s/dist/bin/application.ini', 17:22:19 INFO - 'append_to_log': False, 17:22:19 INFO - 'balrog_credentials_file': 'oauth.txt', 17:22:19 INFO - 'balrog_servers': ({'balrog_api_root': 'https://aus4-admin.mozilla.org/api', 17:22:19 INFO - 'balrog_usernames': {'Fennec': 'ffxbld', 17:22:19 INFO - 'firefox': 'ffxbld', 17:22:19 INFO - 'mobile': 'ffxbld', 17:22:19 INFO - 'thunderbird': 'tbirdbld'}, 17:22:19 INFO - 'ignore_failures': False, 17:22:19 INFO - 'url_replacements': (('http://archive.mozilla.org/pub', 17:22:19 INFO - 'http://download.cdn.mozilla.net/pub'),)},), 17:22:19 INFO - 'base_name': 'WINNT_5.2_%(branch)s', 17:22:19 INFO - 'base_work_dir': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000', 17:22:19 INFO - 'branch': 'mozilla-beta', 17:22:19 INFO - 'branch_uses_per_checkin_strategy': True, 17:22:19 INFO - 'build_pool': 'production', 17:22:19 INFO - 'build_resources_path': '%(abs_src_dir)s/obj-firefox/.mozbuild/build_resources.json', 17:22:19 INFO - 'build_variant': 'devedition', 17:22:19 INFO - 'buildbot_json_path': 'buildprops.json', 17:22:19 INFO - 'check_test_env': {'MINIDUMP_SAVE_PATH': '%(base_work_dir)s/minidumps', 17:22:19 INFO - 'MINIDUMP_STACKWALK': '%(abs_tools_dir)s/breakpad/win32/minidump_stackwalk.exe'}, 17:22:19 INFO - 'clobberer_url': 'https://api.pub.build.mozilla.org/clobberer/lastclobber', 17:22:19 INFO - 'clone_by_revision': False, 17:22:19 INFO - 'clone_upstream_url': 'https://hg.mozilla.org/mozilla-unified', 17:22:19 INFO - 'clone_with_purge': False, 17:22:19 INFO - 'config_files': ('builds/releng_base_windows_32_builds.py', 17:22:19 INFO - 'balrog/production.py', 17:22:19 INFO - 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\scripts\\scripts\\..\\configs\\builds/releng_sub_windows_configs/32_devedition.py', 17:22:19 INFO - 'builds/branch_specifics.py', 17:22:19 INFO - 'builds/build_pool_specifics.py'), 17:22:19 INFO - 'debug_build': False, 17:22:19 INFO - 'default_actions': ('clobber', 17:22:19 INFO - 'clone-tools', 17:22:19 INFO - 'checkout-sources', 17:22:19 INFO - 'build', 17:22:19 INFO - 'upload-files', 17:22:19 INFO - 'sendchange', 17:22:19 INFO - 'check-test', 17:22:19 INFO - 'generate-build-stats', 17:22:19 INFO - 'update'), 17:22:19 INFO - 'enable_ccache': False, 17:22:19 INFO - 'enable_count_ctors': False, 17:22:19 INFO - 'enable_pymake': True, 17:22:19 INFO - 'enable_release_promotion': 1, 17:22:19 INFO - 'enable_signing': True, 17:22:19 INFO - 'enable_talos_sendchange': True, 17:22:19 INFO - 'enable_unittest_sendchange': True, 17:22:19 INFO - 'env': {'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 17:22:19 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 17:22:19 INFO - 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\.mozbuild', 17:22:19 INFO - 'MOZ_AUTOMATION': '1', 17:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:19 INFO - 'MOZ_OBJDIR': 'obj-firefox', 17:22:19 INFO - 'PATH': 'C:/mozilla-build/nsis-3.01;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\\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', 17:22:19 INFO - 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 17:22:19 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\buildprops.json', 17:22:19 INFO - 'TINDERBOX_OUTPUT': '1', 17:22:19 INFO - 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 17:22:19 INFO - 'TOOLTOOL_HOME': '/c/builds'}, 17:22:19 INFO - 'exes': {'buildbot': ('c:\\mozilla-build\\python27\\python.exe', 17:22:19 INFO - 'c:\\mozilla-build\\buildbotve\\scripts\\buildbot'), 17:22:19 INFO - 'make': ('c:\\mozilla-build\\python27\\python.exe', 17:22:19 INFO - 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\src\\build\\pymake\\make.py'), 17:22:19 INFO - 'python2.7': 'c:\\mozilla-build\\python27\\python.exe', 17:22:19 INFO - 'virtualenv': ('c:\\mozilla-build\\python27\\python.exe', 17:22:19 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 17:22:19 INFO - 'force_clobber': True, 17:22:19 INFO - 'graph_selector': '/server/collect.cgi', 17:22:19 INFO - 'graph_server': 'graphs.mozilla.org', 17:22:19 INFO - 'include_post_upload_builddir': False, 17:22:19 INFO - 'is_automation': True, 17:22:19 INFO - 'log_level': 'info', 17:22:19 INFO - 'log_to_console': True, 17:22:19 INFO - 'max_build_output_timeout': 4800, 17:22:19 INFO - 'nightly_build': False, 17:22:19 INFO - 'nightly_promotion_branches': ('mozilla-central', 'mozilla-aurora'), 17:22:19 INFO - 'objdir': 'obj-firefox', 17:22:19 INFO - 'old_packages': ('%(objdir)s/dist/firefox-*', 17:22:19 INFO - '%(objdir)s/dist/fennec*', 17:22:19 INFO - '%(objdir)s/dist/seamonkey*', 17:22:19 INFO - '%(objdir)s/dist/thunderbird*', 17:22:19 INFO - '%(objdir)s/dist/install/sea/*.exe'), 17:22:19 INFO - 'opt_config_files': (), 17:22:19 INFO - 'periodic_clobber': 168, 17:22:19 INFO - 'pgo_build': False, 17:22:19 INFO - 'pgo_platforms': ('linux', 'linux64', 'win32', 'win64'), 17:22:19 INFO - 'platform': 'win32', 17:22:19 INFO - 'platform_overrides': {'linux': {'force_clobber': True, 17:22:19 INFO - 'src_mozconfig': 'browser/config/mozconfigs/linux32/beta'}, 17:22:19 INFO - 'linux-debug': {'update_channel': 'default'}, 17:22:19 INFO - 'linux-devedition': {'update_channel': 'aurora'}, 17:22:19 INFO - 'linux64': {'force_clobber': True, 17:22:19 INFO - 'src_mozconfig': 'browser/config/mozconfigs/linux64/beta'}, 17:22:19 INFO - 'linux64-add-on-devel': {'update_channel': 'default'}, 17:22:19 INFO - 'linux64-asan': {'update_channel': 'default'}, 17:22:19 INFO - 'linux64-asan-debug': {'update_channel': 'default'}, 17:22:19 INFO - 'linux64-debug': {'update_channel': 'default'}, 17:22:19 INFO - 'linux64-devedition': {'update_channel': 'aurora'}, 17:22:19 INFO - 'linux64-st-an': {'update_channel': 'default'}, 17:22:19 INFO - 'linux64-st-an-debug': {'update_channel': 'default'}, 17:22:19 INFO - 'macosx64': {'force_clobber': True, 17:22:19 INFO - 'src_mozconfig': 'browser/config/mozconfigs/macosx64/beta'}, 17:22:19 INFO - 'macosx64-add-on-devel': {'update_channel': 'default'}, 17:22:19 INFO - 'macosx64-debug': {'update_channel': 'default'}, 17:22:19 INFO - 'macosx64-devedition': {'update_channel': 'aurora'}, 17:22:19 INFO - 'macosx64-st-an': {'update_channel': 'default'}, 17:22:19 INFO - 'macosx64-st-an-debug': {'update_channel': 'default'}, 17:22:19 INFO - 'win32': {'force_clobber': True, 17:22:19 INFO - 'src_mozconfig': 'browser/config/mozconfigs/win32/beta'}, 17:22:19 INFO - 'win32-add-on-devel': {'update_channel': 'default'}, 17:22:19 INFO - 'win32-debug': {'update_channel': 'default'}, 17:22:19 INFO - 'win32-devedition': {'update_channel': 'aurora'}, 17:22:19 INFO - 'win64': {'force_clobber': True, 17:22:19 INFO - 'src_mozconfig': 'browser/config/mozconfigs/win64/beta'}, 17:22:19 INFO - 'win64-add-on-devel': {'update_channel': 'default'}, 17:22:19 INFO - 'win64-debug': {'update_channel': 'default'}, 17:22:19 INFO - 'win64-devedition': {'update_channel': 'aurora'}}, 17:22:19 INFO - 'platform_supports_post_upload_to_latest': True, 17:22:19 INFO - 'publish_nightly_en_US_routes': True, 17:22:19 INFO - 'release_to_try_builds': False, 17:22:19 INFO - 'repo_base': 'https://hg.mozilla.org', 17:22:19 INFO - 'repo_path': 'releases/mozilla-beta', 17:22:19 INFO - 'scm_level': 1, 17:22:19 INFO - 'sendchange_masters': ('buildbot-master81.build.mozilla.org:9301',), 17:22:19 INFO - 'src_mozconfig': 'browser/config/mozconfigs/win32/devedition', 17:22:19 INFO - 'stage_platform': 'win32-devedition', 17:22:19 INFO - 'stage_product': 'devedition', 17:22:19 INFO - 'stage_server': 'upload.ffxbld.productdelivery.prod.mozaws.net', 17:22:19 INFO - 'stage_ssh_key': 'ffxbld_rsa', 17:22:19 INFO - 'stage_username': 'ffxbld', 17:22:19 INFO - 'taskcluster_credentials_file': 'oauth.txt', 17:22:19 INFO - 'taskcluster_index': 'index', 17:22:19 INFO - 'tinderbox_build_dir': None, 17:22:19 INFO - 'to_tinderbox_dated': True, 17:22:19 INFO - 'tools_repo': 'https://hg.mozilla.org/build/tools', 17:22:19 INFO - 'tooltool_bootstrap': 'setup.sh', 17:22:19 INFO - 'tooltool_manifest_src': 'browser/config/tooltool-manifests/win32/releng.manifest', 17:22:19 INFO - 'tooltool_script': ('c:\\mozilla-build\\python27\\python.exe', 17:22:19 INFO - 'C:/mozilla-build/tooltool.py'), 17:22:19 INFO - 'tooltool_url': 'https://api.pub.build.mozilla.org/tooltool/', 17:22:19 INFO - 'update_channel': 'aurora', 17:22:19 INFO - 'upload_env': {'UPLOAD_HOST': '%(stage_server)s', 17:22:19 INFO - 'UPLOAD_SSH_KEY': '/c/Users/cltbld/.ssh/%(stage_ssh_key)s', 17:22:19 INFO - 'UPLOAD_TO_TEMP': '1', 17:22:19 INFO - 'UPLOAD_USER': '%(stage_username)s'}, 17:22:19 INFO - 'use_clobberer': True, 17:22:19 INFO - 'vcs_share_base': 'C:/builds/hg-shared', 17:22:19 INFO - 'virtualenv_modules': ('requests==2.8.1', 17:22:19 INFO - 'PyHawk-with-a-single-extra-commit==0.1.5', 17:22:19 INFO - 'taskcluster==0.0.26'), 17:22:19 INFO - 'virtualenv_path': 'venv', 17:22:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:22:19 INFO - 'who': '', 17:22:19 INFO - 'work_dir': 'build'} 17:22:19 INFO - [mozharness: 2017-05-09 00:22:19.431000Z] Skipping get-secrets step. 17:22:19 INFO - [mozharness: 2017-05-09 00:22:19.431000Z] Running clobber step. 17:22:19 INFO - Running main action method: clobber 17:22:19 INFO - Clobbering because our config forced us to 17:22:19 INFO - rmtree: c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build 17:22:19 INFO - Using _rmtree_windows ... 17:22:19 INFO - retry: Calling _rmtree_windows with args: ('c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build',), kwargs: {}, attempt #1 17:22:22 INFO - retry: Calling run_command with args: [['python', 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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-beta', u'WINNT 5.2 mozilla-beta devedition build', 'm-beta-w32-devedition-00000000', u'b-2008-spot-118', 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 17:22:22 INFO - Running command: ['python', 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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-beta', u'WINNT 5.2 mozilla-beta devedition build', 'm-beta-w32-devedition-00000000', u'b-2008-spot-118', u'http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/'] in c:\builds\moz2_slave 17:22:22 INFO - Copy/paste: python c:\builds\moz2_slave\m-beta-w32-devedition-00000000\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-beta "WINNT 5.2 mozilla-beta devedition build" m-beta-w32-devedition-00000000 b-2008-spot-118 http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/ 17:22:23 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-118&builddir=m-beta-w32-devedition-00000000&branch=mozilla-beta&buildername=WINNT+5.2+mozilla-beta+devedition+build 17:22:23 INFO - m-beta-w32-devedition-00000000:Our last clobber date: None 17:22:23 INFO - m-beta-w32-devedition-00000000:Server clobber date: None 17:22:23 INFO - TinderboxPrint: purged clobber 17:22:23 INFO - Return code: 0 17:22:23 INFO - [mozharness: 2017-05-09 00:22:23.010000Z] Finished clobber step (success) 17:22:23 INFO - [mozharness: 2017-05-09 00:22:23.011000Z] Running clone-tools step. 17:22:23 INFO - Running main action method: clone_tools 17:22:23 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\tools'), kwargs: {}, attempt #1 17:22:23 WARNING - did not specify revision or branch; assuming "default" 17:22:23 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 17:22:23 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 17:22:24 INFO - Mercurial Distributed SCM (version 3.9.1) 17:22:24 INFO - (see https://mercurial-scm.org for more information) 17:22:24 INFO - Copyright (C) 2005-2016 Matt Mackall and others 17:22:24 INFO - This is free software; see the source for copying conditions. There is NO 17:22:24 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 17:22:24 INFO - Return code: 0 17:22:24 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 17:22:24 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 17:22:24 INFO - checking encoding (cp1252)... 17:22:24 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 17:22:24 INFO - checking Python version (2.7.12) 17:22:24 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 17:22:24 INFO - checking Mercurial version (3.9.1) 17:22:24 INFO - checking Mercurial custom build () 17:22:24 INFO - checking module policy (c) 17:22:24 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 17:22:24 INFO - checking templates (c:\mozilla-build\hg\templates)... 17:22:24 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 17:22:24 INFO - checking commit editor... (notepad) 17:22:24 INFO - checking username (Mozilla Release Engineering ) 17:22:24 INFO - no problems detected 17:22:24 INFO - Return code: 0 17:22:24 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/build/tools', 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\tools', '--sharebase', 'C:/builds/hg-shared', '--branch', 'default'] 17:22:24 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\m-beta-w32-devedition-00000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/build/tools c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\tools --sharebase C:/builds/hg-shared --branch default 17:22:28 INFO - ensuring https://hg.mozilla.org/build/tools@default is available at c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\tools 17:22:28 INFO - (sharing from new pooled repository 7ae7fb134bf7aec6ec96a062ff47a69053dd2973) 17:22:28 INFO - applying clone bundle from https://s3-us-west-2.amazonaws.com/moz-hg-bundles-us-west-2/build/tools/9a9f8651ab6c298518ad2d5bc06d7bdab39b9b2b.packed1.hg 17:22:28 INFO - 2170 files to transfer, 28.8 MB of data 17:22:28 INFO - transferred 28.8 MB in 2.0 seconds (14.5 MB/sec) 17:22:28 INFO - finished applying clone bundle 17:22:28 INFO - searching for changes 17:22:28 INFO - no changes found 17:22:28 INFO - searching for changes 17:22:28 INFO - no changes found 17:22:28 INFO - (pulling to obtain default) 17:22:28 INFO - (remote resolved default to 9a9f8651ab6c298518ad2d5bc06d7bdab39b9b2b; result is not deterministic) 17:22:28 INFO - (revision already present locally; not pulling) 17:22:30 INFO - 17:22:30 INFO - updating [====> ] 100/1046 17:22:30 INFO - updating [================================================> ] 900/1046 17:22:30 INFO - 17:22:30 INFO - 1046 files updated, 0 files merged, 0 files removed, 0 files unresolved 17:22:30 INFO - updated to 9a9f8651ab6c298518ad2d5bc06d7bdab39b9b2b 17:22:30 INFO - Return code: 0 17:22:30 INFO - [mozharness: 2017-05-09 00:22:30.914000Z] Finished clone-tools step (success) 17:22:30 INFO - [mozharness: 2017-05-09 00:22:30.914000Z] Running checkout-sources step. 17:22:30 INFO - Running main action method: checkout_sources 17:22:30 INFO - Release promotion update channel: aurora 17:22:30 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\src'), kwargs: {}, attempt #1 17:22:30 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 17:22:30 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 17:22:31 INFO - Mercurial Distributed SCM (version 3.9.1) 17:22:31 INFO - (see https://mercurial-scm.org for more information) 17:22:31 INFO - Copyright (C) 2005-2016 Matt Mackall and others 17:22:31 INFO - This is free software; see the source for copying conditions. There is NO 17:22:31 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 17:22:31 INFO - Return code: 0 17:22:31 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 17:22:31 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 17:22:31 INFO - checking encoding (cp1252)... 17:22:31 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 17:22:31 INFO - checking Python version (2.7.12) 17:22:31 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 17:22:31 INFO - checking Mercurial version (3.9.1) 17:22:31 INFO - checking Mercurial custom build () 17:22:31 INFO - checking module policy (c) 17:22:31 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 17:22:31 INFO - checking templates (c:\mozilla-build\hg\templates)... 17:22:31 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 17:22:31 INFO - checking commit editor... (notepad) 17:22:31 INFO - checking username (Mozilla Release Engineering ) 17:22:31 INFO - no problems detected 17:22:31 INFO - Return code: 0 17:22:31 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/releases/mozilla-beta', 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\src', '--sharebase', 'C:/builds/hg-shared', '--upstream', 'https://hg.mozilla.org/mozilla-unified', '--revision', '798ee0e6f73eaed68e3f3968a20e9ae5c7a82718'] 17:22:31 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\m-beta-w32-devedition-00000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/releases/mozilla-beta c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\src --sharebase C:/builds/hg-shared --upstream https://hg.mozilla.org/mozilla-unified --revision 798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 17:23:02 INFO - ensuring https://hg.mozilla.org/releases/mozilla-beta@798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 is available at c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\src 17:23:02 INFO - (cloning from upstream repo https://hg.mozilla.org/mozilla-unified) 17:23:02 INFO - (sharing from existing pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29) 17:23:02 INFO - searching for changes 17:23:02 INFO - adding changesets 17:23:02 INFO - adding manifests 17:23:02 INFO - adding file changes 17:23:02 INFO - added 11 changesets with 35 changes to 34 files (-1 heads) 17:36:06 INFO - 17:36:06 INFO - updating [ ] 100/184477 17:36:06 INFO - updating [ ] 500/184477 17:36:06 INFO - updating [ ] 800/184477 10m15s 17:36:06 INFO - updating [ ] 1100/184477 10m37s 17:36:06 INFO - updating [ ] 1400/184477 11m02s 17:36:06 INFO - updating [ ] 1800/184477 10m57s 17:36:06 INFO - updating [ ] 2100/184477 11m10s 17:36:06 INFO - updating [ ] 2300/184477 12m49s 17:36:06 INFO - updating [ ] 2500/184477 13m34s 17:36:06 INFO - updating [ ] 3000/184477 13m06s 17:36:06 INFO - updating [ ] 3300/184477 14m03s 17:36:06 INFO - updating [ ] 3600/184477 16m13s 17:36:06 INFO - updating [ ] 3900/184477 16m04s 17:36:06 INFO - updating [> ] 4100/184477 16m14s 17:36:06 INFO - updating [> ] 4300/184477 16m18s 17:36:06 INFO - updating [> ] 4600/184477 16m08s 17:36:06 INFO - updating [> ] 4800/184477 16m07s 17:36:06 INFO - updating [> ] 5000/184477 16m30s 17:36:06 INFO - updating [> ] 5400/184477 16m15s 17:36:06 INFO - updating [> ] 5700/184477 16m09s 17:36:06 INFO - updating [> ] 6000/184477 15m51s 17:36:06 INFO - updating [> ] 6200/184477 15m53s 17:36:06 INFO - updating [> ] 6500/184477 15m44s 17:36:06 INFO - updating [> ] 6700/184477 15m52s 17:36:06 INFO - updating [> ] 7100/184477 15m49s 17:36:06 INFO - updating [> ] 7400/184477 15m39s 17:36:06 INFO - updating [> ] 7600/184477 15m47s 17:36:06 INFO - updating [> ] 7900/184477 15m37s 17:36:06 INFO - updating [=> ] 8100/184477 16m39s 17:36:06 INFO - updating [=> ] 8200/184477 16m50s 17:36:06 INFO - updating [=> ] 8400/184477 16m55s 17:36:06 INFO - updating [=> ] 8700/184477 16m44s 17:36:06 INFO - updating [=> ] 9000/184477 16m32s 17:36:06 INFO - updating [=> ] 9200/184477 16m30s 17:36:06 INFO - updating [=> ] 9500/184477 16m26s 17:36:06 INFO - updating [=> ] 9800/184477 16m26s 17:36:06 INFO - updating [=> ] 10000/184477 16m23s 17:36:06 INFO - updating [=> ] 10500/184477 15m57s 17:36:06 INFO - updating [=> ] 10800/184477 15m46s 17:36:06 INFO - updating [=> ] 11000/184477 15m48s 17:36:06 INFO - updating [=> ] 11300/184477 15m37s 17:36:07 INFO - updating [=> ] 11500/184477 15m38s 17:36:07 INFO - updating [=> ] 12000/184477 15m14s 17:36:07 INFO - updating [==> ] 12300/184477 15m17s 17:36:07 INFO - updating [==> ] 12500/184477 15m16s 17:36:07 INFO - updating [==> ] 12800/184477 15m07s 17:36:07 INFO - updating [==> ] 13100/184477 15m02s 17:36:07 INFO - updating [==> ] 13200/184477 15m11s 17:36:07 INFO - updating [==> ] 13400/184477 15m18s 17:36:07 INFO - updating [==> ] 13800/184477 15m02s 17:36:07 INFO - updating [==> ] 14100/184477 14m57s 17:36:07 INFO - updating [==> ] 14300/184477 14m56s 17:36:07 INFO - updating [==> ] 14700/184477 14m52s 17:36:07 INFO - updating [==> ] 14900/184477 14m52s 17:36:07 INFO - updating [==> ] 15300/184477 14m43s 17:36:07 INFO - updating [==> ] 15800/184477 14m28s 17:36:07 INFO - updating [==> ] 16000/184477 14m30s 17:36:07 INFO - updating [===> ] 16300/184477 14m23s 17:36:07 INFO - updating [===> ] 16600/184477 14m18s 17:36:07 INFO - updating [===> ] 16900/184477 14m19s 17:36:07 INFO - updating [===> ] 17100/184477 14m19s 17:36:07 INFO - updating [===> ] 17500/184477 14m11s 17:36:07 INFO - updating [===> ] 17700/184477 14m10s 17:36:07 INFO - updating [===> ] 18100/184477 14m03s 17:36:07 INFO - updating [===> ] 18400/184477 13m58s 17:36:07 INFO - updating [===> ] 18700/184477 13m54s 17:36:07 INFO - updating [===> ] 19100/184477 13m48s 17:36:07 INFO - updating [===> ] 19400/184477 13m44s 17:36:07 INFO - updating [===> ] 19600/184477 13m47s 17:36:07 INFO - updating [===> ] 20000/184477 13m38s 17:36:07 INFO - updating [====> ] 20500/184477 13m28s 17:36:07 INFO - updating [====> ] 20600/184477 13m38s 17:36:07 INFO - updating [====> ] 20700/184477 13m43s 17:36:07 INFO - updating [====> ] 21200/184477 13m31s 17:36:07 INFO - updating [====> ] 21400/184477 13m32s 17:36:07 INFO - updating [====> ] 21600/184477 13m33s 17:36:07 INFO - updating [====> ] 21900/184477 13m30s 17:36:07 INFO - updating [====> ] 22400/184477 13m18s 17:36:07 INFO - updating [====> ] 23200/184477 12m54s 17:36:07 INFO - updating [====> ] 23400/184477 12m54s 17:36:07 INFO - updating [====> ] 23900/184477 12m44s 17:36:07 INFO - updating [=====> ] 24300/184477 12m37s 17:36:07 INFO - updating [=====> ] 24500/184477 12m39s 17:36:07 INFO - updating [=====> ] 24800/184477 12m37s 17:36:07 INFO - updating [=====> ] 25200/184477 12m29s 17:36:07 INFO - updating [=====> ] 25600/184477 12m23s 17:36:07 INFO - updating [=====> ] 26000/184477 12m18s 17:36:07 INFO - updating [=====> ] 26300/184477 12m15s 17:36:07 INFO - updating [=====> ] 26600/184477 12m14s 17:36:07 INFO - updating [=====> ] 26900/184477 12m12s 17:36:07 INFO - updating [=====> ] 27200/184477 12m12s 17:36:07 INFO - updating [=====> ] 27400/184477 12m13s 17:36:07 INFO - updating [=====> ] 27700/184477 12m11s 17:36:07 INFO - updating [=====> ] 28000/184477 12m08s 17:36:07 INFO - updating [======> ] 28400/184477 12m02s 17:36:07 INFO - updating [======> ] 28500/184477 12m14s 17:36:07 INFO - updating [======> ] 28900/184477 12m15s 17:36:07 INFO - updating [======> ] 29200/184477 12m15s 17:36:07 INFO - updating [======> ] 29400/184477 12m38s 17:36:07 INFO - updating [======> ] 29800/184477 12m33s 17:36:07 INFO - updating [======> ] 29900/184477 12m38s 17:36:07 INFO - updating [======> ] 30300/184477 12m33s 17:36:07 INFO - updating [======> ] 30500/184477 12m33s 17:36:07 INFO - updating [======> ] 30900/184477 12m27s 17:36:07 INFO - updating [======> ] 31300/184477 12m23s 17:36:07 INFO - updating [======> ] 31700/184477 12m21s 17:36:07 INFO - updating [======> ] 32000/184477 12m18s 17:36:07 INFO - updating [=======> ] 32400/184477 12m13s 17:36:07 INFO - updating [=======> ] 32800/184477 12m07s 17:36:07 INFO - updating [=======> ] 33100/184477 12m05s 17:36:07 INFO - updating [=======> ] 33400/184477 12m02s 17:36:07 INFO - updating [=======> ] 33500/184477 12m06s 17:36:07 INFO - updating [=======> ] 33900/184477 12m01s 17:36:07 INFO - updating [=======> ] 34300/184477 11m59s 17:36:07 INFO - updating [=======> ] 34600/184477 11m57s 17:36:07 INFO - updating [=======> ] 34900/184477 11m54s 17:36:07 INFO - updating [=======> ] 35200/184477 11m51s 17:36:07 INFO - updating [=======> ] 35700/184477 11m43s 17:36:07 INFO - updating [========> ] 36200/184477 11m36s 17:36:07 INFO - updating [========> ] 36500/184477 11m34s 17:36:07 INFO - updating [========> ] 36900/184477 11m29s 17:36:07 INFO - updating [========> ] 37200/184477 11m27s 17:36:07 INFO - updating [========> ] 37400/184477 11m27s 17:36:07 INFO - updating [========> ] 37700/184477 11m24s 17:36:07 INFO - updating [========> ] 38100/184477 11m20s 17:36:07 INFO - updating [========> ] 38700/184477 11m10s 17:36:07 INFO - updating [========> ] 39100/184477 11m06s 17:36:07 INFO - updating [========> ] 39200/184477 11m10s 17:36:07 INFO - updating [========> ] 39400/184477 11m09s 17:36:07 INFO - updating [========> ] 39700/184477 11m07s 17:36:07 INFO - updating [========> ] 39800/184477 11m09s 17:36:07 INFO - updating [========> ] 40000/184477 11m11s 17:36:07 INFO - updating [=========> ] 40300/184477 11m09s 17:36:07 INFO - updating [=========> ] 40400/184477 11m11s 17:36:07 INFO - updating [=========> ] 40700/184477 11m12s 17:36:07 INFO - updating [=========> ] 40900/184477 11m14s 17:36:07 INFO - updating [=========> ] 41300/184477 11m10s 17:36:07 INFO - updating [=========> ] 41400/184477 11m12s 17:36:07 INFO - updating [=========> ] 41600/184477 11m15s 17:36:07 INFO - updating [=========> ] 41800/184477 11m15s 17:36:07 INFO - updating [=========> ] 42200/184477 11m11s 17:36:07 INFO - updating [=========> ] 42300/184477 11m14s 17:36:07 INFO - updating [=========> ] 42600/184477 11m13s 17:36:07 INFO - updating [=========> ] 42900/184477 11m11s 17:36:07 INFO - updating [=========> ] 43000/184477 11m12s 17:36:07 INFO - updating [=========> ] 43300/184477 11m11s 17:36:07 INFO - updating [=========> ] 43500/184477 11m12s 17:36:07 INFO - updating [=========> ] 44000/184477 11m05s 17:36:07 INFO - updating [==========> ] 44200/184477 11m08s 17:36:07 INFO - updating [==========> ] 44400/184477 11m08s 17:36:07 INFO - updating [==========> ] 44600/184477 11m07s 17:36:07 INFO - updating [==========> ] 45000/184477 11m05s 17:36:07 INFO - updating [==========> ] 45500/184477 11m00s 17:36:07 INFO - updating [==========> ] 45800/184477 10m58s 17:36:07 INFO - updating [==========> ] 46000/184477 10m57s 17:36:07 INFO - updating [==========> ] 46300/184477 10m57s 17:36:07 INFO - updating [==========> ] 46600/184477 10m55s 17:36:07 INFO - updating [==========> ] 46900/184477 10m54s 17:36:07 INFO - updating [==========> ] 47100/184477 10m54s 17:36:07 INFO - updating [==========> ] 47500/184477 10m50s 17:36:07 INFO - updating [==========> ] 47700/184477 10m49s 17:36:07 INFO - updating [==========> ] 48000/184477 10m54s 17:36:07 INFO - updating [===========> ] 48400/184477 10m50s 17:36:07 INFO - updating [===========> ] 48500/184477 10m54s 17:36:07 INFO - updating [===========> ] 48800/184477 10m53s 17:36:07 INFO - updating [===========> ] 49000/184477 10m55s 17:36:07 INFO - updating [===========> ] 49200/184477 10m54s 17:36:07 INFO - updating [===========> ] 49600/184477 10m50s 17:36:07 INFO - updating [===========> ] 49800/184477 10m51s 17:36:07 INFO - updating [===========> ] 50000/184477 10m52s 17:36:07 INFO - updating [===========> ] 50200/184477 10m52s 17:36:07 INFO - updating [===========> ] 50700/184477 10m46s 17:36:07 INFO - updating [===========> ] 50900/184477 10m53s 17:36:07 INFO - updating [===========> ] 51200/184477 10m51s 17:36:07 INFO - updating [===========> ] 51400/184477 10m51s 17:36:07 INFO - updating [===========> ] 51700/184477 10m48s 17:36:07 INFO - updating [============> ] 52300/184477 10m47s 17:36:07 INFO - updating [============> ] 53200/184477 10m34s 17:36:07 INFO - updating [============> ] 53800/184477 10m27s 17:36:07 INFO - updating [============> ] 54400/184477 10m20s 17:36:07 INFO - updating [============> ] 55000/184477 10m13s 17:36:07 INFO - updating [============> ] 55700/184477 10m05s 17:36:07 INFO - updating [=============> ] 56300/184477 9m58s 17:36:07 INFO - updating [=============> ] 56900/184477 9m52s 17:36:07 INFO - updating [=============> ] 57100/184477 9m52s 17:36:07 INFO - updating [=============> ] 57200/184477 9m56s 17:36:07 INFO - updating [=============> ] 57400/184477 9m59s 17:36:07 INFO - updating [=============> ] 57500/184477 10m00s 17:36:07 INFO - updating [=============> ] 57700/184477 10m00s 17:36:07 INFO - updating [=============> ] 57800/184477 10m06s 17:36:07 INFO - updating [=============> ] 58300/184477 10m01s 17:36:07 INFO - updating [=============> ] 58700/184477 9m58s 17:36:07 INFO - updating [==============> ] 59100/184477 9m54s 17:36:07 INFO - updating [==============> ] 59400/184477 9m57s 17:36:07 INFO - updating [==============> ] 59800/184477 9m54s 17:36:07 INFO - updating [==============> ] 60200/184477 9m51s 17:36:07 INFO - updating [==============> ] 60600/184477 9m48s 17:36:07 INFO - updating [==============> ] 61000/184477 9m45s 17:36:07 INFO - updating [==============> ] 61300/184477 9m43s 17:36:07 INFO - updating [==============> ] 61800/184477 9m38s 17:36:07 INFO - updating [==============> ] 62200/184477 9m35s 17:36:07 INFO - updating [==============> ] 62700/184477 9m31s 17:36:07 INFO - updating [===============> ] 63200/184477 9m27s 17:36:07 INFO - updating [===============> ] 63800/184477 9m21s 17:36:07 INFO - updating [===============> ] 64400/184477 9m15s 17:36:07 INFO - updating [===============> ] 64900/184477 9m10s 17:36:07 INFO - updating [===============> ] 65400/184477 9m06s 17:36:07 INFO - updating [===============> ] 65600/184477 9m06s 17:36:07 INFO - updating [===============> ] 65900/184477 9m04s 17:36:07 INFO - updating [===============> ] 66300/184477 9m01s 17:36:07 INFO - updating [================> ] 66800/184477 8m56s 17:36:07 INFO - updating [================> ] 67400/184477 8m51s 17:36:07 INFO - updating [================> ] 67900/184477 8m46s 17:36:07 INFO - updating [================> ] 68500/184477 8m41s 17:36:07 INFO - updating [================> ] 69000/184477 8m36s 17:36:07 INFO - updating [================> ] 69500/184477 8m32s 17:36:07 INFO - updating [================> ] 70000/184477 8m29s 17:36:07 INFO - updating [================> ] 70600/184477 8m24s 17:36:07 INFO - updating [=================> ] 71100/184477 8m20s 17:36:07 INFO - updating [=================> ] 71500/184477 8m17s 17:36:07 INFO - updating [=================> ] 72100/184477 8m12s 17:36:07 INFO - updating [=================> ] 72600/184477 8m08s 17:36:07 INFO - updating [=================> ] 73000/184477 8m05s 17:36:07 INFO - updating [=================> ] 73500/184477 8m02s 17:36:07 INFO - updating [=================> ] 73900/184477 7m59s 17:36:07 INFO - updating [=================> ] 74400/184477 7m55s 17:36:07 INFO - updating [==================> ] 75000/184477 7m51s 17:36:07 INFO - updating [==================> ] 75400/184477 7m48s 17:36:07 INFO - updating [==================> ] 75900/184477 7m45s 17:36:07 INFO - updating [==================> ] 76400/184477 7m42s 17:36:07 INFO - updating [==================> ] 77000/184477 7m37s 17:36:07 INFO - updating [==================> ] 77600/184477 7m33s 17:36:07 INFO - updating [==================> ] 78000/184477 7m30s 17:36:07 INFO - updating [==================> ] 78400/184477 7m28s 17:36:07 INFO - updating [===================> ] 78800/184477 7m25s 17:36:07 INFO - updating [===================> ] 79300/184477 7m22s 17:36:07 INFO - updating [===================> ] 79700/184477 7m20s 17:36:07 INFO - updating [===================> ] 80100/184477 7m18s 17:36:07 INFO - updating [===================> ] 80500/184477 7m15s 17:36:07 INFO - updating [===================> ] 81000/184477 7m12s 17:36:07 INFO - updating [===================> ] 81500/184477 7m09s 17:36:07 INFO - updating [===================> ] 81900/184477 7m07s 17:36:07 INFO - updating [====================> ] 82500/184477 7m03s 17:36:07 INFO - updating [====================> ] 83000/184477 7m00s 17:36:07 INFO - updating [====================> ] 83500/184477 6m57s 17:36:07 INFO - updating [====================> ] 84000/184477 6m54s 17:36:07 INFO - updating [====================> ] 84500/184477 6m51s 17:36:07 INFO - updating [====================> ] 84900/184477 6m49s 17:36:07 INFO - updating [====================> ] 85100/184477 6m48s 17:36:07 INFO - updating [====================> ] 85500/184477 6m46s 17:36:07 INFO - updating [====================> ] 85900/184477 6m44s 17:36:07 INFO - updating [====================> ] 86300/184477 6m43s 17:36:07 INFO - updating [=====================> ] 86800/184477 6m40s 17:36:07 INFO - updating [=====================> ] 87200/184477 6m37s 17:36:07 INFO - updating [=====================> ] 87500/184477 6m36s 17:36:07 INFO - updating [=====================> ] 87600/184477 6m36s 17:36:07 INFO - updating [=====================> ] 87800/184477 6m38s 17:36:07 INFO - updating [=====================> ] 88200/184477 6m36s 17:36:07 INFO - updating [=====================> ] 88700/184477 6m33s 17:36:07 INFO - updating [=====================> ] 89000/184477 6m32s 17:36:07 INFO - updating [=====================> ] 89300/184477 6m31s 17:36:07 INFO - updating [=====================> ] 89500/184477 6m30s 17:36:07 INFO - updating [=====================> ] 90000/184477 6m27s 17:36:07 INFO - updating [======================> ] 90300/184477 6m27s 17:36:07 INFO - updating [======================> ] 90500/184477 6m27s 17:36:07 INFO - updating [======================> ] 91000/184477 6m24s 17:36:07 INFO - updating [======================> ] 91400/184477 6m21s 17:36:07 INFO - updating [======================> ] 92100/184477 6m17s 17:36:07 INFO - updating [======================> ] 92600/184477 6m14s 17:36:07 INFO - updating [======================> ] 93000/184477 6m12s 17:36:07 INFO - updating [======================> ] 93800/184477 6m06s 17:36:07 INFO - updating [=======================> ] 94300/184477 6m03s 17:36:07 INFO - updating [=======================> ] 95100/184477 5m58s 17:36:07 INFO - updating [=======================> ] 95500/184477 5m56s 17:36:07 INFO - updating [=======================> ] 96000/184477 5m53s 17:36:07 INFO - updating [=======================> ] 96400/184477 5m52s 17:36:07 INFO - updating [=======================> ] 96800/184477 5m50s 17:36:07 INFO - updating [=======================> ] 97300/184477 5m48s 17:36:07 INFO - updating [=======================> ] 98000/184477 5m43s 17:36:07 INFO - updating [========================> ] 98600/184477 5m40s 17:36:07 INFO - updating [========================> ] 98700/184477 5m40s 17:36:07 INFO - updating [========================> ] 99200/184477 5m37s 17:36:07 INFO - updating [========================> ] 99600/184477 5m35s 17:36:07 INFO - updating [========================> ] 100000/184477 5m34s 17:36:07 INFO - updating [========================> ] 100100/184477 5m36s 17:36:07 INFO - updating [========================> ] 100600/184477 5m33s 17:36:07 INFO - updating [========================> ] 101200/184477 5m30s 17:36:07 INFO - updating [========================> ] 101800/184477 5m27s 17:36:07 INFO - updating [=========================> ] 102500/184477 5m22s 17:36:07 INFO - updating [=========================> ] 102700/184477 5m22s 17:36:07 INFO - updating [=========================> ] 103200/184477 5m19s 17:36:07 INFO - updating [=========================> ] 103700/184477 5m17s 17:36:07 INFO - updating [=========================> ] 104300/184477 5m13s 17:36:07 INFO - updating [=========================> ] 104700/184477 5m12s 17:36:07 INFO - updating [=========================> ] 105200/184477 5m09s 17:36:07 INFO - updating [=========================> ] 105700/184477 5m07s 17:36:07 INFO - updating [==========================> ] 106100/184477 5m05s 17:36:07 INFO - updating [==========================> ] 106700/184477 5m02s 17:36:07 INFO - updating [==========================> ] 107300/184477 4m59s 17:36:07 INFO - updating [==========================> ] 107700/184477 4m57s 17:36:07 INFO - updating [==========================> ] 108300/184477 4m54s 17:36:07 INFO - updating [==========================> ] 108700/184477 4m52s 17:36:07 INFO - updating [==========================> ] 109100/184477 4m50s 17:36:07 INFO - updating [==========================> ] 109600/184477 4m48s 17:36:07 INFO - updating [==========================> ] 109800/184477 4m47s 17:36:07 INFO - updating [===========================> ] 110200/184477 4m46s 17:36:07 INFO - updating [===========================> ] 110500/184477 4m44s 17:36:07 INFO - updating [===========================> ] 110900/184477 4m43s 17:36:07 INFO - updating [===========================> ] 111200/184477 4m42s 17:36:07 INFO - updating [===========================> ] 111500/184477 4m41s 17:36:07 INFO - updating [===========================> ] 112000/184477 4m39s 17:36:07 INFO - updating [===========================> ] 112500/184477 4m36s 17:36:07 INFO - updating [===========================> ] 112800/184477 4m35s 17:36:07 INFO - updating [===========================> ] 113200/184477 4m34s 17:36:07 INFO - updating [===========================> ] 113400/184477 4m33s 17:36:07 INFO - updating [===========================> ] 113600/184477 4m33s 17:36:07 INFO - updating [============================> ] 114000/184477 4m31s 17:36:07 INFO - updating [============================> ] 114400/184477 4m29s 17:36:07 INFO - updating [============================> ] 114600/184477 4m29s 17:36:07 INFO - updating [============================> ] 115000/184477 4m27s 17:36:07 INFO - updating [============================> ] 115100/184477 4m27s 17:36:07 INFO - updating [============================> ] 115300/184477 4m27s 17:36:07 INFO - updating [============================> ] 115600/184477 4m26s 17:36:07 INFO - updating [============================> ] 115700/184477 4m26s 17:36:07 INFO - updating [============================> ] 116000/184477 4m25s 17:36:07 INFO - updating [============================> ] 116200/184477 4m25s 17:36:07 INFO - updating [============================> ] 116600/184477 4m23s 17:36:07 INFO - updating [============================> ] 116800/184477 4m24s 17:36:07 INFO - updating [============================> ] 117200/184477 4m23s 17:36:07 INFO - updating [============================> ] 117400/184477 4m23s 17:36:07 INFO - updating [============================> ] 117600/184477 4m23s 17:36:07 INFO - updating [=============================> ] 117800/184477 4m22s 17:36:07 INFO - updating [=============================> ] 118000/184477 4m22s 17:36:07 INFO - updating [=============================> ] 118200/184477 4m21s 17:36:07 INFO - updating [=============================> ] 118500/184477 4m20s 17:36:07 INFO - updating [=============================> ] 119100/184477 4m17s 17:36:07 INFO - updating [=============================> ] 119600/184477 4m14s 17:36:07 INFO - updating [=============================> ] 120000/184477 4m13s 17:36:07 INFO - updating [=============================> ] 120100/184477 4m12s 17:36:07 INFO - updating [=============================> ] 120500/184477 4m11s 17:36:07 INFO - updating [=============================> ] 120700/184477 4m10s 17:36:07 INFO - updating [=============================> ] 121000/184477 4m09s 17:36:07 INFO - updating [=============================> ] 121200/184477 4m09s 17:36:07 INFO - updating [=============================> ] 121600/184477 4m08s 17:36:07 INFO - updating [==============================> ] 121900/184477 4m07s 17:36:07 INFO - updating [==============================> ] 122100/184477 4m07s 17:36:07 INFO - updating [==============================> ] 122600/184477 4m04s 17:36:07 INFO - updating [==============================> ] 122700/184477 4m04s 17:36:07 INFO - updating [==============================> ] 122900/184477 4m04s 17:36:07 INFO - updating [==============================> ] 123200/184477 4m02s 17:36:07 INFO - updating [==============================> ] 123500/184477 4m02s 17:36:07 INFO - updating [==============================> ] 123700/184477 4m01s 17:36:07 INFO - updating [==============================> ] 123900/184477 4m00s 17:36:07 INFO - updating [==============================> ] 124000/184477 4m01s 17:36:07 INFO - updating [==============================> ] 124200/184477 4m00s 17:36:07 INFO - updating [==============================> ] 124600/184477 3m58s 17:36:07 INFO - updating [==============================> ] 125000/184477 3m57s 17:36:07 INFO - updating [==============================> ] 125200/184477 3m56s 17:36:07 INFO - updating [==============================> ] 125400/184477 3m56s 17:36:07 INFO - updating [==============================> ] 125500/184477 3m56s 17:36:07 INFO - updating [==============================> ] 125600/184477 3m56s 17:36:07 INFO - updating [===============================> ] 126100/184477 3m53s 17:36:07 INFO - updating [===============================> ] 126200/184477 3m53s 17:36:07 INFO - updating [===============================> ] 126400/184477 3m53s 17:36:07 INFO - updating [===============================> ] 126500/184477 3m53s 17:36:07 INFO - updating [===============================> ] 126900/184477 3m51s 17:36:07 INFO - updating [===============================> ] 127000/184477 3m52s 17:36:07 INFO - updating [===============================> ] 127300/184477 3m51s 17:36:07 INFO - updating [===============================> ] 127600/184477 3m49s 17:36:07 INFO - updating [===============================> ] 127900/184477 3m48s 17:36:07 INFO - updating [===============================> ] 128100/184477 3m48s 17:36:07 INFO - updating [===============================> ] 128300/184477 3m47s 17:36:07 INFO - updating [===============================> ] 128800/184477 3m46s 17:36:07 INFO - updating [===============================> ] 129000/184477 3m45s 17:36:07 INFO - updating [===============================> ] 129300/184477 3m44s 17:36:07 INFO - updating [===============================> ] 129400/184477 3m44s 17:36:07 INFO - updating [================================> ] 129600/184477 3m43s 17:36:07 INFO - updating [================================> ] 129700/184477 3m43s 17:36:07 INFO - updating [================================> ] 130100/184477 3m41s 17:36:07 INFO - updating [================================> ] 130200/184477 3m42s 17:36:07 INFO - updating [================================> ] 130500/184477 3m40s 17:36:07 INFO - updating [================================> ] 130900/184477 3m39s 17:36:07 INFO - updating [================================> ] 131100/184477 3m38s 17:36:07 INFO - updating [================================> ] 131300/184477 3m37s 17:36:07 INFO - updating [================================> ] 131600/184477 3m36s 17:36:07 INFO - updating [================================> ] 131900/184477 3m35s 17:36:07 INFO - updating [================================> ] 132300/184477 3m33s 17:36:07 INFO - updating [================================> ] 132400/184477 3m33s 17:36:07 INFO - updating [================================> ] 132600/184477 3m33s 17:36:07 INFO - updating [================================> ] 133000/184477 3m31s 17:36:07 INFO - updating [=================================> ] 133500/184477 3m29s 17:36:07 INFO - updating [=================================> ] 133700/184477 3m28s 17:36:07 INFO - updating [=================================> ] 134000/184477 3m27s 17:36:07 INFO - updating [=================================> ] 134300/184477 3m26s 17:36:07 INFO - updating [=================================> ] 134400/184477 3m26s 17:36:07 INFO - updating [=================================> ] 134700/184477 3m24s 17:36:07 INFO - updating [=================================> ] 135200/184477 3m22s 17:36:07 INFO - updating [=================================> ] 135400/184477 3m22s 17:36:07 INFO - updating [=================================> ] 136000/184477 3m19s 17:36:07 INFO - updating [=================================> ] 136500/184477 3m16s 17:36:07 INFO - updating [=================================> ] 136700/184477 3m16s 17:36:07 INFO - updating [=================================> ] 137000/184477 3m15s 17:36:07 INFO - updating [=================================> ] 137200/184477 3m14s 17:36:07 INFO - updating [==================================> ] 137400/184477 3m14s 17:36:07 INFO - updating [==================================> ] 137600/184477 3m13s 17:36:07 INFO - updating [==================================> ] 137800/184477 3m12s 17:36:07 INFO - updating [==================================> ] 138000/184477 3m12s 17:36:07 INFO - updating [==================================> ] 138200/184477 3m11s 17:36:07 INFO - updating [==================================> ] 138600/184477 3m10s 17:36:07 INFO - updating [==================================> ] 138900/184477 3m09s 17:36:07 INFO - updating [==================================> ] 139100/184477 3m08s 17:36:07 INFO - updating [==================================> ] 139400/184477 3m06s 17:36:07 INFO - updating [==================================> ] 139500/184477 3m06s 17:36:07 INFO - updating [==================================> ] 139700/184477 3m06s 17:36:07 INFO - updating [==================================> ] 139900/184477 3m05s 17:36:07 INFO - updating [==================================> ] 140300/184477 3m04s 17:36:07 INFO - updating [==================================> ] 140600/184477 3m03s 17:36:07 INFO - updating [==================================> ] 140800/184477 3m02s 17:36:07 INFO - updating [==================================> ] 141100/184477 3m01s 17:36:07 INFO - updating [==================================> ] 141200/184477 3m01s 17:36:07 INFO - updating [===================================> ] 141600/184477 2m59s 17:36:07 INFO - updating [===================================> ] 141700/184477 2m59s 17:36:07 INFO - updating [===================================> ] 142100/184477 2m58s 17:36:07 INFO - updating [===================================> ] 142400/184477 2m56s 17:36:07 INFO - updating [===================================> ] 142700/184477 2m55s 17:36:07 INFO - updating [===================================> ] 143000/184477 2m54s 17:36:07 INFO - updating [===================================> ] 143200/184477 2m53s 17:36:07 INFO - updating [===================================> ] 143700/184477 2m51s 17:36:07 INFO - updating [===================================> ] 143900/184477 2m50s 17:36:07 INFO - updating [===================================> ] 144100/184477 2m50s 17:36:07 INFO - updating [===================================> ] 144500/184477 2m49s 17:36:07 INFO - updating [===================================> ] 144900/184477 2m48s 17:36:07 INFO - updating [====================================> ] 145400/184477 2m45s 17:36:07 INFO - updating [====================================> ] 145500/184477 2m45s 17:36:07 INFO - updating [====================================> ] 146200/184477 2m42s 17:36:07 INFO - updating [====================================> ] 146800/184477 2m39s 17:36:07 INFO - updating [====================================> ] 147200/184477 2m37s 17:36:07 INFO - updating [====================================> ] 147500/184477 2m36s 17:36:07 INFO - updating [====================================> ] 147900/184477 2m34s 17:36:07 INFO - updating [====================================> ] 148500/184477 2m31s 17:36:07 INFO - updating [====================================> ] 148600/184477 2m31s 17:36:07 INFO - updating [====================================> ] 148900/184477 2m30s 17:36:07 INFO - updating [=====================================> ] 149400/184477 2m28s 17:36:07 INFO - updating [=====================================> ] 149700/184477 2m26s 17:36:07 INFO - updating [=====================================> ] 150100/184477 2m25s 17:36:07 INFO - updating [=====================================> ] 150400/184477 2m23s 17:36:07 INFO - updating [=====================================> ] 150800/184477 2m22s 17:36:07 INFO - updating [=====================================> ] 151200/184477 2m20s 17:36:07 INFO - updating [=====================================> ] 151700/184477 2m17s 17:36:07 INFO - updating [=====================================> ] 152300/184477 2m15s 17:36:07 INFO - updating [=====================================> ] 152600/184477 2m13s 17:36:07 INFO - updating [=====================================> ] 152900/184477 2m12s 17:36:07 INFO - updating [======================================> ] 153300/184477 2m10s 17:36:07 INFO - updating [======================================> ] 153900/184477 2m08s 17:36:07 INFO - updating [======================================> ] 154500/184477 2m05s 17:36:07 INFO - updating [======================================> ] 155000/184477 2m03s 17:36:07 INFO - updating [======================================> ] 155600/184477 2m00s 17:36:07 INFO - updating [======================================> ] 155900/184477 1m59s 17:36:07 INFO - updating [======================================> ] 156300/184477 1m57s 17:36:07 INFO - updating [======================================> ] 156500/184477 1m56s 17:36:07 INFO - updating [======================================> ] 156900/184477 1m54s 17:36:07 INFO - updating [=======================================> ] 157100/184477 1m54s 17:36:07 INFO - updating [=======================================> ] 157600/184477 1m52s 17:36:07 INFO - updating [=======================================> ] 158100/184477 1m49s 17:36:07 INFO - updating [=======================================> ] 158500/184477 1m48s 17:36:07 INFO - updating [=======================================> ] 158900/184477 1m46s 17:36:07 INFO - updating [=======================================> ] 159200/184477 1m45s 17:36:07 INFO - updating [=======================================> ] 159800/184477 1m42s 17:36:07 INFO - updating [=======================================> ] 160300/184477 1m40s 17:36:07 INFO - updating [=======================================> ] 160700/184477 1m38s 17:36:07 INFO - updating [========================================> ] 161100/184477 1m36s 17:36:07 INFO - updating [========================================> ] 161300/184477 1m36s 17:36:07 INFO - updating [========================================> ] 161600/184477 1m35s 17:36:07 INFO - updating [========================================> ] 161800/184477 1m34s 17:36:07 INFO - updating [========================================> ] 162100/184477 1m33s 17:36:07 INFO - updating [========================================> ] 162500/184477 1m31s 17:36:07 INFO - updating [========================================> ] 163000/184477 1m29s 17:36:07 INFO - updating [========================================> ] 163400/184477 1m27s 17:36:07 INFO - updating [========================================> ] 163800/184477 1m25s 17:36:07 INFO - updating [========================================> ] 164200/184477 1m24s 17:36:07 INFO - updating [========================================> ] 164500/184477 1m23s 17:36:07 INFO - updating [========================================> ] 164800/184477 1m21s 17:36:07 INFO - updating [=========================================> ] 165100/184477 1m20s 17:36:07 INFO - updating [=========================================> ] 165400/184477 1m19s 17:36:07 INFO - updating [=========================================> ] 165800/184477 1m17s 17:36:07 INFO - updating [=========================================> ] 166000/184477 1m16s 17:36:07 INFO - updating [=========================================> ] 166400/184477 1m15s 17:36:07 INFO - updating [=========================================> ] 166500/184477 1m14s 17:36:07 INFO - updating [=========================================> ] 166600/184477 1m14s 17:36:07 INFO - updating [=========================================> ] 166900/184477 1m13s 17:36:07 INFO - updating [=========================================> ] 167300/184477 1m11s 17:36:07 INFO - updating [=========================================> ] 167600/184477 1m10s 17:36:07 INFO - updating [=========================================> ] 167900/184477 1m09s 17:36:07 INFO - updating [=========================================> ] 168200/184477 1m08s 17:36:07 INFO - updating [=========================================> ] 168500/184477 1m06s 17:36:07 INFO - updating [==========================================> ] 168900/184477 1m05s 17:36:07 INFO - updating [==========================================> ] 169200/184477 1m04s 17:36:07 INFO - updating [==========================================> ] 169600/184477 1m02s 17:36:07 INFO - updating [==========================================> ] 170000/184477 1m00s 17:36:07 INFO - updating [============================================> ] 170300/184477 59s 17:36:07 INFO - updating [============================================> ] 170600/184477 58s 17:36:07 INFO - updating [============================================> ] 170900/184477 56s 17:36:07 INFO - updating [============================================> ] 171200/184477 55s 17:36:07 INFO - updating [============================================> ] 171500/184477 54s 17:36:07 INFO - updating [============================================> ] 172000/184477 52s 17:36:07 INFO - updating [============================================> ] 172500/184477 50s 17:36:07 INFO - updating [============================================> ] 172800/184477 49s 17:36:07 INFO - updating [=============================================> ] 173300/184477 46s 17:36:07 INFO - updating [=============================================> ] 173600/184477 45s 17:36:07 INFO - updating [=============================================> ] 173800/184477 44s 17:36:07 INFO - updating [=============================================> ] 173900/184477 44s 17:36:07 INFO - updating [=============================================> ] 174100/184477 43s 17:36:07 INFO - updating [=============================================> ] 174400/184477 42s 17:36:07 INFO - updating [=============================================> ] 174600/184477 41s 17:36:07 INFO - updating [=============================================> ] 175000/184477 40s 17:36:07 INFO - updating [=============================================> ] 175100/184477 39s 17:36:07 INFO - updating [=============================================> ] 175300/184477 38s 17:36:07 INFO - updating [=============================================> ] 175700/184477 37s 17:36:07 INFO - updating [=============================================> ] 175800/184477 36s 17:36:07 INFO - updating [=============================================> ] 176000/184477 36s 17:36:07 INFO - updating [=============================================> ] 176200/184477 35s 17:36:07 INFO - updating [=============================================> ] 176600/184477 33s 17:36:07 INFO - updating [=============================================> ] 176900/184477 32s 17:36:07 INFO - updating [==============================================> ] 177100/184477 31s 17:36:07 INFO - updating [==============================================> ] 177300/184477 30s 17:36:07 INFO - updating [==============================================> ] 177500/184477 29s 17:36:07 INFO - updating [==============================================> ] 177600/184477 29s 17:36:07 INFO - updating [==============================================> ] 177800/184477 28s 17:36:07 INFO - updating [==============================================> ] 178100/184477 27s 17:36:07 INFO - updating [==============================================> ] 178300/184477 26s 17:36:07 INFO - updating [==============================================> ] 178600/184477 25s 17:36:07 INFO - updating [==============================================> ] 178800/184477 24s 17:36:07 INFO - updating [==============================================> ] 179100/184477 23s 17:36:07 INFO - updating [==============================================> ] 179300/184477 22s 17:36:07 INFO - updating [==============================================> ] 179500/184477 21s 17:36:07 INFO - updating [==============================================> ] 179800/184477 20s 17:36:07 INFO - updating [==============================================> ] 180100/184477 19s 17:36:07 INFO - updating [==============================================> ] 180500/184477 17s 17:36:07 INFO - updating [==============================================> ] 180700/184477 16s 17:36:07 INFO - updating [===============================================> ] 181000/184477 15s 17:36:07 INFO - updating [===============================================> ] 181300/184477 14s 17:36:07 INFO - updating [===============================================> ] 181500/184477 13s 17:36:07 INFO - updating [===============================================> ] 181800/184477 12s 17:36:07 INFO - updating [===============================================> ] 182100/184477 10s 17:36:07 INFO - updating [===============================================> ] 182300/184477 10s 17:36:07 INFO - updating [===============================================> ] 182500/184477 09s 17:36:07 INFO - updating [===============================================> ] 182700/184477 08s 17:36:07 INFO - updating [===============================================> ] 182900/184477 07s 17:36:07 INFO - updating [===============================================> ] 183100/184477 06s 17:36:07 INFO - updating [===============================================> ] 183500/184477 05s 17:36:07 INFO - updating [===============================================> ] 183700/184477 04s 17:36:07 INFO - updating [===============================================> ] 184000/184477 03s 17:36:07 INFO - updating [===============================================> ] 184200/184477 02s 17:36:07 INFO - updating [================================================>] 184477/184477 01s 17:36:07 INFO - 17:36:07 INFO - adding remote bookmark aurora 17:36:07 INFO - adding remote bookmark beta 17:36:07 INFO - adding remote bookmark central 17:36:07 INFO - adding remote bookmark esr10 17:36:07 INFO - adding remote bookmark esr17 17:36:07 INFO - adding remote bookmark esr24 17:36:07 INFO - adding remote bookmark esr31 17:36:07 INFO - adding remote bookmark esr38 17:36:07 INFO - adding remote bookmark esr45 17:36:07 INFO - adding remote bookmark esr52 17:36:07 INFO - adding remote bookmark fx-team 17:36:07 INFO - adding remote bookmark inbound 17:36:07 INFO - adding remote bookmark release 17:36:07 INFO - 184477 files updated, 0 files merged, 0 files removed, 0 files unresolved 17:36:07 INFO - updated to 798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 17:36:07 INFO - Return code: 0 17:36:07 INFO - Setting buildbot property comments to Bug 1349310 - Update graphite2 to release 1.3.10. r=milan, a=dveditz 17:36:07 INFO - Writing buildbot properties ['comments'] to c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\comments 17:36:07 INFO - Writing to file c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\comments 17:36:07 INFO - Contents: 17:36:07 INFO - comments:Bug 1349310 - Update graphite2 to release 1.3.10. r=milan, a=dveditz 17:36:07 INFO - Setting buildbot property got_revision to 798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 17:36:07 INFO - Writing buildbot properties ['got_revision'] to c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\got_revision 17:36:07 INFO - Writing to file c:\builds\moz2_slave\m-beta-w32-devedition-00000000\properties\got_revision 17:36:07 INFO - Contents: 17:36:07 INFO - got_revision:798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 17:36:09 INFO - [mozharness: 2017-05-09 00:36:09.999000Z] Finished checkout-sources step (success) 17:36:10 INFO - [mozharness: 2017-05-09 00:36:10Z] Skipping setup-mock step. 17:36:10 INFO - [mozharness: 2017-05-09 00:36:10Z] Running build step. 17:36:10 INFO - Running main action method: build 17:36:10 INFO - removing old packages... 17:36:10 INFO - There wasn't any old packages to remove. 17:36:10 INFO - Using in-tree mozconfig 17:36:10 INFO - Reading from file c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\src\browser/config/mozconfigs/win32/devedition 17:36:10 INFO - Contents: 17:36:10 INFO - if [ -n "$ENABLE_RELEASE_PROMOTION" ]; then 17:36:10 INFO - MOZ_AUTOMATION_UPLOAD_SYMBOLS=1 17:36:10 INFO - MOZ_AUTOMATION_UPDATE_PACKAGING=1 17:36:10 INFO - fi 17:36:10 INFO - 17:36:10 INFO - . "$topsrcdir/build/mozconfig.win-common" 17:36:10 INFO - . "$topsrcdir/browser/config/mozconfigs/win32/common-opt" 17:36:10 INFO - 17:36:10 INFO - # Add-on signing is not required for DevEdition 17:36:10 INFO - MOZ_REQUIRE_SIGNING=0 17:36:10 INFO - 17:36:10 INFO - ac_add_options --enable-verify-mar 17:36:10 INFO - 17:36:10 INFO - ac_add_options --with-branding=browser/branding/aurora 17:36:10 INFO - 17:36:10 INFO - mk_add_options MOZ_PGO=1 17:36:10 INFO - 17:36:10 INFO - . "$topsrcdir/build/mozconfig.common.override" 17:36:10 INFO - . "$topsrcdir/build/mozconfig.cache" 17:36:10 INFO - mozconfig content: 17:36:10 INFO - if [ -n "$ENABLE_RELEASE_PROMOTION" ]; then 17:36:10 INFO - MOZ_AUTOMATION_UPLOAD_SYMBOLS=1 17:36:10 INFO - MOZ_AUTOMATION_UPDATE_PACKAGING=1 17:36:10 INFO - fi 17:36:10 INFO - 17:36:10 INFO - . "$topsrcdir/build/mozconfig.win-common" 17:36:10 INFO - . "$topsrcdir/browser/config/mozconfigs/win32/common-opt" 17:36:10 INFO - 17:36:10 INFO - # Add-on signing is not required for DevEdition 17:36:10 INFO - MOZ_REQUIRE_SIGNING=0 17:36:10 INFO - 17:36:10 INFO - ac_add_options --enable-verify-mar 17:36:10 INFO - 17:36:10 INFO - ac_add_options --with-branding=browser/branding/aurora 17:36:10 INFO - 17:36:10 INFO - mk_add_options MOZ_PGO=1 17:36:10 INFO - 17:36:10 INFO - . "$topsrcdir/build/mozconfig.common.override" 17:36:10 INFO - . "$topsrcdir/build/mozconfig.cache" 17:36:10 INFO - Copying c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\src\browser/config/mozconfigs/win32/devedition to c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\src\.mozconfig 17:36:10 INFO - ['sh', 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\tools\\scripts\\tooltool\\tooltool_wrapper.sh', 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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'] 17:36:10 INFO - Running command: ['sh', 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\build\\tools\\scripts\\tooltool\\tooltool_wrapper.sh', 'c:\\builds\\moz2_slave\\m-beta-w32-devedition-00000000\\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-beta-w32-devedition-00000000\build\src 17:36:10 INFO - Copy/paste: sh c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\tools\scripts\tooltool\tooltool_wrapper.sh c:\builds\moz2_slave\m-beta-w32-devedition-00000000\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 17:36:10 INFO - c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\src\browser/config/tooltool-manifests/win32/releng.manifest content 17:36:10 INFO - ======================================================= 17:36:10 INFO - [ 17:36:10 INFO - { 17:36:10 INFO - "size": 266240, 17:36:10 INFO - "digest": "bb345b0e700ffab4d09436981f14b5de84da55a3f18a7f09ebc4364a4488acdeab8d46f447b12ac70f2da1444a68b8ce8b8675f0dae2ccf845e966d1df0f0869", 17:36:10 INFO - "algorithm": "sha512", 17:36:10 INFO - "filename": "mozmake.exe" 17:36:10 INFO - }, 17:36:10 INFO - { 17:36:10 INFO - "version": "rustc 1.15.1 (021bd294c 2017-02-08) repack", 17:36:10 INFO - "size": 67227255, 17:36:10 INFO - "digest": "95164dc1abb2ab90ea18cf4f322d5c8390e0212e79a52b12578720d568eb6df62e55a9938c8d2a364cd3a6bd0f99608c2139d141a88ec43484b8d0651398532a", 17:36:10 INFO - "algorithm": "sha512", 17:36:10 INFO - "filename": "rustc.tar.bz2", 17:36:10 INFO - "unpack": true 17:36:10 INFO - }, 17:36:10 INFO - { 17:36:10 INFO - "version": "sccache rev b21198a7183a2fe226ff49348b1c0b51bae9f4f8", 17:36:10 INFO - "algorithm": "sha512", 17:36:10 INFO - "visibility": "public", 17:36:10 INFO - "filename": "sccache2.tar.bz2", 17:36:10 INFO - "unpack": true, 17:36:10 INFO - "digest": "7dee5c5602b3830cb8ac45ebaa8542714bbac0e50eabbff58a06972a02ceeab75ed7c56ff22a23f760b8317ae8e9a01cdecfaf75a7acbd2a4cdd817967170d2e", 17:36:10 INFO - "size": 1179901 17:36:10 INFO - }, 17:36:10 INFO - { 17:36:10 INFO - "version": "Visual Studio 2015 Update 3 14.0.25425.01 / SDK 10.0.14393.0", 17:36:10 INFO - "size": 326656969, 17:36:10 INFO - "digest": "babc414ffc0457d27f5a1ed24a8e4873afbe2f1c1a4075469a27c005e1babc3b2a788f643f825efedff95b79686664c67ec4340ed535487168a3482e68559bc7", 17:36:10 INFO - "algorithm": "sha512", 17:36:10 INFO - "filename": "vs2015u3.zip", 17:36:10 INFO - "unpack": true 17:36:10 INFO - }, 17:36:10 INFO - { 17:36:10 INFO - "version": "makecab rev d2bc6797648b7a834782714a55d339d2fd4e58c8", 17:36:10 INFO - "algorithm": "sha512", 17:36:10 INFO - "visibility": "public", 17:36:10 INFO - "filename": "makecab.tar.bz2", 17:36:10 INFO - "unpack": true, 17:36:10 INFO - "digest": "196ac6a567c85559957dfe511c3d8654d23c94d5603259e19ccafe9d71e0e4ccee63ccc9a778f2699654b786cda54266108b7d4db543d01bb0b42545b4e6ec75", 17:36:10 INFO - "size": 297118 17:36:10 INFO - } 17:36:10 INFO - ] 17:36:10 INFO - ======================================================= 17:36:10 INFO - Fetching... 17:36:11 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-beta-w32-devedition-00000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'] 17:36:11 INFO - INFO - File mozmake.exe retrieved from local cache c:/builds/tooltool_cache 17:36:11 INFO - INFO - File rustc.tar.bz2 not present in local cache folder c:/builds/tooltool_cache 17:36:11 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 17:36:14 INFO - INFO - File rustc.tar.bz2 fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\src\tmpqiqwij 17:36:14 INFO - INFO - File sccache2.tar.bz2 not present in local cache folder c:/builds/tooltool_cache 17:36:14 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 17:36:16 INFO - INFO - File sccache2.tar.bz2 fetched from https://api.pub.build.mozilla.org/tooltool/ as c:\builds\moz2_slave\m-beta-w32-devedition-00000000\build\src\tmpkcavtr 17:36:53 INFO - INFO - File vs2015u3.zip retrieved from local cache c:/builds/tooltool_cache 17:37:01 INFO - INFO - File makecab.tar.bz2 retrieved from local cache c:/builds/tooltool_cache 17:37:02 INFO - INFO - File integrity verified, renaming tmpqiqwij to rustc.tar.bz2 17:37:02 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 17:37:02 INFO - INFO - Local cache c:/builds/tooltool_cache updated with rustc.tar.bz2 17:37:02 INFO - INFO - File integrity verified, renaming tmpkcavtr to sccache2.tar.bz2 17:37:02 INFO - INFO - Updating local cache c:/builds/tooltool_cache... 17:37:02 INFO - INFO - Local cache c:/builds/tooltool_cache updated with sccache2.tar.bz2 17:37:02 INFO - INFO - unzipping "vs2015u3.zip" command interrupted, attempting to kill command interrupted, attempting to kill command interrupted, attempting to kill command interrupted, attempting to kill command interrupted, attempting to kill command interrupted, attempting to kill command interrupted, attempting to kill command interrupted, attempting to kill command interrupted, attempting to kill SIGKILL failed to kill process using fake rc=-1 program finished with exit code -1 remoteFailed: [Failure instance: Traceback from remote host -- Traceback (most recent call last): Failure: exceptions.RuntimeError: SIGKILL failed to kill process ] The web-page 'stop build' button was pressed by 'rvandermeulen@mozilla.com': Cancelled via self-serve The web-page 'stop build' button was pressed by 'rvandermeulen@mozilla.com': Cancelled via self-serve The web-page 'stop build' button was pressed by 'rvandermeulen@mozilla.com': Cancelled via self-serve The web-page 'stop build' button was pressed by 'rvandermeulen@mozilla.com': Cancelled via self-serve The web-page 'stop build' button was pressed by 'rvandermeulen@mozilla.com': Cancelled via self-serve The web-page 'stop build' button was pressed by 'rvandermeulen@mozilla.com': Cancelled via self-serve The web-page 'stop build' button was pressed by 'rvandermeulen@mozilla.com': Cancelled via self-serve The web-page 'stop build' button was pressed by 'rvandermeulen@mozilla.com': Cancelled via self-serve The web-page 'stop build' button was pressed by 'rvandermeulen@mozilla.com': Cancelled via self-serve

exceptions.RuntimeError: SIGKILL failed to kill process

exceptions.RuntimeError: SIGKILL failed to kill process

Traceback (most recent call last): Failure: exceptions.RuntimeError ========= Finished run_script exception (results: 4, elapsed: 16 mins, 35 secs) (at 2017-05-08 17:38:22.345761) ========= ========= Started set props: buildid got_revision comments builduid stage_platform (results: 0, elapsed: 12 secs) (at 2017-05-08 17:38:22.350261) ========= 'bash' '-c' 'for file in `ls -1`; do cat $file; done' in dir c:\builds\moz2_slave\m-beta-w32-devedition-00000000\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-118 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-118 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=16 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\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=3184 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056 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-118 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:20170508140042 builduid:72b4262d94a94c868f01ca2ea13b1629 comments:Bug 1349310 - Update graphite2 to release 1.3.10. r=milan, a=dveditz got_revision:798ee0e6f73eaed68e3f3968a20e9ae5c7a82718 stage_platform:win32-devedition program finished with exit code 0 elapsedTime=12.607000 buildid: '20170508140042' got_revision: '798ee0e6f73eaed68e3f3968a20e9ae5c7a82718' comments: 'Bug 1349310 - Update graphite2 to release 1.3.10. r=milan, a=dveditz' builduid: '72b4262d94a94c868f01ca2ea13b1629' stage_platform: 'win32-devedition' ========= master_lag: 0.04 ========= ========= Finished set props: buildid got_revision comments builduid stage_platform (results: 0, elapsed: 12 secs) (at 2017-05-08 17:38:35.000318) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 17:38:35.001316) ========= 'rm' '-f' 'oauth.txt' in dir c:\builds\moz2_slave\m-beta-w32-devedition-00000000\. (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-118 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-118 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=16 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\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=3184 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056 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-118 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.50 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 17:38:35.609968) ========= ========= Started reboot slave lost (results: 2, elapsed: 27 secs) (at 2017-05-08 17:38:35.610913) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/external_tools/count_and_reboot.py' '-f' '../reboot_count.txt' '-n' '1' '-z' in dir c:\builds\moz2_slave\m-beta-w32-devedition-00000000\. (timeout 1200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/external_tools/count_and_reboot.py', '-f', '../reboot_count.txt', '-n', '1', '-z'] 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-118 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-118 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_TOOLS=C:\mozilla-build\moztools-x64 MSYSTEM=MINGW32 NUMBER_OF_PROCESSORS=16 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\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 PDBSTR_PATH=/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe 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-beta-w32-devedition-00000000/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=3184 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-LUJiAs4056/agent.4056 SYSTEMDRIVE=C: SYSTEMROOT=C:\Windows TEMP=C:/Users/cltbld/AppData/Local/Temp TERM=cygwin TINDERBOX_OUTPUT=1 TMP=C:/Users/cltbld/AppData/Local/Temp TOOLTOOL_CACHE=/c/builds/tooltool_cache TOOLTOOL_HOME=/c/builds USERDOMAIN=B-2008-SPOT-118 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 ************************************************************************************************ *********** END OF RUN - NOW DOING SCHEDULED REBOOT; FOLLOWING ERROR MESSAGE EXPECTED ********** ************************************************************************************************ program finished with exit code 1073807364 elapsedTime=27.884000 ========= master_lag: 0.01 ========= ========= Finished reboot slave lost (results: 2, elapsed: 27 secs) (at 2017-05-08 17:39:03.508197) ========= ========= Total master_lag: 0.61 =========