builder: ash-win64-pgo slave: b-2008-spot-109 starttime: 1491280215.41 results: failure (2) buildid: 20170403213001 builduid: da2ce914d9c74097857d04e3439df6ce revision: 03438d67a48a8590220d787672054e81f77dc99c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:15.410283) ========= master: http://buildbot-master74.bb.releng.usw2.mozilla.com:8001/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:15.410729) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:15.411016) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:15.543343) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 21:30:15.543908) ========= '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\ash-w64-pgo-000000000000000000\. (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-109 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-109 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 62 Stepping 4, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3e04 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=3224 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/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-109 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-04-03 21:30:13-- 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% 45.0K=0.3s 2017-04-03 21:30:14 (45.0 KB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.713000 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 21:30:17.323386) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:17.327627) ========= 'rm' '-rf' 'scripts' 'properties' in dir c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\. (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-109 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-109 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 62 Stepping 4, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3e04 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=3224 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/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-109 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.107000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:17.442782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-03 21:30:17.475009) ========= 'bash' '-c' 'python archiver_client.py mozharness --repo projects/ash --rev 03438d67a48a8590220d787672054e81f77dc99c --destination scripts --debug' in dir c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/ash --rev 03438d67a48a8590220d787672054e81f77dc99c --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-109 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-109 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 62 Stepping 4, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3e04 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=3224 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/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-109 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-04-03 21:30:14,776 truncating revision to first 12 chars 2017-04-03 21:30:14,776 Setting DEBUG logging. 2017-04-03 21:30:14,776 attempt 1/10 2017-04-03 21:30:14,776 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/ash/03438d67a48a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-03 21:30:16,242 unpacking tar archive at: ash-03438d67a48a/testing/mozharness/ 2017-04-03 21:30:16,384 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.112000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-03 21:30:19.605802) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:19.606800) ========= script_repo_revision: 03438d67a48a8590220d787672054e81f77dc99c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:19.607174) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:19.607449) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:19.622323) ========= ========= Started remove old nonce (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:19.622602) ========= 'rm' '-f' 'nonce' in dir c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\. (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-109 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-109 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 62 Stepping 4, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3e04 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=3224 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/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-109 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.107000 ========= master_lag: 0.02 ========= ========= Finished remove old nonce (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:19.749845) ========= ========= Started downloading to token (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:19.750666) ========= Slave: b-2008-spot-109 IP: 10.132.53.47 Duration: 25200 URI: https://mac-v2-signing7.srv.releng.scl3.mozilla.com:9110/token ========= Finished downloading to token (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:20.035505) ========= ========= Started set props: toolsdir (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:20.035978) ========= 'bash' '-c' 'pwd' in dir c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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-109 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-109 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 62 Stepping 4, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3e04 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=3224 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/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-109 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/ash-w64-pgo-000000000000000000/scripts program finished with exit code 0 elapsedTime=0.107000 toolsdir: '/c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts' ========= master_lag: 0.01 ========= ========= Finished set props: toolsdir (results: 0, elapsed: 0 secs) (at 2017-04-03 21:30:20.151682) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 46 mins, 51 secs) (at 2017-04-03 21:30:20.154433) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/fx_desktop_build.py' '--config' 'builds/releng_base_windows_64_builds.py' '--config' 'balrog/production.py' '--branch' 'ash' '--build-pool' 'production' '--enable-pgo' in dir c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\. (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_64_builds.py', '--config', 'balrog/production.py', '--branch', 'ash', '--build-pool', 'production', '--enable-pgo'] 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-109 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-109 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:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110 MOZ_SIGN_CMD=python /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/signing_cache -t /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/token -n /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/nonce -c /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110 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 62 Stepping 4, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3e04 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/ash-w64-pgo-000000000000000000/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=3224 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/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-109 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 21:30:17 INFO - MultiFileLogger online at 20170403 21:30:17 in c:\builds\moz2_slave\ash-w64-pgo-000000000000000000 21:30:17 INFO - Script was run with '--branch ash' and 'ash' matches a key in 'builds/branch_specifics.py'. Updating self.config with items from that key's value. 21:30:17 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. 21:30:17 INFO - To generate a config file based upon options passed and config files used, run script as before but extend options with "--dump-config" 21:30:17 INFO - For a diff of where self.config got its items, run the script again as before but extend options with: "--dump-config-hierarchy" 21:30:17 INFO - Both --dump-config and --dump-config-hierarchy don't actually run any actions. 21:30:17 INFO - We are running this in buildbot, grab the build props 21:30:17 INFO - Using buildbot properties: 21:30:17 INFO - { 21:30:17 INFO - "project": "", 21:30:17 INFO - "product": "firefox", 21:30:17 INFO - "script_repo_revision": "production", 21:30:17 INFO - "scheduler": "ash periodic", 21:30:17 INFO - "repository": "", 21:30:17 INFO - "buildername": "WINNT 6.1 x86-64 ash pgo-build", 21:30:17 INFO - "buildid": "20170403213001", 21:30:17 INFO - "basedir": "/c/builds/moz2_slave/ash-w64-pgo-000000000000000000", 21:30:17 INFO - "buildnumber": 0, 21:30:17 INFO - "platform": "win64", 21:30:17 INFO - "master": "http://buildbot-master74.bb.releng.usw2.mozilla.com:8001/", 21:30:17 INFO - "branch": "ash", 21:30:17 INFO - "revision": "03438d67a48a8590220d787672054e81f77dc99c", 21:30:17 INFO - "slavename": "b-2008-spot-109", 21:30:17 INFO - "builduid": "da2ce914d9c74097857d04e3439df6ce", 21:30:17 INFO - "repo_path": "projects/ash" 21:30:17 INFO - } 21:30:17 INFO - Setting buildbot property stage_platform to win64-pgo 21:30:17 INFO - mkdir: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\properties 21:30:17 INFO - Writing buildbot properties ['stage_platform'] to c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\properties\stage_platform 21:30:17 INFO - Writing to file c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\properties\stage_platform 21:30:17 INFO - Contents: 21:30:17 INFO - stage_platform:win64-pgo 21:30:17 WARNING - Try message not found. 21:30:17 INFO - Run as scripts/scripts/fx_desktop_build.py --config builds/releng_base_windows_64_builds.py --config balrog/production.py --branch ash --build-pool production --enable-pgo 21:30:17 INFO - Determining buildid from buildbot properties 21:30:17 INFO - Setting buildbot property buildid to 20170403213001 21:30:17 INFO - Writing buildbot properties ['buildid'] to c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\properties\buildid 21:30:17 INFO - Writing to file c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\properties\buildid 21:30:17 INFO - Contents: 21:30:17 INFO - buildid:20170403213001 21:30:17 INFO - Determining builduid from buildbot properties 21:30:17 INFO - Setting buildbot property builduid to da2ce914d9c74097857d04e3439df6ce 21:30:17 INFO - Writing buildbot properties ['builduid'] to c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\properties\builduid 21:30:17 INFO - Writing to file c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\properties\builduid 21:30:17 INFO - Contents: 21:30:17 INFO - builduid:da2ce914d9c74097857d04e3439df6ce 21:30:17 INFO - repo_path not in config. Using 'projects/ash' instead 21:30:17 INFO - Creating virtualenv c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv 21:30:17 INFO - mkdir: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build 21:30:17 INFO - Running command: ['c:\\mozilla-build\\python27\\python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\venv'] in c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build 21:30:17 INFO - Copy/paste: c:\mozilla-build\python27\python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv 21:30:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:30:20 INFO - New python executable in c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv\Scripts\python.exe 21:30:27 INFO - Installing distribute................................................................................................................................................................................................done. 21:30:39 INFO - Installing pip....................................done. 21:30:39 INFO - Return code: 0 21:30:39 INFO - Getting output from command: ['c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\venv\\Scripts\\pip', '--version'] 21:30:39 INFO - Copy/paste: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv\Scripts\pip --version 21:30:41 INFO - Reading from file tmpfile_stdout 21:30:41 INFO - Output received: 21:30:41 INFO - pip 9.0.1 from c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg (python 2.7) 21:30:41 INFO - Using _rmtree_windows ... 21:30:41 INFO - Using _rmtree_windows ... 21:30:41 INFO - Installing requests==2.8.1 into virtualenv c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv 21:30:41 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\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 0x02619DC8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02746020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024FA210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0276F270>, '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 0x02777CB8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0260B9A0>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build', 'env': {'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'B-2008-SPOT-109', 'SYSTEMDRIVE': 'C:', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-109', 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 'MOZ_AUTOMATION': '1', 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 'HOSTTYPE': 'i686', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PWD': 'c:/Users/cltbld', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel', 'CVS_RSH': 'ssh', 'LOGNAME': 'cltbld', 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 'MOZ_OBJDIR': 'obj-firefox', 'SYSTEMROOT': 'C:\\Windows', 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'MSYSTEM': 'MINGW32', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'MAKE_MODE': 'unix', 'TERM': 'cygwin', 'SHELL': 'C:/mozilla-build/msys/bin/sh', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 'SHLVL': '1', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_ARCHITECTURE': 'x86', 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'EDITOR': 'emacs.exe', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'OSTYPE': 'msys', 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 'SSH_AGENT_PID': '3224', 'HOME': 'c:/Users/cltbld', 'PROGRAMW6432': 'C:\\Program Files', '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', 'PROMPT': '$P$G', 'WINDOWS_TRACING_FLAGS': '3', 'PROCESSOR_REVISION': '3e04', 'PROGRAMDATA': 'C:\\ProgramData', 'LOG': '"c:\\runner\\buildbot-startup.log"', 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/agent.4056', 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\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', 'IDLEIZER_HALT_ON_IDLE': 'true', 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 'TINDERBOX_OUTPUT': '1', 'LOGONSERVER': '\\\\B-2008-SPOT-109', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/signing_cache -t /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/token -n /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/nonce -c /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'HOMEPATH': '\\', 'MOZILLABUILD': 'C:\\mozilla-build', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\Windows', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 'HOMEDRIVE': 'C:', 'OS_VERSION': '2008', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'MOZBUILDDIR': 'C:\\mozilla-build', 'OLDPWD': 'c:/Users/cltbld', 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 'NUMBER_OF_PROCESSORS': '16', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'TOOLTOOL_HOME': '/c/builds', 'PROCESSOR_LEVEL': '6', 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'MACHTYPE': 'i686-pc-msys', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'USERPROFILE': 'C:\\Users\\cltbld'}}, attempt #1 21:30:41 INFO - Running command: ['c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'requests==2.8.1'] in c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build 21:30:41 INFO - Copy/paste: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv\Scripts\pip install --timeout 120 requests==2.8.1 21:30:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:30:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:30:41 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 21:30:41 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 21:30:41 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 21:30:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 21:30:41 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 21:30:41 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 21:30:41 INFO - 'COMPUTERNAME': 'B-2008-SPOT-109', 21:30:41 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 21:30:41 INFO - 'CVS_RSH': 'ssh', 21:30:41 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 21:30:41 INFO - 'EDITOR': 'emacs.exe', 21:30:41 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:30:41 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 21:30:41 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 21:30:41 INFO - 'HOME': 'c:/Users/cltbld', 21:30:41 INFO - 'HOMEDRIVE': 'C:', 21:30:41 INFO - 'HOMEPATH': '\\', 21:30:41 INFO - 'HOSTTYPE': 'i686', 21:30:41 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 21:30:41 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 21:30:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:30:41 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 21:30:41 INFO - 'LOGNAME': 'cltbld', 21:30:41 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-109', 21:30:41 INFO - 'MACHTYPE': 'i686-pc-msys', 21:30:41 INFO - 'MAKE_MODE': 'unix', 21:30:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 21:30:41 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 21:30:41 INFO - 'MOZ_AUTOMATION': '1', 21:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:41 INFO - 'MOZ_OBJDIR': 'obj-firefox', 21:30:41 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 21:30:41 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/signing_cache -t /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/token -n /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/nonce -c /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 21:30:41 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 21:30:41 INFO - 'MSYSTEM': 'MINGW32', 21:30:41 INFO - 'NUMBER_OF_PROCESSORS': '16', 21:30:41 INFO - 'OLDPWD': 'c:/Users/cltbld', 21:30:41 INFO - 'OS': 'Windows_NT', 21:30:41 INFO - 'OSTYPE': 'msys', 21:30:41 INFO - 'OS_VERSION': '2008', 21:30:41 INFO - 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\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', 21:30:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:30:41 INFO - 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 21:30:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:30:41 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 21:30:41 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel', 21:30:41 INFO - 'PROCESSOR_LEVEL': '6', 21:30:41 INFO - 'PROCESSOR_REVISION': '3e04', 21:30:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:30:41 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 21:30:41 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 21:30:41 INFO - 'PROGRAMW6432': 'C:\\Program Files', 21:30:41 INFO - 'PROMPT': '$P$G', 21:30:41 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\buildprops.json', 21:30:41 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 21:30:41 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 21:30:41 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:30:41 INFO - 'PWD': 'c:/Users/cltbld', 21:30:41 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 21:30:41 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 21:30:41 INFO - 'SHLVL': '1', 21:30:41 INFO - 'SSH_AGENT_PID': '3224', 21:30:41 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/agent.4056', 21:30:41 INFO - 'SYSTEMDRIVE': 'C:', 21:30:41 INFO - 'SYSTEMROOT': 'C:\\Windows', 21:30:41 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 21:30:41 INFO - 'TERM': 'cygwin', 21:30:41 INFO - 'TINDERBOX_OUTPUT': '1', 21:30:41 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 21:30:41 INFO - 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 21:30:41 INFO - 'TOOLTOOL_HOME': '/c/builds', 21:30:41 INFO - 'USERDOMAIN': 'B-2008-SPOT-109', 21:30:41 INFO - 'USERNAME': 'cltbld', 21:30:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:30:41 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 21:30:41 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 21:30:41 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 21:30:41 INFO - 'WINDIR': 'C:\\Windows', 21:30:41 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:30:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:30:41 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 21:30:41 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 21:30:42 INFO - Collecting requests==2.8.1 21:30:43 INFO - Using cached requests-2.8.1-py2.py3-none-any.whl 21:30:43 INFO - Installing collected packages: requests 21:30:43 INFO - Successfully installed requests-2.8.1 21:30:44 INFO - c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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. 21:30:44 INFO - SNIMissingWarning 21:30:44 INFO - c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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. 21:30:44 INFO - InsecurePlatformWarning 21:30:44 INFO - c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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. 21:30:44 INFO - InsecurePlatformWarning 21:30:44 INFO - Return code: 0 21:30:44 INFO - Installing PyHawk-with-a-single-extra-commit==0.1.5 into virtualenv c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv 21:30:44 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\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 0x02619DC8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02746020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024FA210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0276F270>, '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 0x02777CB8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0260B9A0>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build', 'env': {'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'B-2008-SPOT-109', 'SYSTEMDRIVE': 'C:', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-109', 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 'MOZ_AUTOMATION': '1', 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 'HOSTTYPE': 'i686', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PWD': 'c:/Users/cltbld', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel', 'CVS_RSH': 'ssh', 'LOGNAME': 'cltbld', 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 'MOZ_OBJDIR': 'obj-firefox', 'SYSTEMROOT': 'C:\\Windows', 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'MSYSTEM': 'MINGW32', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'MAKE_MODE': 'unix', 'TERM': 'cygwin', 'SHELL': 'C:/mozilla-build/msys/bin/sh', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 'SHLVL': '1', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_ARCHITECTURE': 'x86', 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'EDITOR': 'emacs.exe', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'OSTYPE': 'msys', 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 'SSH_AGENT_PID': '3224', 'HOME': 'c:/Users/cltbld', 'PROGRAMW6432': 'C:\\Program Files', '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', 'PROMPT': '$P$G', 'WINDOWS_TRACING_FLAGS': '3', 'PROCESSOR_REVISION': '3e04', 'PROGRAMDATA': 'C:\\ProgramData', 'LOG': '"c:\\runner\\buildbot-startup.log"', 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/agent.4056', 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\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', 'IDLEIZER_HALT_ON_IDLE': 'true', 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 'TINDERBOX_OUTPUT': '1', 'LOGONSERVER': '\\\\B-2008-SPOT-109', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/signing_cache -t /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/token -n /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/nonce -c /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'HOMEPATH': '\\', 'MOZILLABUILD': 'C:\\mozilla-build', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\Windows', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 'HOMEDRIVE': 'C:', 'OS_VERSION': '2008', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'MOZBUILDDIR': 'C:\\mozilla-build', 'OLDPWD': 'c:/Users/cltbld', 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 'NUMBER_OF_PROCESSORS': '16', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'TOOLTOOL_HOME': '/c/builds', 'PROCESSOR_LEVEL': '6', 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'MACHTYPE': 'i686-pc-msys', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'USERPROFILE': 'C:\\Users\\cltbld'}}, attempt #1 21:30:44 INFO - Running command: ['c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'PyHawk-with-a-single-extra-commit==0.1.5'] in c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build 21:30:44 INFO - Copy/paste: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv\Scripts\pip install --timeout 120 PyHawk-with-a-single-extra-commit==0.1.5 21:30:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:30:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:30:44 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 21:30:44 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 21:30:44 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 21:30:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 21:30:44 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 21:30:44 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 21:30:44 INFO - 'COMPUTERNAME': 'B-2008-SPOT-109', 21:30:44 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 21:30:44 INFO - 'CVS_RSH': 'ssh', 21:30:44 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 21:30:44 INFO - 'EDITOR': 'emacs.exe', 21:30:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:30:44 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 21:30:44 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 21:30:44 INFO - 'HOME': 'c:/Users/cltbld', 21:30:44 INFO - 'HOMEDRIVE': 'C:', 21:30:44 INFO - 'HOMEPATH': '\\', 21:30:44 INFO - 'HOSTTYPE': 'i686', 21:30:44 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 21:30:44 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 21:30:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:30:44 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 21:30:44 INFO - 'LOGNAME': 'cltbld', 21:30:44 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-109', 21:30:44 INFO - 'MACHTYPE': 'i686-pc-msys', 21:30:44 INFO - 'MAKE_MODE': 'unix', 21:30:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 21:30:44 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 21:30:44 INFO - 'MOZ_AUTOMATION': '1', 21:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:44 INFO - 'MOZ_OBJDIR': 'obj-firefox', 21:30:44 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 21:30:44 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/signing_cache -t /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/token -n /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/nonce -c /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 21:30:44 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 21:30:44 INFO - 'MSYSTEM': 'MINGW32', 21:30:44 INFO - 'NUMBER_OF_PROCESSORS': '16', 21:30:44 INFO - 'OLDPWD': 'c:/Users/cltbld', 21:30:44 INFO - 'OS': 'Windows_NT', 21:30:44 INFO - 'OSTYPE': 'msys', 21:30:44 INFO - 'OS_VERSION': '2008', 21:30:44 INFO - 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\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', 21:30:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:30:44 INFO - 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 21:30:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:30:44 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 21:30:44 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel', 21:30:44 INFO - 'PROCESSOR_LEVEL': '6', 21:30:44 INFO - 'PROCESSOR_REVISION': '3e04', 21:30:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:30:44 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 21:30:44 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 21:30:44 INFO - 'PROGRAMW6432': 'C:\\Program Files', 21:30:44 INFO - 'PROMPT': '$P$G', 21:30:44 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\buildprops.json', 21:30:44 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 21:30:44 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 21:30:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:30:44 INFO - 'PWD': 'c:/Users/cltbld', 21:30:44 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 21:30:44 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 21:30:44 INFO - 'SHLVL': '1', 21:30:44 INFO - 'SSH_AGENT_PID': '3224', 21:30:44 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/agent.4056', 21:30:44 INFO - 'SYSTEMDRIVE': 'C:', 21:30:44 INFO - 'SYSTEMROOT': 'C:\\Windows', 21:30:44 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 21:30:44 INFO - 'TERM': 'cygwin', 21:30:44 INFO - 'TINDERBOX_OUTPUT': '1', 21:30:44 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 21:30:44 INFO - 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 21:30:44 INFO - 'TOOLTOOL_HOME': '/c/builds', 21:30:44 INFO - 'USERDOMAIN': 'B-2008-SPOT-109', 21:30:44 INFO - 'USERNAME': 'cltbld', 21:30:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:30:44 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 21:30:44 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 21:30:44 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 21:30:44 INFO - 'WINDIR': 'C:\\Windows', 21:30:44 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:30:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:30:44 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 21:30:44 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 21:30:44 INFO - Collecting PyHawk-with-a-single-extra-commit==0.1.5 21:30:45 INFO - Using cached PyHawk-with-a-single-extra-commit-0.1.5.tar.gz 21:30:45 INFO - Installing collected packages: PyHawk-with-a-single-extra-commit 21:30:45 INFO - Running setup.py install for PyHawk-with-a-single-extra-commit: started 21:30:45 INFO - Running setup.py install for PyHawk-with-a-single-extra-commit: finished with status 'done' 21:30:46 INFO - Successfully installed PyHawk-with-a-single-extra-commit-0.1.5 21:30:46 INFO - c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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. 21:30:46 INFO - SNIMissingWarning 21:30:46 INFO - c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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. 21:30:46 INFO - InsecurePlatformWarning 21:30:46 INFO - Return code: 0 21:30:46 INFO - Installing taskcluster==0.0.26 into virtualenv c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv 21:30:46 INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\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 0x02619DC8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02746020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x024FA210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0276F270>, '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 0x02777CB8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0260B9A0>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build', 'env': {'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'B-2008-SPOT-109', 'SYSTEMDRIVE': 'C:', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-109', 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 'MOZ_AUTOMATION': '1', 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 'HOSTTYPE': 'i686', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PWD': 'c:/Users/cltbld', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel', 'CVS_RSH': 'ssh', 'LOGNAME': 'cltbld', 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 'MOZ_OBJDIR': 'obj-firefox', 'SYSTEMROOT': 'C:\\Windows', 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'MSYSTEM': 'MINGW32', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'MAKE_MODE': 'unix', 'TERM': 'cygwin', 'SHELL': 'C:/mozilla-build/msys/bin/sh', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 'SHLVL': '1', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_ARCHITECTURE': 'x86', 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'EDITOR': 'emacs.exe', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'OSTYPE': 'msys', 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 'SSH_AGENT_PID': '3224', 'HOME': 'c:/Users/cltbld', 'PROGRAMW6432': 'C:\\Program Files', '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', 'PROMPT': '$P$G', 'WINDOWS_TRACING_FLAGS': '3', 'PROCESSOR_REVISION': '3e04', 'PROGRAMDATA': 'C:\\ProgramData', 'LOG': '"c:\\runner\\buildbot-startup.log"', 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/agent.4056', 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\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', 'IDLEIZER_HALT_ON_IDLE': 'true', 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 'TINDERBOX_OUTPUT': '1', 'LOGONSERVER': '\\\\B-2008-SPOT-109', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/signing_cache -t /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/token -n /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/nonce -c /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'HOMEPATH': '\\', 'MOZILLABUILD': 'C:\\mozilla-build', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\Windows', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 'HOMEDRIVE': 'C:', 'OS_VERSION': '2008', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'MOZBUILDDIR': 'C:\\mozilla-build', 'OLDPWD': 'c:/Users/cltbld', 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 'NUMBER_OF_PROCESSORS': '16', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'TOOLTOOL_HOME': '/c/builds', 'PROCESSOR_LEVEL': '6', 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'MACHTYPE': 'i686-pc-msys', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'USERPROFILE': 'C:\\Users\\cltbld'}}, attempt #1 21:30:46 INFO - Running command: ['c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'taskcluster==0.0.26'] in c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build 21:30:46 INFO - Copy/paste: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv\Scripts\pip install --timeout 120 taskcluster==0.0.26 21:30:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:30:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:30:46 INFO - 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 21:30:46 INFO - 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 21:30:46 INFO - 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 21:30:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 21:30:46 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 21:30:46 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 21:30:46 INFO - 'COMPUTERNAME': 'B-2008-SPOT-109', 21:30:46 INFO - 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 21:30:46 INFO - 'CVS_RSH': 'ssh', 21:30:46 INFO - 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 21:30:46 INFO - 'EDITOR': 'emacs.exe', 21:30:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:30:46 INFO - 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 21:30:46 INFO - 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 21:30:46 INFO - 'HOME': 'c:/Users/cltbld', 21:30:46 INFO - 'HOMEDRIVE': 'C:', 21:30:46 INFO - 'HOMEPATH': '\\', 21:30:46 INFO - 'HOSTTYPE': 'i686', 21:30:46 INFO - 'IDLEIZER_HALT_ON_IDLE': 'true', 21:30:46 INFO - 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 21:30:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:30:46 INFO - 'LOG': '"c:\\runner\\buildbot-startup.log"', 21:30:46 INFO - 'LOGNAME': 'cltbld', 21:30:46 INFO - 'LOGONSERVER': '\\\\B-2008-SPOT-109', 21:30:46 INFO - 'MACHTYPE': 'i686-pc-msys', 21:30:46 INFO - 'MAKE_MODE': 'unix', 21:30:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build', 21:30:46 INFO - 'MOZILLABUILD': 'C:\\mozilla-build', 21:30:46 INFO - 'MOZ_AUTOMATION': '1', 21:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:46 INFO - 'MOZ_OBJDIR': 'obj-firefox', 21:30:46 INFO - 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110,gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110,dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110,macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 21:30:46 INFO - 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/signing_cache -t /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/token -n /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/nonce -c /c/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing5.srv.releng.scl3.mozilla.com:9110 -H gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing6.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing1.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing2.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing3.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing4.srv.releng.scl3.mozilla.com:9110 -H dmgv2:mac-v2-signing6.srv.releng.scl3.mozilla.com:9110 -H macapp:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 21:30:46 INFO - 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 21:30:46 INFO - 'MSYSTEM': 'MINGW32', 21:30:46 INFO - 'NUMBER_OF_PROCESSORS': '16', 21:30:46 INFO - 'OLDPWD': 'c:/Users/cltbld', 21:30:46 INFO - 'OS': 'Windows_NT', 21:30:46 INFO - 'OSTYPE': 'msys', 21:30:46 INFO - 'OS_VERSION': '2008', 21:30:46 INFO - 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\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', 21:30:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:30:46 INFO - 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 21:30:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:30:46 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 21:30:46 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel', 21:30:46 INFO - 'PROCESSOR_LEVEL': '6', 21:30:46 INFO - 'PROCESSOR_REVISION': '3e04', 21:30:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:30:46 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 21:30:46 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 21:30:46 INFO - 'PROGRAMW6432': 'C:\\Program Files', 21:30:46 INFO - 'PROMPT': '$P$G', 21:30:46 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\buildprops.json', 21:30:46 INFO - 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 21:30:46 INFO - 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 21:30:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:30:46 INFO - 'PWD': 'c:/Users/cltbld', 21:30:46 INFO - 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 21:30:46 INFO - 'SHELL': 'C:/mozilla-build/msys/bin/sh', 21:30:46 INFO - 'SHLVL': '1', 21:30:46 INFO - 'SSH_AGENT_PID': '3224', 21:30:46 INFO - 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/agent.4056', 21:30:46 INFO - 'SYSTEMDRIVE': 'C:', 21:30:46 INFO - 'SYSTEMROOT': 'C:\\Windows', 21:30:46 INFO - 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 21:30:46 INFO - 'TERM': 'cygwin', 21:30:46 INFO - 'TINDERBOX_OUTPUT': '1', 21:30:46 INFO - 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 21:30:46 INFO - 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 21:30:46 INFO - 'TOOLTOOL_HOME': '/c/builds', 21:30:46 INFO - 'USERDOMAIN': 'B-2008-SPOT-109', 21:30:46 INFO - 'USERNAME': 'cltbld', 21:30:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:30:46 INFO - 'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 21:30:46 INFO - 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 21:30:46 INFO - 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 21:30:46 INFO - 'WINDIR': 'C:\\Windows', 21:30:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:30:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:30:46 INFO - 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 21:30:46 INFO - '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'} 21:30:46 INFO - Collecting taskcluster==0.0.26 21:30:47 INFO - Using cached taskcluster-0.0.26.tar.gz 21:30:47 INFO - Collecting requests<=2.7.0,>=2.4.3 (from taskcluster==0.0.26) 21:30:47 INFO - Using cached requests-2.7.0-py2.py3-none-any.whl 21:30:47 INFO - Requirement already satisfied: PyHawk_with_a_single_extra_commit==0.1.5 in .\venv\lib\site-packages (from taskcluster==0.0.26) 21:30:47 INFO - Collecting slugid (from taskcluster==0.0.26) 21:30:47 INFO - Using cached slugid-1.0.7.tar.gz 21:30:47 INFO - Installing collected packages: requests, slugid, taskcluster 21:30:47 INFO - Found existing installation: requests 2.8.1 21:30:47 INFO - Uninstalling requests-2.8.1: 21:30:48 INFO - Successfully uninstalled requests-2.8.1 21:30:49 INFO - Running setup.py install for slugid: started 21:30:49 INFO - Running setup.py install for slugid: finished with status 'done' 21:30:49 INFO - Running setup.py install for taskcluster: started 21:30:49 INFO - Running setup.py install for taskcluster: finished with status 'done' 21:30:50 INFO - Successfully installed requests-2.7.0 slugid-1.0.7 taskcluster-0.0.26 21:30:50 INFO - c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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. 21:30:50 INFO - SNIMissingWarning 21:30:50 INFO - c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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. 21:30:50 INFO - InsecurePlatformWarning 21:30:50 INFO - Return code: 0 21:30:50 INFO - Done creating virtualenv c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv. 21:30:50 INFO - Getting output from command: ['c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\venv\\Scripts\\pip', 'freeze'] 21:30:50 INFO - Copy/paste: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\venv\Scripts\pip freeze 21:30:51 INFO - Reading from file tmpfile_stdout 21:30:51 INFO - Using _rmtree_windows ... 21:30:51 INFO - Using _rmtree_windows ... 21:30:51 INFO - Current package versions: 21:30:51 INFO - PyHawk-with-a-single-extra-commit == 0.1.5 21:30:51 INFO - requests == 2.7.0 21:30:51 INFO - slugid == 1.0.7 21:30:51 INFO - taskcluster == 0.0.26 21:30:51 INFO - Running pre-run listener: suppress_windows_modal_dialogs 21:30:51 INFO - Dumping config to c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\logs\localconfig.json. 21:30:51 INFO - {'app_ini_path': '%(obj_dir)s/dist/bin/application.ini', 21:30:51 INFO - 'append_to_log': False, 21:30:51 INFO - 'balrog_credentials_file': 'oauth.txt', 21:30:51 INFO - 'balrog_servers': ({'balrog_api_root': 'https://aus4-admin.mozilla.org/api', 21:30:51 INFO - 'balrog_usernames': {'Fennec': 'ffxbld', 21:30:51 INFO - 'firefox': 'ffxbld', 21:30:51 INFO - 'mobile': 'ffxbld', 21:30:51 INFO - 'thunderbird': 'tbirdbld'}, 21:30:51 INFO - 'ignore_failures': False, 21:30:51 INFO - 'url_replacements': (('http://archive.mozilla.org/pub', 21:30:51 INFO - 'http://download.cdn.mozilla.net/pub'),)},), 21:30:51 INFO - 'base_name': 'WINNT_6.1_x86-64_%(branch)s', 21:30:51 INFO - 'base_work_dir': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000', 21:30:51 INFO - 'branch': 'ash', 21:30:51 INFO - 'build_pool': 'production', 21:30:51 INFO - 'build_resources_path': '%(abs_src_dir)s/obj-firefox/.mozbuild/build_resources.json', 21:30:51 INFO - 'buildbot_json_path': 'buildprops.json', 21:30:51 INFO - 'check_test_env': {'MINIDUMP_SAVE_PATH': '%(base_work_dir)s/minidumps', 21:30:51 INFO - 'MINIDUMP_STACKWALK': '%(abs_tools_dir)s/breakpad/win64/minidump_stackwalk.exe'}, 21:30:51 INFO - 'clobberer_url': 'https://api.pub.build.mozilla.org/clobberer/lastclobber', 21:30:51 INFO - 'clone_by_revision': False, 21:30:51 INFO - 'clone_with_purge': False, 21:30:51 INFO - 'config_files': ('builds/releng_base_windows_64_builds.py', 21:30:51 INFO - 'balrog/production.py', 21:30:51 INFO - 'builds/branch_specifics.py', 21:30:51 INFO - 'builds/build_pool_specifics.py'), 21:30:51 INFO - 'debug_build': False, 21:30:51 INFO - 'default_actions': ('clobber', 21:30:51 INFO - 'clone-tools', 21:30:51 INFO - 'checkout-sources', 21:30:51 INFO - 'build', 21:30:51 INFO - 'upload-files', 21:30:51 INFO - 'sendchange', 21:30:51 INFO - 'check-test', 21:30:51 INFO - 'generate-build-stats', 21:30:51 INFO - 'update'), 21:30:51 INFO - 'enable_ccache': False, 21:30:51 INFO - 'enable_count_ctors': False, 21:30:51 INFO - 'enable_pymake': True, 21:30:51 INFO - 'enable_signing': True, 21:30:51 INFO - 'enable_talos_sendchange': True, 21:30:51 INFO - 'enable_unittest_sendchange': True, 21:30:51 INFO - 'env': {'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 21:30:51 INFO - 'MOZ_AUTOMATION': '1', 21:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:51 INFO - 'MOZ_OBJDIR': 'obj-firefox', 21:30:51 INFO - 'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\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', 21:30:51 INFO - 'PDBSTR_PATH': '/c/Program Files (x86)/Windows Kits/8.0/Debuggers/x64/srcsrv/pdbstr.exe', 21:30:51 INFO - 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\buildprops.json', 21:30:51 INFO - 'TINDERBOX_OUTPUT': '1', 21:30:51 INFO - 'TOOLTOOL_CACHE': 'c:/builds/tooltool_cache', 21:30:51 INFO - 'TOOLTOOL_HOME': '/c/builds'}, 21:30:51 INFO - 'exes': {'buildbot': ('c:\\mozilla-build\\python27\\python.exe', 21:30:51 INFO - 'c:\\mozilla-build\\buildbotve\\scripts\\buildbot'), 21:30:51 INFO - 'make': ('c:\\mozilla-build\\python27\\python.exe', 21:30:51 INFO - 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src\\build\\pymake\\make.py'), 21:30:51 INFO - 'python2.7': 'c:\\mozilla-build\\python27\\python.exe', 21:30:51 INFO - 'virtualenv': ('c:\\mozilla-build\\python27\\python.exe', 21:30:51 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 21:30:51 INFO - 'graph_selector': '/server/collect.cgi', 21:30:51 INFO - 'graph_server': 'graphs.mozilla.org', 21:30:51 INFO - 'include_post_upload_builddir': False, 21:30:51 INFO - 'is_automation': True, 21:30:51 INFO - 'log_level': 'info', 21:30:51 INFO - 'log_to_console': True, 21:30:51 INFO - 'max_build_output_timeout': 4800, 21:30:51 INFO - 'nightly_build': False, 21:30:51 INFO - 'nightly_promotion_branches': ('mozilla-central', 'mozilla-aurora'), 21:30:51 INFO - 'objdir': 'obj-firefox', 21:30:51 INFO - 'old_packages': ('%(objdir)s/dist/firefox-*', 21:30:51 INFO - '%(objdir)s/dist/fennec*', 21:30:51 INFO - '%(objdir)s/dist/seamonkey*', 21:30:51 INFO - '%(objdir)s/dist/thunderbird*', 21:30:51 INFO - '%(objdir)s/dist/install/sea/*.exe'), 21:30:51 INFO - 'opt_config_files': (), 21:30:51 INFO - 'periodic_clobber': 168, 21:30:51 INFO - 'pgo_build': True, 21:30:51 INFO - 'pgo_platforms': ('linux', 'linux64', 'win32', 'win64'), 21:30:51 INFO - 'platform': 'win64', 21:30:51 INFO - 'platform_supports_post_upload_to_latest': True, 21:30:51 INFO - 'publish_nightly_en_US_routes': True, 21:30:51 INFO - 'release_to_try_builds': False, 21:30:51 INFO - 'repo_base': 'https://hg.mozilla.org', 21:30:51 INFO - 'scm_level': 1, 21:30:51 INFO - 'sendchange_masters': ('buildbot-master81.build.mozilla.org:9301',), 21:30:51 INFO - 'src_mozconfig': 'browser/config/mozconfigs/win64/nightly', 21:30:51 INFO - 'stage_platform': 'win64', 21:30:51 INFO - 'stage_product': 'firefox', 21:30:51 INFO - 'stage_server': 'upload.ffxbld.productdelivery.prod.mozaws.net', 21:30:51 INFO - 'stage_ssh_key': 'ffxbld_rsa', 21:30:51 INFO - 'stage_username': 'ffxbld', 21:30:51 INFO - 'taskcluster_credentials_file': 'oauth.txt', 21:30:51 INFO - 'taskcluster_index': 'index', 21:30:51 INFO - 'tinderbox_build_dir': None, 21:30:51 INFO - 'to_tinderbox_dated': True, 21:30:51 INFO - 'tools_repo': 'https://hg.mozilla.org/build/tools', 21:30:51 INFO - 'tooltool_bootstrap': 'setup.sh', 21:30:51 INFO - 'tooltool_manifest_src': 'browser/config/tooltool-manifests/win64/releng.manifest', 21:30:51 INFO - 'tooltool_script': ('c:\\mozilla-build\\python27\\python.exe', 21:30:51 INFO - 'C:/mozilla-build/tooltool.py'), 21:30:51 INFO - 'tooltool_url': 'https://api.pub.build.mozilla.org/tooltool/', 21:30:51 INFO - 'upload_env': {'UPLOAD_HOST': '%(stage_server)s', 21:30:51 INFO - 'UPLOAD_SSH_KEY': '/c/Users/cltbld/.ssh/%(stage_ssh_key)s', 21:30:51 INFO - 'UPLOAD_TO_TEMP': '1', 21:30:51 INFO - 'UPLOAD_USER': '%(stage_username)s'}, 21:30:51 INFO - 'use_clobberer': True, 21:30:51 INFO - 'vcs_share_base': 'C:/builds/hg-shared', 21:30:51 INFO - 'virtualenv_modules': ('requests==2.8.1', 21:30:51 INFO - 'PyHawk-with-a-single-extra-commit==0.1.5', 21:30:51 INFO - 'taskcluster==0.0.26'), 21:30:51 INFO - 'virtualenv_path': 'venv', 21:30:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:30:51 INFO - 'who': '', 21:30:51 INFO - 'work_dir': 'build'} 21:30:51 INFO - [mozharness: 2017-04-04 04:30:51.288000Z] Skipping get-secrets step. 21:30:51 INFO - [mozharness: 2017-04-04 04:30:51.288000Z] Running clobber step. 21:30:51 INFO - Running main action method: clobber 21:30:51 INFO - retry: Calling run_command with args: [['python', 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\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'ash', u'WINNT 6.1 x86-64 ash pgo-build', 'ash-w64-pgo-000000000000000000', u'b-2008-spot-109', u'http://buildbot-master74.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 21:30:51 INFO - Running command: ['python', 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\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'ash', u'WINNT 6.1 x86-64 ash pgo-build', 'ash-w64-pgo-000000000000000000', u'b-2008-spot-109', u'http://buildbot-master74.bb.releng.usw2.mozilla.com:8001/'] in c:\builds\moz2_slave 21:30:51 INFO - Copy/paste: python c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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 ash "WINNT 6.1 x86-64 ash pgo-build" ash-w64-pgo-000000000000000000 b-2008-spot-109 http://buildbot-master74.bb.releng.usw2.mozilla.com:8001/ 21:30:51 INFO - Checking clobber URL: https://api.pub.build.mozilla.org/clobberer/lastclobber?master=http%3A%2F%2Fbuildbot-master74.bb.releng.usw2.mozilla.com%3A8001%2F&slave=b-2008-spot-109&builddir=ash-w64-pgo-000000000000000000&branch=ash&buildername=WINNT+6.1+x86-64+ash+pgo-build 21:30:51 INFO - ash-w64-pgo-000000000000000000:Our last clobber date: None 21:30:51 INFO - ash-w64-pgo-000000000000000000:Server clobber date: None 21:30:51 INFO - TinderboxPrint: purged clobber 21:30:51 INFO - Return code: 0 21:30:51 INFO - [mozharness: 2017-04-04 04:30:51.730000Z] Finished clobber step (success) 21:30:51 INFO - [mozharness: 2017-04-04 04:30:51.730000Z] Running clone-tools step. 21:30:51 INFO - Running main action method: clone_tools 21:30:51 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\tools'), kwargs: {}, attempt #1 21:30:51 WARNING - did not specify revision or branch; assuming "default" 21:30:51 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 21:30:51 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 21:30:53 INFO - Mercurial Distributed SCM (version 3.9.1) 21:30:53 INFO - (see https://mercurial-scm.org for more information) 21:30:53 INFO - Copyright (C) 2005-2016 Matt Mackall and others 21:30:53 INFO - This is free software; see the source for copying conditions. There is NO 21:30:53 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 21:30:53 INFO - Return code: 0 21:30:53 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 21:30:53 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 21:30:53 INFO - checking encoding (cp1252)... 21:30:53 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 21:30:53 INFO - checking Python version (2.7.12) 21:30:53 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 21:30:53 INFO - checking Mercurial version (3.9.1) 21:30:53 INFO - checking Mercurial custom build () 21:30:53 INFO - checking module policy (c) 21:30:53 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 21:30:53 INFO - checking templates (c:\mozilla-build\hg\templates)... 21:30:53 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 21:30:53 INFO - checking commit editor... (notepad) 21:30:53 INFO - checking username (Mozilla Release Engineering ) 21:30:53 INFO - no problems detected 21:30:53 INFO - Return code: 0 21:30:53 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/build/tools', 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\tools', '--sharebase', 'C:/builds/hg-shared', '--branch', 'default'] 21:30:53 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/build/tools c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\tools --sharebase C:/builds/hg-shared --branch default 21:30:54 INFO - ensuring https://hg.mozilla.org/build/tools@default is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\tools 21:30:54 INFO - (sharing from existing pooled repository 7ae7fb134bf7aec6ec96a062ff47a69053dd2973) 21:30:54 INFO - searching for changes 21:30:54 INFO - no changes found 21:30:54 INFO - (pulling to obtain default) 21:30:54 INFO - (remote resolved default to 1e74ed3ecdc2b8cfa77f2eb171e547527012467a; result is not deterministic) 21:30:55 INFO - (revision already present locally; not pulling) 21:30:59 INFO - 21:30:59 INFO - updating [=================================================> ] 1000/1046 01s 21:30:59 INFO - 21:30:59 INFO - 1046 files updated, 0 files merged, 0 files removed, 0 files unresolved 21:30:59 INFO - updated to 1e74ed3ecdc2b8cfa77f2eb171e547527012467a 21:30:59 INFO - Return code: 0 21:30:59 INFO - [mozharness: 2017-04-04 04:30:59.229000Z] Finished clone-tools step (success) 21:30:59 INFO - [mozharness: 2017-04-04 04:30:59.229000Z] Running checkout-sources step. 21:30:59 INFO - Running main action method: checkout_sources 21:30:59 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src'), kwargs: {}, attempt #1 21:30:59 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 21:30:59 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 21:30:59 INFO - Mercurial Distributed SCM (version 3.9.1) 21:30:59 INFO - (see https://mercurial-scm.org for more information) 21:30:59 INFO - Copyright (C) 2005-2016 Matt Mackall and others 21:30:59 INFO - This is free software; see the source for copying conditions. There is NO 21:30:59 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 21:30:59 INFO - Return code: 0 21:30:59 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 21:30:59 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 21:30:59 INFO - checking encoding (cp1252)... 21:30:59 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 21:30:59 INFO - checking Python version (2.7.12) 21:30:59 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 21:30:59 INFO - checking Mercurial version (3.9.1) 21:30:59 INFO - checking Mercurial custom build () 21:30:59 INFO - checking module policy (c) 21:30:59 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 21:30:59 INFO - checking templates (c:\mozilla-build\hg\templates)... 21:30:59 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 21:30:59 INFO - checking commit editor... (notepad) 21:30:59 INFO - checking username (Mozilla Release Engineering ) 21:30:59 INFO - no problems detected 21:30:59 INFO - Return code: 0 21:30:59 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/projects/ash', 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src', '--sharebase', 'C:/builds/hg-shared', '--revision', '03438d67a48a8590220d787672054e81f77dc99c'] 21:30:59 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/projects/ash c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src --sharebase C:/builds/hg-shared --revision 03438d67a48a8590220d787672054e81f77dc99c 21:33:02 INFO - 21:33:02 INFO - searching [ <=> ] 3 21:33:02 INFO - searching [ <=> ] 5 21:33:02 INFO - 21:33:02 INFO - 21:33:02 INFO - changesets [=============> ] 5543/20426 21:33:02 INFO - changesets [====================> ] 8817/20426 04s 21:33:02 INFO - changesets [==========================> ] 11548/20426 03s 21:33:02 INFO - changesets [================================> ] 13824/20426 03s 21:33:02 INFO - changesets [=====================================> ] 16144/20426 02s 21:33:02 INFO - changesets [========================================> ] 17446/20426 02s 21:33:02 INFO - changesets [===========================================> ] 18619/20426 01s 21:33:02 INFO - changesets [==============================================> ] 19740/20426 01s 21:33:02 INFO - 21:33:02 INFO - 21:33:02 INFO - manifests [ ] 4/20426 4h54m 21:33:02 INFO - manifests [ ] 6/20426 4h46m 21:33:02 INFO - manifests [ ] 8/20426 4h44m 21:33:02 INFO - manifests [ ] 10/20426 4h43m 21:33:02 INFO - manifests [ ] 12/20426 4h43m 21:33:02 INFO - manifests [ ] 14/20426 4h43m 21:33:02 INFO - manifests [ ] 16/20426 4h44m 21:33:02 INFO - manifests [ ] 18/20426 4h45m 21:33:02 INFO - manifests [ ] 20/20426 4h45m 21:33:02 INFO - manifests [ ] 22/20426 4h46m 21:33:02 INFO - manifests [ ] 30/20426 3h48m 21:33:02 INFO - manifests [ ] 32/20426 3h53m 21:33:02 INFO - manifests [ ] 37/20426 3h37m 21:33:02 INFO - manifests [ ] 40/20426 3h36m 21:33:02 INFO - manifests [ ] 42/20426 3h40m 21:33:02 INFO - manifests [ ] 44/20426 3h45m 21:33:02 INFO - manifests [ ] 46/20426 3h49m 21:33:02 INFO - manifests [ ] 48/20426 3h53m 21:33:02 INFO - manifests [ ] 50/20426 3h57m 21:33:02 INFO - manifests [ ] 52/20426 4h01m 21:33:02 INFO - manifests [ ] 54/20426 4h05m 21:33:02 INFO - manifests [ ] 55/20426 4h07m 21:33:02 INFO - manifests [ ] 56/20426 4h09m 21:33:02 INFO - manifests [ ] 57/20426 4h11m 21:33:02 INFO - manifests [ ] 58/20426 4h13m 21:33:02 INFO - manifests [ ] 59/20426 4h15m 21:33:02 INFO - manifests [ ] 60/20426 4h17m 21:33:02 INFO - manifests [ ] 61/20426 4h19m 21:33:02 INFO - manifests [ ] 62/20426 4h21m 21:33:02 INFO - manifests [ ] 63/20426 4h23m 21:33:02 INFO - manifests [ ] 64/20426 4h25m 21:33:02 INFO - manifests [ ] 65/20426 4h27m 21:33:02 INFO - manifests [ ] 66/20426 4h29m 21:33:02 INFO - manifests [ ] 67/20426 4h30m 21:33:02 INFO - manifests [ ] 68/20426 4h32m 21:33:02 INFO - manifests [ ] 69/20426 4h34m 21:33:02 INFO - manifests [ ] 70/20426 4h35m 21:33:02 INFO - manifests [ ] 71/20426 4h37m 21:33:02 INFO - manifests [ ] 72/20426 4h38m 21:33:02 INFO - manifests [ ] 73/20426 4h40m 21:33:02 INFO - manifests [ ] 74/20426 4h41m 21:33:02 INFO - manifests [ ] 75/20426 4h43m 21:33:02 INFO - manifests [ ] 76/20426 4h44m 21:33:02 INFO - manifests [ ] 77/20426 4h45m 21:33:02 INFO - manifests [ ] 78/20426 4h47m 21:33:02 INFO - manifests [ ] 79/20426 4h48m 21:33:02 INFO - manifests [ ] 80/20426 4h50m 21:33:02 INFO - manifests [ ] 81/20426 4h51m 21:33:02 INFO - manifests [ ] 82/20426 4h52m 21:33:02 INFO - manifests [ ] 83/20426 4h54m 21:33:02 INFO - manifests [ ] 84/20426 4h55m 21:33:02 INFO - manifests [ ] 85/20426 4h56m 21:33:02 INFO - manifests [ ] 86/20426 4h58m 21:33:02 INFO - manifests [ ] 87/20426 4h59m 21:33:02 INFO - manifests [ ] 88/20426 5h00m 21:33:02 INFO - manifests [ ] 89/20426 5h01m 21:33:02 INFO - manifests [ ] 90/20426 5h03m 21:33:02 INFO - manifests [ ] 91/20426 5h04m 21:33:02 INFO - manifests [ ] 92/20426 5h05m 21:33:02 INFO - manifests [ ] 93/20426 5h06m 21:33:02 INFO - manifests [ ] 94/20426 5h07m 21:33:02 INFO - manifests [ ] 95/20426 5h09m 21:33:02 INFO - manifests [ ] 96/20426 5h10m 21:33:02 INFO - manifests [ ] 97/20426 5h11m 21:33:02 INFO - manifests [ ] 98/20426 5h13m 21:33:02 INFO - manifests [ ] 99/20426 5h14m 21:33:02 INFO - manifests [ ] 100/20426 5h15m 21:33:02 INFO - manifests [ ] 107/20426 4h58m 21:33:02 INFO - manifests [==> ] 1612/20426 18m21s 21:33:02 INFO - manifests [==================> ] 8292/20426 2m20sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:33:02 INFO - (sharing from existing pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29) 21:33:02 INFO - searching for changes 21:33:02 INFO - adding changesets 21:33:02 INFO - adding manifests 21:33:02 INFO - 21:33:02 INFO - 21:33:02 INFO - transaction abort! 21:33:02 INFO - rollback completed 21:33:02 INFO - stream ended unexpectedly (got 0 bytes, expected 4) 21:33:02 INFO - (retrying after network failure on attempt 1 of 3) 21:33:02 INFO - (waiting 2.83s before retry) 21:36:08 INFO - 21:36:08 INFO - searching [ <=> ] 2 21:36:08 INFO - searching [ <=> ] 3 21:36:08 INFO - searching [ <=> ] 5 21:36:08 INFO - 21:36:08 INFO - 21:36:08 INFO - changesets [ ] 1/20426 21:36:08 INFO - changesets [========> ] 3656/20426 21:36:08 INFO - changesets [================> ] 6917/20426 21:36:08 INFO - changesets [======================> ] 9658/20426 04s 21:36:08 INFO - changesets [===========================> ] 11903/20426 03s 21:36:08 INFO - changesets [=================================> ] 14186/20426 03s 21:36:08 INFO - changesets [======================================> ] 16311/20426 02s 21:36:08 INFO - changesets [=========================================> ] 17512/20426 02s 21:36:08 INFO - changesets [===========================================> ] 18668/20426 01s 21:36:08 INFO - changesets [==============================================> ] 19778/20426 01s 21:36:08 INFO - 21:36:08 INFO - 21:36:08 INFO - manifests [ ] 1/20426 21:36:08 INFO - manifests [ ] 3/20426 3w02d 21:36:08 INFO - manifests [ ] 5/20426 11d04h 21:36:08 INFO - manifests [ ] 7/20426 7d13h 21:36:08 INFO - manifests [ ] 9/20426 5d17h 21:36:08 INFO - manifests [ ] 11/20426 4d14h 21:36:08 INFO - manifests [ ] 13/20426 3d21h 21:36:08 INFO - manifests [ ] 15/20426 3d08h 21:36:08 INFO - manifests [ ] 17/20426 2d23h 21:36:08 INFO - manifests [ ] 19/20426 2d16h 21:36:08 INFO - manifests [ ] 21/20426 2d10h 21:36:08 INFO - manifests [ ] 25/20426 2d01h 21:36:08 INFO - manifests [ ] 31/20426 1d15h 21:36:08 INFO - manifests [ ] 36/20426 1d10h 21:36:08 INFO - manifests [ ] 38/20426 1d09h 21:36:08 INFO - manifests [ ] 41/20426 29h54m 21:36:08 INFO - manifests [ ] 43/20426 28h43m 21:36:08 INFO - manifests [ ] 45/20426 27h39m 21:36:08 INFO - manifests [ ] 47/20426 26h41m 21:36:08 INFO - manifests [ ] 49/20426 25h48m 21:36:08 INFO - manifests [ ] 51/20426 24h59m 21:36:08 INFO - manifests [ ] 53/20426 24h14m 21:36:08 INFO - manifests [ ] 55/20426 23h33m 21:36:08 INFO - manifests [ ] 56/20426 23h14m 21:36:08 INFO - manifests [ ] 57/20426 22h56m 21:36:08 INFO - manifests [ ] 58/20426 22h38m 21:36:08 INFO - manifests [ ] 59/20426 22h21m 21:36:08 INFO - manifests [ ] 60/20426 22h04m 21:36:08 INFO - manifests [ ] 61/20426 21h48m 21:36:08 INFO - manifests [ ] 62/20426 21h33m 21:36:08 INFO - manifests [ ] 63/20426 21h18m 21:36:08 INFO - manifests [ ] 64/20426 21h04m 21:36:08 INFO - manifests [ ] 65/20426 20h50m 21:36:08 INFO - manifests [ ] 66/20426 20h37m 21:36:08 INFO - manifests [ ] 67/20426 20h24m 21:36:08 INFO - manifests [ ] 68/20426 20h12m 21:36:08 INFO - manifests [ ] 69/20426 19h59m 21:36:08 INFO - manifests [ ] 70/20426 19h47m 21:36:08 INFO - manifests [ ] 71/20426 19h36m 21:36:08 INFO - manifests [ ] 72/20426 19h25m 21:36:08 INFO - manifests [ ] 73/20426 19h14m 21:36:08 INFO - manifests [ ] 74/20426 19h03m 21:36:08 INFO - manifests [ ] 75/20426 18h53m 21:36:08 INFO - manifests [ ] 76/20426 18h43m 21:36:08 INFO - manifests [ ] 77/20426 18h33m 21:36:08 INFO - manifests [ ] 78/20426 18h24m 21:36:08 INFO - manifests [ ] 79/20426 18h14m 21:36:08 INFO - manifests [ ] 80/20426 18h05m 21:36:08 INFO - manifests [ ] 81/20426 17h57m 21:36:08 INFO - manifests [ ] 82/20426 17h48m 21:36:08 INFO - manifests [ ] 83/20426 17h40m 21:36:08 INFO - manifests [ ] 84/20426 17h32m 21:36:08 INFO - manifests [ ] 85/20426 17h24m 21:36:08 INFO - manifests [ ] 86/20426 17h17m 21:36:08 INFO - manifests [ ] 87/20426 17h09m 21:36:08 INFO - manifests [ ] 88/20426 17h02m 21:36:08 INFO - manifests [ ] 89/20426 16h55m 21:36:08 INFO - manifests [ ] 90/20426 16h48m 21:36:08 INFO - manifests [ ] 91/20426 16h42m 21:36:08 INFO - manifests [ ] 92/20426 16h35m 21:36:08 INFO - manifests [ ] 93/20426 16h29m 21:36:08 INFO - manifests [ ] 94/20426 16h23m 21:36:08 INFO - manifests [ ] 95/20426 16h17m 21:36:08 INFO - manifests [ ] 96/20426 16h11m 21:36:08 INFO - manifests [ ] 97/20426 16h05m 21:36:08 INFO - manifests [ ] 98/20426 16h00m 21:36:08 INFO - manifests [ ] 99/20426 15h54m 21:36:08 INFO - manifests [ ] 100/20426 15h49m 21:36:08 INFO - manifests [ ] 107/20426 14h50m 21:36:08 INFO - manifests [==> ] 1580/20426 55m36s 21:36:08 INFO - manifests [=================> ] 7809/20426 7m34sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:36:08 INFO - (existing repository shared store: C:\builds\hg-shared\8ba995b74e18334ab3707f27e9eb8f4e37ba3d29\.hg) 21:36:08 INFO - (pulling to obtain 03438d67a48a8590220d787672054e81f77dc99c) 21:36:08 INFO - searching for changes 21:36:08 INFO - adding changesets 21:36:08 INFO - adding manifests 21:36:08 INFO - 21:36:08 INFO - 21:36:08 INFO - transaction abort! 21:36:08 INFO - rollback completed 21:36:08 INFO - stream ended unexpectedly (got 0 bytes, expected 4) 21:36:08 INFO - (retrying after network failure on attempt 2 of 3) 21:36:08 INFO - (waiting 7.33s before retry) 21:38:17 INFO - 21:38:17 INFO - searching [ <=> ] 2 21:38:17 INFO - searching [ <=> ] 3 21:38:17 INFO - searching [ <=> ] 5 21:38:17 INFO - 21:38:17 INFO - 21:38:17 INFO - changesets [ ] 1/20426 21:38:17 INFO - changesets [=======> ] 3419/20426 21:38:17 INFO - changesets [================> ] 6688/20426 21:38:17 INFO - changesets [=====================> ] 9437/20426 04s 21:38:17 INFO - changesets [===========================> ] 11811/20426 03s 21:38:17 INFO - changesets [================================> ] 14061/20426 03s 21:38:17 INFO - changesets [=====================================> ] 16189/20426 02s 21:38:17 INFO - changesets [========================================> ] 17418/20426 02s 21:38:17 INFO - changesets [===========================================> ] 18551/20426 01s 21:38:17 INFO - changesets [==============================================> ] 19646/20426 01s 21:38:17 INFO - 21:38:17 INFO - 21:38:17 INFO - manifests [ ] 1/20426 21:38:17 INFO - manifests [ ] 3/20426 5w03d 21:38:17 INFO - manifests [ ] 5/20426 2w05d 21:38:17 INFO - manifests [ ] 7/20426 12d17h 21:38:17 INFO - manifests [ ] 9/20426 9d14h 21:38:17 INFO - manifests [ ] 11/20426 7d17h 21:38:17 INFO - manifests [ ] 13/20426 6d11h 21:38:17 INFO - manifests [ ] 15/20426 5d14h 21:38:17 INFO - manifests [ ] 17/20426 4d21h 21:38:17 INFO - manifests [ ] 19/20426 4d09h 21:38:17 INFO - manifests [ ] 21/20426 3d23h 21:38:17 INFO - manifests [ ] 25/20426 3d08h 21:38:17 INFO - manifests [ ] 31/20426 2d16h 21:38:17 INFO - manifests [ ] 36/20426 2d07h 21:38:17 INFO - manifests [ ] 38/20426 2d05h 21:38:17 INFO - manifests [ ] 41/20426 2d01h 21:38:17 INFO - manifests [ ] 43/20426 1d23h 21:38:17 INFO - manifests [ ] 45/20426 1d21h 21:38:17 INFO - manifests [ ] 47/20426 1d19h 21:38:17 INFO - manifests [ ] 49/20426 1d18h 21:38:17 INFO - manifests [ ] 51/20426 1d16h 21:38:17 INFO - manifests [ ] 53/20426 1d15h 21:38:17 INFO - manifests [ ] 55/20426 1d14h 21:38:17 INFO - manifests [ ] 56/20426 1d13h 21:38:17 INFO - manifests [ ] 57/20426 1d13h 21:38:17 INFO - manifests [ ] 58/20426 1d12h 21:38:17 INFO - manifests [ ] 59/20426 1d12h 21:38:17 INFO - manifests [ ] 60/20426 1d11h 21:38:17 INFO - manifests [ ] 61/20426 1d11h 21:38:17 INFO - manifests [ ] 62/20426 1d10h 21:38:17 INFO - manifests [ ] 63/20426 1d10h 21:38:17 INFO - manifests [ ] 64/20426 1d09h 21:38:17 INFO - manifests [ ] 65/20426 1d09h 21:38:17 INFO - manifests [ ] 66/20426 1d09h 21:38:17 INFO - manifests [ ] 67/20426 1d08h 21:38:17 INFO - manifests [ ] 68/20426 1d08h 21:38:17 INFO - manifests [ ] 69/20426 1d07h 21:38:17 INFO - manifests [ ] 70/20426 1d07h 21:38:17 INFO - manifests [ ] 71/20426 1d07h 21:38:17 INFO - manifests [ ] 72/20426 29h51m 21:38:17 INFO - manifests [ ] 73/20426 29h32m 21:38:17 INFO - manifests [ ] 74/20426 29h12m 21:38:17 INFO - manifests [ ] 75/20426 28h54m 21:38:17 INFO - manifests [ ] 76/20426 28h36m 21:38:17 INFO - manifests [ ] 77/20426 28h18m 21:38:17 INFO - manifests [ ] 78/20426 28h01m 21:38:17 INFO - manifests [ ] 79/20426 27h45m 21:38:17 INFO - manifests [ ] 80/20426 27h28m 21:38:17 INFO - manifests [ ] 81/20426 27h13m 21:38:17 INFO - manifests [ ] 82/20426 26h57m 21:38:17 INFO - manifests [ ] 83/20426 26h42m 21:38:17 INFO - manifests [ ] 84/20426 26h28m 21:38:17 INFO - manifests [ ] 85/20426 26h14m 21:38:17 INFO - manifests [ ] 86/20426 26h00m 21:38:17 INFO - manifests [ ] 87/20426 25h46m 21:38:17 INFO - manifests [ ] 88/20426 25h33m 21:38:17 INFO - manifests [ ] 89/20426 25h20m 21:38:17 INFO - manifests [ ] 90/20426 25h08m 21:38:17 INFO - manifests [ ] 91/20426 24h55m 21:38:17 INFO - manifests [ ] 92/20426 24h44m 21:38:17 INFO - manifests [ ] 93/20426 24h32m 21:38:17 INFO - manifests [ ] 94/20426 24h20m 21:38:17 INFO - manifests [ ] 95/20426 24h09m 21:38:17 INFO - manifests [ ] 96/20426 23h59m 21:38:17 INFO - manifests [ ] 97/20426 23h48m 21:38:17 INFO - manifests [ ] 98/20426 23h38m 21:38:17 INFO - manifests [ ] 99/20426 23h28m 21:38:17 INFO - manifests [ ] 100/20426 23h18m 21:38:17 INFO - manifests [ ] 107/20426 21h49m 21:38:17 INFO - manifests [==> ] 1579/20426 1h22m 21:38:17 INFO - manifests [=================> ] 8123/20426 10m24s 21:38:17 INFO - manifests [=======================> ] 10240/20426 6m51sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:38:17 INFO - (existing repository shared store: C:\builds\hg-shared\8ba995b74e18334ab3707f27e9eb8f4e37ba3d29\.hg) 21:38:17 INFO - (pulling to obtain 03438d67a48a8590220d787672054e81f77dc99c) 21:38:17 INFO - searching for changes 21:38:17 INFO - adding changesets 21:38:17 INFO - adding manifests 21:38:17 INFO - 21:38:17 INFO - 21:38:17 INFO - transaction abort! 21:38:17 INFO - rollback completed 21:38:17 INFO - stream ended unexpectedly (got 0 bytes, expected 4) 21:38:17 INFO - Traceback (most recent call last): 21:38:17 INFO - File "mercurial\dispatch.pyc", line 204, in _runcatch 21:38:17 INFO - File "mercurial\dispatch.pyc", line 880, in _dispatch 21:38:17 INFO - File "mercurial\dispatch.pyc", line 637, in runcommand 21:38:17 INFO - File "mercurial\extensions.pyc", line 210, in closure 21:38:17 INFO - File "hgext\color.pyc", line 503, in colorcmd 21:38:17 INFO - File "mercurial\dispatch.pyc", line 1010, in _runcommand 21:38:17 INFO - File "mercurial\dispatch.pyc", line 971, in checkargs 21:38:17 INFO - File "mercurial\dispatch.pyc", line 877, in 21:38:17 INFO - File "mercurial\util.pyc", line 1036, in check 21:38:17 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 163, in robustcheckout 21:38:17 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 289, in _docheckout 21:38:17 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 172, in callself 21:38:17 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 348, in _docheckout 21:38:17 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 172, in callself 21:38:17 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 347, in _docheckout 21:38:17 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 262, in handlepullabort 21:38:17 INFO - Abort: reached maximum number of network attempts; giving up 21:38:17 INFO - abort: reached maximum number of network attempts; giving up 21:38:17 ERROR - Return code: 255 21:38:17 INFO - rmtree: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:38:17 INFO - Using _rmtree_windows ... 21:38:17 INFO - retry: Calling _rmtree_windows with args: ('c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src',), kwargs: {}, attempt #1 21:38:17 INFO - retry: attempt #1 caught exception: repo checkout failed! 21:38:17 INFO - retry: Failed, sleeping 60 seconds before retrying 21:39:17 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src'), kwargs: {}, attempt #2 21:39:17 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 21:39:17 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 21:39:18 INFO - Mercurial Distributed SCM (version 3.9.1) 21:39:18 INFO - (see https://mercurial-scm.org for more information) 21:39:18 INFO - Copyright (C) 2005-2016 Matt Mackall and others 21:39:18 INFO - This is free software; see the source for copying conditions. There is NO 21:39:18 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 21:39:18 INFO - Return code: 0 21:39:18 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 21:39:18 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 21:39:18 INFO - checking encoding (cp1252)... 21:39:18 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 21:39:18 INFO - checking Python version (2.7.12) 21:39:18 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 21:39:18 INFO - checking Mercurial version (3.9.1) 21:39:18 INFO - checking Mercurial custom build () 21:39:18 INFO - checking module policy (c) 21:39:18 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 21:39:18 INFO - checking templates (c:\mozilla-build\hg\templates)... 21:39:18 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 21:39:18 INFO - checking commit editor... (notepad) 21:39:18 INFO - checking username (Mozilla Release Engineering ) 21:39:18 INFO - no problems detected 21:39:18 INFO - Return code: 0 21:39:18 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/projects/ash', 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src', '--sharebase', 'C:/builds/hg-shared', '--revision', '03438d67a48a8590220d787672054e81f77dc99c'] 21:39:18 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/projects/ash c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src --sharebase C:/builds/hg-shared --revision 03438d67a48a8590220d787672054e81f77dc99c 21:41:10 INFO - 21:41:10 INFO - searching [ <=> ] 3 21:41:10 INFO - searching [ <=> ] 5 21:41:10 INFO - 21:41:10 INFO - 21:41:10 INFO - changesets [==============> ] 6053/20426 21:41:10 INFO - changesets [=====================> ] 9192/20426 04s 21:41:10 INFO - changesets [===========================> ] 11811/20426 03s 21:41:10 INFO - changesets [=================================> ] 14218/20426 03s 21:41:10 INFO - changesets [======================================> ] 16420/20426 02s 21:41:10 INFO - changesets [=========================================> ] 17656/20426 02s 21:41:10 INFO - changesets [============================================> ] 18849/20426 01s 21:41:10 INFO - changesets [==============================================> ] 19993/20426 01s 21:41:10 INFO - 21:41:10 INFO - 21:41:10 INFO - manifests [ ] 4/20426 4h44m 21:41:10 INFO - manifests [ ] 6/20426 4h40m 21:41:10 INFO - manifests [ ] 8/20426 4h39m 21:41:10 INFO - manifests [ ] 10/20426 4h39m 21:41:10 INFO - manifests [ ] 12/20426 4h39m 21:41:10 INFO - manifests [ ] 14/20426 4h40m 21:41:10 INFO - manifests [ ] 16/20426 4h40m 21:41:10 INFO - manifests [ ] 18/20426 4h41m 21:41:10 INFO - manifests [ ] 20/20426 4h42m 21:41:10 INFO - manifests [ ] 22/20426 4h43m 21:41:10 INFO - manifests [ ] 30/20426 3h46m 21:41:10 INFO - manifests [ ] 32/20426 3h51m 21:41:10 INFO - manifests [ ] 37/20426 3h36m 21:41:10 INFO - manifests [ ] 40/20426 3h36m 21:41:10 INFO - manifests [ ] 42/20426 3h40m 21:41:10 INFO - manifests [ ] 44/20426 3h44m 21:41:10 INFO - manifests [ ] 46/20426 3h48m 21:41:10 INFO - manifests [ ] 48/20426 3h53m 21:41:10 INFO - manifests [ ] 50/20426 3h57m 21:41:10 INFO - manifests [ ] 52/20426 4h01m 21:41:10 INFO - manifests [ ] 54/20426 4h04m 21:41:10 INFO - manifests [ ] 55/20426 4h06m 21:41:10 INFO - manifests [ ] 56/20426 4h08m 21:41:10 INFO - manifests [ ] 57/20426 4h11m 21:41:10 INFO - manifests [ ] 58/20426 4h13m 21:41:10 INFO - manifests [ ] 59/20426 4h15m 21:41:10 INFO - manifests [ ] 60/20426 4h16m 21:41:10 INFO - manifests [ ] 61/20426 4h18m 21:41:10 INFO - manifests [ ] 62/20426 4h20m 21:41:10 INFO - manifests [ ] 63/20426 4h22m 21:41:10 INFO - manifests [ ] 64/20426 4h24m 21:41:10 INFO - manifests [ ] 65/20426 4h26m 21:41:10 INFO - manifests [ ] 66/20426 4h27m 21:41:10 INFO - manifests [ ] 67/20426 4h29m 21:41:10 INFO - manifests [ ] 68/20426 4h31m 21:41:10 INFO - manifests [ ] 69/20426 4h32m 21:41:10 INFO - manifests [ ] 70/20426 4h34m 21:41:10 INFO - manifests [ ] 71/20426 4h35m 21:41:10 INFO - manifests [ ] 72/20426 4h37m 21:41:10 INFO - manifests [ ] 73/20426 4h38m 21:41:10 INFO - manifests [ ] 74/20426 4h40m 21:41:10 INFO - manifests [ ] 75/20426 4h41m 21:41:10 INFO - manifests [ ] 76/20426 4h43m 21:41:10 INFO - manifests [ ] 77/20426 4h44m 21:41:10 INFO - manifests [ ] 78/20426 4h45m 21:41:10 INFO - manifests [ ] 79/20426 4h47m 21:41:10 INFO - manifests [ ] 80/20426 4h48m 21:41:10 INFO - manifests [ ] 81/20426 4h49m 21:41:10 INFO - manifests [ ] 82/20426 4h51m 21:41:10 INFO - manifests [ ] 83/20426 4h52m 21:41:10 INFO - manifests [ ] 84/20426 4h53m 21:41:10 INFO - manifests [ ] 85/20426 4h55m 21:41:10 INFO - manifests [ ] 86/20426 4h56m 21:41:10 INFO - manifests [ ] 87/20426 4h57m 21:41:10 INFO - manifests [ ] 88/20426 4h58m 21:41:10 INFO - manifests [ ] 89/20426 5h00m 21:41:10 INFO - manifests [ ] 90/20426 5h01m 21:41:10 INFO - manifests [ ] 91/20426 5h02m 21:41:10 INFO - manifests [ ] 92/20426 5h03m 21:41:10 INFO - manifests [ ] 93/20426 5h05m 21:41:10 INFO - manifests [ ] 94/20426 5h06m 21:41:10 INFO - manifests [ ] 95/20426 5h07m 21:41:10 INFO - manifests [ ] 96/20426 5h08m 21:41:10 INFO - manifests [ ] 97/20426 5h09m 21:41:10 INFO - manifests [ ] 98/20426 5h11m 21:41:10 INFO - manifests [ ] 99/20426 5h12m 21:41:10 INFO - manifests [ ] 100/20426 5h13m 21:41:10 INFO - manifests [ ] 107/20426 4h56m 21:41:10 INFO - manifests [==> ] 1612/20426 18m14s 21:41:10 INFO - manifests [==================> ] 8188/20426 2m22sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:41:10 INFO - (sharing from existing pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29) 21:41:10 INFO - searching for changes 21:41:10 INFO - adding changesets 21:41:10 INFO - adding manifests 21:41:10 INFO - 21:41:10 INFO - 21:41:10 INFO - transaction abort! 21:41:10 INFO - rollback completed 21:41:10 INFO - stream ended unexpectedly (got 16904 bytes, expected 1869837101) 21:41:10 INFO - (retrying after network failure on attempt 1 of 3) 21:41:10 INFO - (waiting 3.12s before retry) 21:44:14 INFO - 21:44:14 INFO - searching [ <=> ] 2 21:44:14 INFO - searching [ <=> ] 3 21:44:14 INFO - searching [ <=> ] 5 21:44:14 INFO - 21:44:14 INFO - 21:44:14 INFO - changesets [ ] 1/20426 21:44:14 INFO - changesets [========> ] 3562/20426 21:44:14 INFO - changesets [================> ] 6836/20426 21:44:14 INFO - changesets [======================> ] 9603/20426 04s 21:44:14 INFO - changesets [===========================> ] 11865/20426 03s 21:44:14 INFO - changesets [================================> ] 14137/20426 03s 21:44:14 INFO - changesets [======================================> ] 16267/20426 02s 21:44:14 INFO - changesets [========================================> ] 17468/20426 02s 21:44:14 INFO - changesets [===========================================> ] 18611/20426 01s 21:44:14 INFO - changesets [==============================================> ] 19711/20426 01s 21:44:14 INFO - 21:44:14 INFO - 21:44:14 INFO - manifests [ ] 1/20426 21:44:14 INFO - manifests [ ] 3/20426 3w00d 21:44:14 INFO - manifests [ ] 5/20426 10d11h 21:44:14 INFO - manifests [ ] 7/20426 7d01h 21:44:14 INFO - manifests [ ] 9/20426 5d08h 21:44:14 INFO - manifests [ ] 11/20426 4d08h 21:44:14 INFO - manifests [ ] 13/20426 3d15h 21:44:14 INFO - manifests [ ] 15/20426 3d03h 21:44:14 INFO - manifests [ ] 17/20426 2d19h 21:44:14 INFO - manifests [ ] 19/20426 2d12h 21:44:14 INFO - manifests [ ] 21/20426 2d06h 21:44:14 INFO - manifests [ ] 25/20426 1d22h 21:44:14 INFO - manifests [ ] 31/20426 1d13h 21:44:14 INFO - manifests [ ] 36/20426 1d08h 21:44:14 INFO - manifests [ ] 38/20426 1d07h 21:44:14 INFO - manifests [ ] 41/20426 28h11m 21:44:14 INFO - manifests [ ] 43/20426 27h05m 21:44:14 INFO - manifests [ ] 45/20426 26h05m 21:44:14 INFO - manifests [ ] 47/20426 25h11m 21:44:14 INFO - manifests [ ] 49/20426 24h22m 21:44:14 INFO - manifests [ ] 51/20426 23h36m 21:44:14 INFO - manifests [ ] 53/20426 22h54m 21:44:14 INFO - manifests [ ] 55/20426 22h16m 21:44:14 INFO - manifests [ ] 56/20426 21h58m 21:44:14 INFO - manifests [ ] 57/20426 21h41m 21:44:14 INFO - manifests [ ] 58/20426 21h25m 21:44:14 INFO - manifests [ ] 59/20426 21h09m 21:44:14 INFO - manifests [ ] 60/20426 20h54m 21:44:14 INFO - manifests [ ] 61/20426 20h39m 21:44:14 INFO - manifests [ ] 62/20426 20h24m 21:44:14 INFO - manifests [ ] 63/20426 20h11m 21:44:14 INFO - manifests [ ] 64/20426 19h57m 21:44:14 INFO - manifests [ ] 65/20426 19h44m 21:44:14 INFO - manifests [ ] 66/20426 19h32m 21:44:14 INFO - manifests [ ] 67/20426 19h20m 21:44:14 INFO - manifests [ ] 68/20426 19h08m 21:44:14 INFO - manifests [ ] 69/20426 18h57m 21:44:14 INFO - manifests [ ] 70/20426 18h46m 21:44:14 INFO - manifests [ ] 71/20426 18h35m 21:44:14 INFO - manifests [ ] 72/20426 18h25m 21:44:14 INFO - manifests [ ] 73/20426 18h15m 21:44:14 INFO - manifests [ ] 74/20426 18h05m 21:44:14 INFO - manifests [ ] 75/20426 17h55m 21:44:14 INFO - manifests [ ] 76/20426 17h46m 21:44:14 INFO - manifests [ ] 77/20426 17h37m 21:44:14 INFO - manifests [ ] 78/20426 17h28m 21:44:14 INFO - manifests [ ] 79/20426 17h20m 21:44:14 INFO - manifests [ ] 80/20426 17h11m 21:44:14 INFO - manifests [ ] 81/20426 17h03m 21:44:14 INFO - manifests [ ] 82/20426 16h56m 21:44:14 INFO - manifests [ ] 83/20426 16h48m 21:44:14 INFO - manifests [ ] 84/20426 16h41m 21:44:14 INFO - manifests [ ] 85/20426 16h33m 21:44:14 INFO - manifests [ ] 86/20426 16h26m 21:44:14 INFO - manifests [ ] 87/20426 16h20m 21:44:14 INFO - manifests [ ] 88/20426 16h13m 21:44:14 INFO - manifests [ ] 89/20426 16h07m 21:44:14 INFO - manifests [ ] 90/20426 16h00m 21:44:14 INFO - manifests [ ] 91/20426 15h54m 21:44:14 INFO - manifests [ ] 92/20426 15h48m 21:44:14 INFO - manifests [ ] 93/20426 15h43m 21:44:14 INFO - manifests [ ] 94/20426 15h37m 21:44:14 INFO - manifests [ ] 95/20426 15h32m 21:44:14 INFO - manifests [ ] 96/20426 15h26m 21:44:14 INFO - manifests [ ] 97/20426 15h21m 21:44:14 INFO - manifests [ ] 98/20426 15h16m 21:44:14 INFO - manifests [ ] 99/20426 15h11m 21:44:14 INFO - manifests [ ] 100/20426 15h06m 21:44:14 INFO - manifests [ ] 107/20426 14h10m 21:44:14 INFO - manifests [==> ] 1587/20426 52m51s 21:44:14 INFO - manifests [==================> ] 8123/20426 6m46s 21:44:14 INFO - manifests [=======================> ] 10240/20426 4m28sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:44:14 INFO - (existing repository shared store: C:\builds\hg-shared\8ba995b74e18334ab3707f27e9eb8f4e37ba3d29\.hg) 21:44:14 INFO - (pulling to obtain 03438d67a48a8590220d787672054e81f77dc99c) 21:44:14 INFO - searching for changes 21:44:14 INFO - adding changesets 21:44:14 INFO - adding manifests 21:44:14 INFO - 21:44:14 INFO - 21:44:14 INFO - transaction abort! 21:44:14 INFO - rollback completed 21:44:14 INFO - stream ended unexpectedly (got 162080 bytes, expected 1348560741) 21:44:14 INFO - (retrying after network failure on attempt 2 of 3) 21:44:14 INFO - (waiting 7.71s before retry) 21:46:23 INFO - 21:46:23 INFO - searching [ <=> ] 2 21:46:23 INFO - searching [ <=> ] 3 21:46:23 INFO - searching [ <=> ] 5 21:46:23 INFO - 21:46:23 INFO - 21:46:23 INFO - changesets [ ] 1/20426 21:46:23 INFO - changesets [========> ] 3475/20426 21:46:23 INFO - changesets [================> ] 6686/20426 21:46:23 INFO - changesets [=====================> ] 9393/20426 04s 21:46:23 INFO - changesets [===========================> ] 11810/20426 03s 21:46:23 INFO - changesets [================================> ] 13881/20426 03s 21:46:23 INFO - changesets [=====================================> ] 16036/20426 02s 21:46:23 INFO - changesets [========================================> ] 17327/20426 02s 21:46:23 INFO - changesets [===========================================> ] 18477/20426 01s 21:46:23 INFO - changesets [=============================================> ] 19577/20426 01s 21:46:23 INFO - 21:46:23 INFO - 21:46:23 INFO - manifests [ ] 1/20426 21:46:23 INFO - manifests [ ] 3/20426 5w02d 21:46:23 INFO - manifests [ ] 5/20426 2w05d 21:46:23 INFO - manifests [ ] 7/20426 12d06h 21:46:23 INFO - manifests [ ] 9/20426 9d06h 21:46:23 INFO - manifests [ ] 11/20426 7d10h 21:46:23 INFO - manifests [ ] 13/20426 6d06h 21:46:23 INFO - manifests [ ] 15/20426 5d09h 21:46:23 INFO - manifests [ ] 17/20426 4d17h 21:46:23 INFO - manifests [ ] 19/20426 4d05h 21:46:23 INFO - manifests [ ] 21/20426 3d20h 21:46:23 INFO - manifests [ ] 25/20426 3d05h 21:46:23 INFO - manifests [ ] 31/20426 2d14h 21:46:23 INFO - manifests [ ] 36/20426 2d06h 21:46:23 INFO - manifests [ ] 38/20426 2d03h 21:46:23 INFO - manifests [ ] 41/20426 1d23h 21:46:23 INFO - manifests [ ] 43/20426 1d21h 21:46:23 INFO - manifests [ ] 45/20426 1d20h 21:46:23 INFO - manifests [ ] 47/20426 1d18h 21:46:23 INFO - manifests [ ] 49/20426 1d16h 21:46:23 INFO - manifests [ ] 51/20426 1d15h 21:46:23 INFO - manifests [ ] 53/20426 1d14h 21:46:23 INFO - manifests [ ] 55/20426 1d13h 21:46:23 INFO - manifests [ ] 56/20426 1d12h 21:46:23 INFO - manifests [ ] 57/20426 1d12h 21:46:23 INFO - manifests [ ] 58/20426 1d11h 21:46:23 INFO - manifests [ ] 59/20426 1d11h 21:46:23 INFO - manifests [ ] 60/20426 1d10h 21:46:23 INFO - manifests [ ] 61/20426 1d10h 21:46:23 INFO - manifests [ ] 62/20426 1d09h 21:46:23 INFO - manifests [ ] 63/20426 1d09h 21:46:23 INFO - manifests [ ] 64/20426 1d08h 21:46:23 INFO - manifests [ ] 65/20426 1d08h 21:46:23 INFO - manifests [ ] 66/20426 1d08h 21:46:23 INFO - manifests [ ] 67/20426 1d07h 21:46:23 INFO - manifests [ ] 68/20426 1d07h 21:46:23 INFO - manifests [ ] 69/20426 29h56m 21:46:23 INFO - manifests [ ] 70/20426 29h35m 21:46:23 INFO - manifests [ ] 71/20426 29h15m 21:46:23 INFO - manifests [ ] 72/20426 28h56m 21:46:23 INFO - manifests [ ] 73/20426 28h37m 21:46:23 INFO - manifests [ ] 74/20426 28h18m 21:46:23 INFO - manifests [ ] 75/20426 28h01m 21:46:23 INFO - manifests [ ] 76/20426 27h43m 21:46:23 INFO - manifests [ ] 77/20426 27h26m 21:46:23 INFO - manifests [ ] 78/20426 27h10m 21:46:23 INFO - manifests [ ] 79/20426 26h54m 21:46:23 INFO - manifests [ ] 80/20426 26h38m 21:46:23 INFO - manifests [ ] 81/20426 26h23m 21:46:23 INFO - manifests [ ] 82/20426 26h08m 21:46:23 INFO - manifests [ ] 83/20426 25h54m 21:46:23 INFO - manifests [ ] 84/20426 25h40m 21:46:23 INFO - manifests [ ] 85/20426 25h26m 21:46:23 INFO - manifests [ ] 86/20426 25h13m 21:46:23 INFO - manifests [ ] 87/20426 25h00m 21:46:23 INFO - manifests [ ] 88/20426 24h48m 21:46:23 INFO - manifests [ ] 89/20426 24h35m 21:46:23 INFO - manifests [ ] 90/20426 24h23m 21:46:23 INFO - manifests [ ] 91/20426 24h11m 21:46:23 INFO - manifests [ ] 92/20426 24h00m 21:46:23 INFO - manifests [ ] 93/20426 23h49m 21:46:23 INFO - manifests [ ] 94/20426 23h38m 21:46:23 INFO - manifests [ ] 95/20426 23h27m 21:46:23 INFO - manifests [ ] 96/20426 23h17m 21:46:23 INFO - manifests [ ] 97/20426 23h06m 21:46:23 INFO - manifests [ ] 98/20426 22h56m 21:46:23 INFO - manifests [ ] 99/20426 22h47m 21:46:23 INFO - manifests [ ] 100/20426 22h37m 21:46:23 INFO - manifests [ ] 107/20426 21h11m 21:46:23 INFO - manifests [==> ] 1578/20426 1h20m 21:46:23 INFO - manifests [=================> ] 8080/20426 10m11sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:46:23 INFO - (existing repository shared store: C:\builds\hg-shared\8ba995b74e18334ab3707f27e9eb8f4e37ba3d29\.hg) 21:46:23 INFO - (pulling to obtain 03438d67a48a8590220d787672054e81f77dc99c) 21:46:23 INFO - searching for changes 21:46:23 INFO - adding changesets 21:46:23 INFO - adding manifests 21:46:23 INFO - 21:46:23 INFO - 21:46:23 INFO - transaction abort! 21:46:23 INFO - rollback completed 21:46:23 INFO - stream ended unexpectedly (got 8747 bytes, expected 3354678) 21:46:23 INFO - Traceback (most recent call last): 21:46:23 INFO - File "mercurial\dispatch.pyc", line 204, in _runcatch 21:46:23 INFO - File "mercurial\dispatch.pyc", line 880, in _dispatch 21:46:23 INFO - File "mercurial\dispatch.pyc", line 637, in runcommand 21:46:23 INFO - File "mercurial\extensions.pyc", line 210, in closure 21:46:23 INFO - File "hgext\color.pyc", line 503, in colorcmd 21:46:23 INFO - File "mercurial\dispatch.pyc", line 1010, in _runcommand 21:46:23 INFO - File "mercurial\dispatch.pyc", line 971, in checkargs 21:46:23 INFO - File "mercurial\dispatch.pyc", line 877, in 21:46:23 INFO - File "mercurial\util.pyc", line 1036, in check 21:46:23 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 163, in robustcheckout 21:46:23 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 289, in _docheckout 21:46:23 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 172, in callself 21:46:23 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 348, in _docheckout 21:46:23 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 172, in callself 21:46:23 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 347, in _docheckout 21:46:23 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 262, in handlepullabort 21:46:23 INFO - Abort: reached maximum number of network attempts; giving up 21:46:23 INFO - abort: reached maximum number of network attempts; giving up 21:46:23 ERROR - Return code: 255 21:46:23 INFO - rmtree: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:46:23 INFO - Using _rmtree_windows ... 21:46:23 INFO - retry: Calling _rmtree_windows with args: ('c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src',), kwargs: {}, attempt #1 21:46:23 INFO - retry: attempt #2 caught exception: repo checkout failed! 21:46:23 INFO - retry: Failed, sleeping 120 seconds before retrying 21:48:23 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src'), kwargs: {}, attempt #3 21:48:23 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 21:48:23 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 21:48:24 INFO - Mercurial Distributed SCM (version 3.9.1) 21:48:24 INFO - (see https://mercurial-scm.org for more information) 21:48:24 INFO - Copyright (C) 2005-2016 Matt Mackall and others 21:48:24 INFO - This is free software; see the source for copying conditions. There is NO 21:48:24 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 21:48:24 INFO - Return code: 0 21:48:24 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 21:48:24 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 21:48:24 INFO - checking encoding (cp1252)... 21:48:24 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 21:48:24 INFO - checking Python version (2.7.12) 21:48:24 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 21:48:24 INFO - checking Mercurial version (3.9.1) 21:48:24 INFO - checking Mercurial custom build () 21:48:24 INFO - checking module policy (c) 21:48:24 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 21:48:24 INFO - checking templates (c:\mozilla-build\hg\templates)... 21:48:24 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 21:48:24 INFO - checking commit editor... (notepad) 21:48:24 INFO - checking username (Mozilla Release Engineering ) 21:48:24 INFO - no problems detected 21:48:24 INFO - Return code: 0 21:48:24 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/projects/ash', 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src', '--sharebase', 'C:/builds/hg-shared', '--revision', '03438d67a48a8590220d787672054e81f77dc99c'] 21:48:24 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/projects/ash c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src --sharebase C:/builds/hg-shared --revision 03438d67a48a8590220d787672054e81f77dc99c 21:50:26 INFO - 21:50:26 INFO - searching [ <=> ] 3 21:50:26 INFO - searching [ <=> ] 5 21:50:26 INFO - 21:50:26 INFO - 21:50:26 INFO - changesets [==============> ] 5838/20426 21:50:26 INFO - changesets [====================> ] 9010/20426 04s 21:50:26 INFO - changesets [===========================> ] 11710/20426 03s 21:50:26 INFO - changesets [================================> ] 13966/20426 03s 21:50:26 INFO - changesets [=====================================> ] 16233/20426 02s 21:50:26 INFO - changesets [========================================> ] 17487/20426 02s 21:50:26 INFO - changesets [===========================================> ] 18662/20426 01s 21:50:26 INFO - changesets [==============================================> ] 19788/20426 01s 21:50:26 INFO - 21:50:26 INFO - 21:50:26 INFO - manifests [ ] 4/20426 4h47m 21:50:26 INFO - manifests [ ] 6/20426 4h43m 21:50:26 INFO - manifests [ ] 8/20426 4h41m 21:50:26 INFO - manifests [ ] 10/20426 4h41m 21:50:26 INFO - manifests [ ] 12/20426 4h41m 21:50:26 INFO - manifests [ ] 14/20426 4h42m 21:50:26 INFO - manifests [ ] 16/20426 4h43m 21:50:26 INFO - manifests [ ] 18/20426 4h44m 21:50:26 INFO - manifests [ ] 20/20426 4h45m 21:50:26 INFO - manifests [ ] 22/20426 4h46m 21:50:26 INFO - manifests [ ] 30/20426 3h48m 21:50:26 INFO - manifests [ ] 32/20426 3h52m 21:50:26 INFO - manifests [ ] 37/20426 3h37m 21:50:26 INFO - manifests [ ] 40/20426 3h36m 21:50:26 INFO - manifests [ ] 42/20426 3h40m 21:50:26 INFO - manifests [ ] 44/20426 3h45m 21:50:26 INFO - manifests [ ] 46/20426 3h49m 21:50:26 INFO - manifests [ ] 48/20426 3h53m 21:50:26 INFO - manifests [ ] 50/20426 3h57m 21:50:26 INFO - manifests [ ] 52/20426 4h01m 21:50:26 INFO - manifests [ ] 54/20426 4h05m 21:50:26 INFO - manifests [ ] 55/20426 4h07m 21:50:26 INFO - manifests [ ] 56/20426 4h09m 21:50:26 INFO - manifests [ ] 57/20426 4h11m 21:50:26 INFO - manifests [ ] 58/20426 4h14m 21:50:26 INFO - manifests [ ] 59/20426 4h16m 21:50:26 INFO - manifests [ ] 60/20426 4h17m 21:50:26 INFO - manifests [ ] 61/20426 4h19m 21:50:26 INFO - manifests [ ] 62/20426 4h21m 21:50:26 INFO - manifests [ ] 63/20426 4h23m 21:50:26 INFO - manifests [ ] 64/20426 4h25m 21:50:26 INFO - manifests [ ] 65/20426 4h27m 21:50:26 INFO - manifests [ ] 66/20426 4h29m 21:50:26 INFO - manifests [ ] 67/20426 4h31m 21:50:26 INFO - manifests [ ] 68/20426 4h33m 21:50:26 INFO - manifests [ ] 69/20426 4h35m 21:50:26 INFO - manifests [ ] 70/20426 4h36m 21:50:26 INFO - manifests [ ] 71/20426 4h38m 21:50:26 INFO - manifests [ ] 72/20426 4h39m 21:50:26 INFO - manifests [ ] 73/20426 4h41m 21:50:26 INFO - manifests [ ] 74/20426 4h42m 21:50:26 INFO - manifests [ ] 75/20426 4h44m 21:50:26 INFO - manifests [ ] 76/20426 4h45m 21:50:26 INFO - manifests [ ] 77/20426 4h46m 21:50:26 INFO - manifests [ ] 78/20426 4h48m 21:50:26 INFO - manifests [ ] 79/20426 4h49m 21:50:26 INFO - manifests [ ] 80/20426 4h50m 21:50:26 INFO - manifests [ ] 81/20426 4h52m 21:50:26 INFO - manifests [ ] 82/20426 4h53m 21:50:26 INFO - manifests [ ] 83/20426 4h54m 21:50:26 INFO - manifests [ ] 84/20426 4h56m 21:50:26 INFO - manifests [ ] 85/20426 4h57m 21:50:26 INFO - manifests [ ] 86/20426 4h58m 21:50:26 INFO - manifests [ ] 87/20426 5h00m 21:50:26 INFO - manifests [ ] 88/20426 5h01m 21:50:26 INFO - manifests [ ] 89/20426 5h02m 21:50:26 INFO - manifests [ ] 90/20426 5h03m 21:50:26 INFO - manifests [ ] 91/20426 5h05m 21:50:26 INFO - manifests [ ] 92/20426 5h06m 21:50:26 INFO - manifests [ ] 93/20426 5h07m 21:50:26 INFO - manifests [ ] 94/20426 5h08m 21:50:26 INFO - manifests [ ] 95/20426 5h09m 21:50:26 INFO - manifests [ ] 96/20426 5h11m 21:50:26 INFO - manifests [ ] 97/20426 5h12m 21:50:26 INFO - manifests [ ] 98/20426 5h13m 21:50:26 INFO - manifests [ ] 99/20426 5h14m 21:50:26 INFO - manifests [ ] 100/20426 5h16m 21:50:26 INFO - manifests [ ] 107/20426 4h59m 21:50:26 INFO - manifests [==> ] 1603/20426 18m30s 21:50:26 INFO - manifests [=================> ] 8062/20426 2m27s 21:50:26 INFO - manifests [=======================> ] 10240/20426 1m36sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:50:26 INFO - (sharing from existing pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29) 21:50:26 INFO - searching for changes 21:50:26 INFO - adding changesets 21:50:26 INFO - adding manifests 21:50:26 INFO - 21:50:26 INFO - 21:50:26 INFO - transaction abort! 21:50:26 INFO - rollback completed 21:50:26 INFO - stream ended unexpectedly (got 0 bytes, expected 4) 21:50:26 INFO - (retrying after network failure on attempt 1 of 3) 21:50:26 INFO - (waiting 5.52s before retry) 21:53:32 INFO - 21:53:32 INFO - searching [ <=> ] 2 21:53:32 INFO - searching [ <=> ] 3 21:53:32 INFO - searching [ <=> ] 5 21:53:32 INFO - 21:53:32 INFO - 21:53:32 INFO - changesets [ ] 1/20426 21:53:32 INFO - changesets [========> ] 3637/20426 21:53:32 INFO - changesets [================> ] 6888/20426 21:53:32 INFO - changesets [======================> ] 9619/20426 04s 21:53:32 INFO - changesets [===========================> ] 11871/20426 03s 21:53:32 INFO - changesets [================================> ] 14087/20426 03s 21:53:32 INFO - changesets [=====================================> ] 16182/20426 02s 21:53:32 INFO - changesets [========================================> ] 17433/20426 02s 21:53:32 INFO - changesets [===========================================> ] 18583/20426 01s 21:53:32 INFO - changesets [==============================================> ] 19689/20426 01s 21:53:32 INFO - 21:53:32 INFO - 21:53:32 INFO - manifests [ ] 1/20426 21:53:32 INFO - manifests [ ] 3/20426 3w02d 21:53:32 INFO - manifests [ ] 5/20426 11d06h 21:53:32 INFO - manifests [ ] 7/20426 7d14h 21:53:32 INFO - manifests [ ] 9/20426 5d17h 21:53:32 INFO - manifests [ ] 11/20426 4d15h 21:53:32 INFO - manifests [ ] 13/20426 3d21h 21:53:32 INFO - manifests [ ] 15/20426 3d09h 21:53:32 INFO - manifests [ ] 17/20426 2d23h 21:53:32 INFO - manifests [ ] 19/20426 2d16h 21:53:32 INFO - manifests [ ] 21/20426 2d10h 21:53:32 INFO - manifests [ ] 25/20426 2d01h 21:53:32 INFO - manifests [ ] 31/20426 1d16h 21:53:32 INFO - manifests [ ] 36/20426 1d10h 21:53:32 INFO - manifests [ ] 38/20426 1d09h 21:53:32 INFO - manifests [ ] 41/20426 1d07h 21:53:32 INFO - manifests [ ] 43/20426 28h51m 21:53:32 INFO - manifests [ ] 45/20426 27h47m 21:53:32 INFO - manifests [ ] 47/20426 26h49m 21:53:32 INFO - manifests [ ] 49/20426 25h55m 21:53:32 INFO - manifests [ ] 51/20426 25h06m 21:53:32 INFO - manifests [ ] 53/20426 24h21m 21:53:32 INFO - manifests [ ] 55/20426 23h40m 21:53:32 INFO - manifests [ ] 56/20426 23h21m 21:53:32 INFO - manifests [ ] 57/20426 23h02m 21:53:32 INFO - manifests [ ] 58/20426 22h44m 21:53:32 INFO - manifests [ ] 59/20426 22h27m 21:53:32 INFO - manifests [ ] 60/20426 22h10m 21:53:32 INFO - manifests [ ] 61/20426 21h54m 21:53:32 INFO - manifests [ ] 62/20426 21h39m 21:53:32 INFO - manifests [ ] 63/20426 21h24m 21:53:32 INFO - manifests [ ] 64/20426 21h09m 21:53:32 INFO - manifests [ ] 65/20426 20h55m 21:53:32 INFO - manifests [ ] 66/20426 20h42m 21:53:32 INFO - manifests [ ] 67/20426 20h29m 21:53:32 INFO - manifests [ ] 68/20426 20h16m 21:53:32 INFO - manifests [ ] 69/20426 20h04m 21:53:32 INFO - manifests [ ] 70/20426 19h52m 21:53:32 INFO - manifests [ ] 71/20426 19h40m 21:53:32 INFO - manifests [ ] 72/20426 19h29m 21:53:32 INFO - manifests [ ] 73/20426 19h18m 21:53:32 INFO - manifests [ ] 74/20426 19h07m 21:53:32 INFO - manifests [ ] 75/20426 18h57m 21:53:32 INFO - manifests [ ] 76/20426 18h47m 21:53:32 INFO - manifests [ ] 77/20426 18h37m 21:53:32 INFO - manifests [ ] 78/20426 18h28m 21:53:32 INFO - manifests [ ] 79/20426 18h18m 21:53:32 INFO - manifests [ ] 80/20426 18h09m 21:53:32 INFO - manifests [ ] 81/20426 18h01m 21:53:32 INFO - manifests [ ] 82/20426 17h52m 21:53:32 INFO - manifests [ ] 83/20426 17h44m 21:53:32 INFO - manifests [ ] 84/20426 17h36m 21:53:32 INFO - manifests [ ] 85/20426 17h28m 21:53:32 INFO - manifests [ ] 86/20426 17h20m 21:53:32 INFO - manifests [ ] 87/20426 17h13m 21:53:32 INFO - manifests [ ] 88/20426 17h06m 21:53:32 INFO - manifests [ ] 89/20426 16h59m 21:53:32 INFO - manifests [ ] 90/20426 16h52m 21:53:32 INFO - manifests [ ] 91/20426 16h45m 21:53:32 INFO - manifests [ ] 92/20426 16h39m 21:53:32 INFO - manifests [ ] 93/20426 16h32m 21:53:32 INFO - manifests [ ] 94/20426 16h26m 21:53:32 INFO - manifests [ ] 95/20426 16h20m 21:53:32 INFO - manifests [ ] 96/20426 16h14m 21:53:32 INFO - manifests [ ] 97/20426 16h08m 21:53:32 INFO - manifests [ ] 98/20426 16h03m 21:53:32 INFO - manifests [ ] 99/20426 15h57m 21:53:32 INFO - manifests [ ] 100/20426 15h52m 21:53:32 INFO - manifests [ ] 107/20426 14h53m 21:53:32 INFO - manifests [==> ] 1562/20426 56m29s 21:53:32 INFO - manifests [=================> ] 7825/20426 7m34sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:53:32 INFO - (existing repository shared store: C:\builds\hg-shared\8ba995b74e18334ab3707f27e9eb8f4e37ba3d29\.hg) 21:53:32 INFO - (pulling to obtain 03438d67a48a8590220d787672054e81f77dc99c) 21:53:32 INFO - searching for changes 21:53:32 INFO - adding changesets 21:53:32 INFO - adding manifests 21:53:32 INFO - 21:53:32 INFO - 21:53:32 INFO - transaction abort! 21:53:32 INFO - rollback completed 21:53:32 INFO - stream ended unexpectedly (got 0 bytes, expected 4) 21:53:32 INFO - (retrying after network failure on attempt 2 of 3) 21:53:32 INFO - (waiting 9.00s before retry) 21:55:44 INFO - 21:55:44 INFO - searching [ <=> ] 2 21:55:44 INFO - searching [ <=> ] 3 21:55:44 INFO - searching [ <=> ] 5 21:55:44 INFO - 21:55:44 INFO - 21:55:44 INFO - changesets [ ] 1/20426 21:55:44 INFO - changesets [=======> ] 3338/20426 21:55:44 INFO - changesets [================> ] 6566/20426 21:55:44 INFO - changesets [=====================> ] 9317/20426 04s 21:55:44 INFO - changesets [===========================> ] 11752/20426 03s 21:55:44 INFO - changesets [================================> ] 13806/20426 03s 21:55:44 INFO - changesets [=====================================> ] 15929/20426 02s 21:55:44 INFO - changesets [========================================> ] 17262/20426 02s 21:55:44 INFO - changesets [===========================================> ] 18394/20426 01s 21:55:44 INFO - changesets [=============================================> ] 19488/20426 01s 21:55:44 INFO - 21:55:44 INFO - 21:55:44 INFO - manifests [ ] 1/20426 21:55:44 INFO - manifests [ ] 3/20426 5w03d 21:55:44 INFO - manifests [ ] 5/20426 2w06d 21:55:44 INFO - manifests [ ] 7/20426 12d18h 21:55:44 INFO - manifests [ ] 9/20426 9d15h 21:55:44 INFO - manifests [ ] 11/20426 7d18h 21:55:44 INFO - manifests [ ] 13/20426 6d12h 21:55:44 INFO - manifests [ ] 15/20426 5d14h 21:55:44 INFO - manifests [ ] 17/20426 4d22h 21:55:44 INFO - manifests [ ] 19/20426 4d09h 21:55:44 INFO - manifests [ ] 21/20426 3d23h 21:55:44 INFO - manifests [ ] 25/20426 3d08h 21:55:44 INFO - manifests [ ] 31/20426 2d16h 21:55:44 INFO - manifests [ ] 36/20426 2d08h 21:55:44 INFO - manifests [ ] 38/20426 2d05h 21:55:44 INFO - manifests [ ] 41/20426 2d01h 21:55:44 INFO - manifests [ ] 43/20426 1d23h 21:55:44 INFO - manifests [ ] 45/20426 1d21h 21:55:44 INFO - manifests [ ] 47/20426 1d20h 21:55:44 INFO - manifests [ ] 49/20426 1d18h 21:55:44 INFO - manifests [ ] 51/20426 1d17h 21:55:44 INFO - manifests [ ] 53/20426 1d15h 21:55:44 INFO - manifests [ ] 55/20426 1d14h 21:55:44 INFO - manifests [ ] 56/20426 1d13h 21:55:44 INFO - manifests [ ] 57/20426 1d13h 21:55:44 INFO - manifests [ ] 58/20426 1d12h 21:55:44 INFO - manifests [ ] 59/20426 1d12h 21:55:44 INFO - manifests [ ] 60/20426 1d11h 21:55:44 INFO - manifests [ ] 61/20426 1d11h 21:55:44 INFO - manifests [ ] 62/20426 1d10h 21:55:44 INFO - manifests [ ] 63/20426 1d10h 21:55:44 INFO - manifests [ ] 64/20426 1d10h 21:55:44 INFO - manifests [ ] 65/20426 1d09h 21:55:44 INFO - manifests [ ] 66/20426 1d09h 21:55:44 INFO - manifests [ ] 67/20426 1d08h 21:55:44 INFO - manifests [ ] 68/20426 1d08h 21:55:44 INFO - manifests [ ] 69/20426 1d08h 21:55:44 INFO - manifests [ ] 70/20426 1d07h 21:55:44 INFO - manifests [ ] 71/20426 1d07h 21:55:44 INFO - manifests [ ] 72/20426 29h59m 21:55:44 INFO - manifests [ ] 73/20426 29h39m 21:55:44 INFO - manifests [ ] 74/20426 29h20m 21:55:44 INFO - manifests [ ] 75/20426 29h01m 21:55:44 INFO - manifests [ ] 76/20426 28h43m 21:55:44 INFO - manifests [ ] 77/20426 28h26m 21:55:44 INFO - manifests [ ] 78/20426 28h08m 21:55:44 INFO - manifests [ ] 79/20426 27h52m 21:55:44 INFO - manifests [ ] 80/20426 27h35m 21:55:44 INFO - manifests [ ] 81/20426 27h20m 21:55:44 INFO - manifests [ ] 82/20426 27h04m 21:55:44 INFO - manifests [ ] 83/20426 26h49m 21:55:44 INFO - manifests [ ] 84/20426 26h35m 21:55:44 INFO - manifests [ ] 85/20426 26h20m 21:55:44 INFO - manifests [ ] 86/20426 26h06m 21:55:44 INFO - manifests [ ] 87/20426 25h53m 21:55:44 INFO - manifests [ ] 88/20426 25h40m 21:55:44 INFO - manifests [ ] 89/20426 25h27m 21:55:44 INFO - manifests [ ] 90/20426 25h14m 21:55:44 INFO - manifests [ ] 91/20426 25h02m 21:55:44 INFO - manifests [ ] 92/20426 24h50m 21:55:44 INFO - manifests [ ] 93/20426 24h38m 21:55:44 INFO - manifests [ ] 94/20426 24h27m 21:55:44 INFO - manifests [ ] 95/20426 24h15m 21:55:44 INFO - manifests [ ] 96/20426 24h04m 21:55:44 INFO - manifests [ ] 97/20426 23h54m 21:55:44 INFO - manifests [ ] 98/20426 23h43m 21:55:44 INFO - manifests [ ] 99/20426 23h33m 21:55:44 INFO - manifests [ ] 100/20426 23h23m 21:55:44 INFO - manifests [ ] 107/20426 21h54m 21:55:44 INFO - manifests [==> ] 1562/20426 1h24m 21:55:44 INFO - manifests [=================> ] 7849/20426 11m03sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:55:44 INFO - (existing repository shared store: C:\builds\hg-shared\8ba995b74e18334ab3707f27e9eb8f4e37ba3d29\.hg) 21:55:44 INFO - (pulling to obtain 03438d67a48a8590220d787672054e81f77dc99c) 21:55:44 INFO - searching for changes 21:55:44 INFO - adding changesets 21:55:44 INFO - adding manifests 21:55:44 INFO - 21:55:44 INFO - 21:55:44 INFO - transaction abort! 21:55:44 INFO - rollback completed 21:55:44 INFO - stream ended unexpectedly (got 42091 bytes, expected 1715023923) 21:55:44 INFO - Traceback (most recent call last): 21:55:44 INFO - File "mercurial\dispatch.pyc", line 204, in _runcatch 21:55:44 INFO - File "mercurial\dispatch.pyc", line 880, in _dispatch 21:55:44 INFO - File "mercurial\dispatch.pyc", line 637, in runcommand 21:55:44 INFO - File "mercurial\extensions.pyc", line 210, in closure 21:55:44 INFO - File "hgext\color.pyc", line 503, in colorcmd 21:55:44 INFO - File "mercurial\dispatch.pyc", line 1010, in _runcommand 21:55:44 INFO - File "mercurial\dispatch.pyc", line 971, in checkargs 21:55:44 INFO - File "mercurial\dispatch.pyc", line 877, in 21:55:44 INFO - File "mercurial\util.pyc", line 1036, in check 21:55:44 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 163, in robustcheckout 21:55:44 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 289, in _docheckout 21:55:44 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 172, in callself 21:55:44 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 348, in _docheckout 21:55:44 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 172, in callself 21:55:44 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 347, in _docheckout 21:55:44 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 262, in handlepullabort 21:55:44 INFO - Abort: reached maximum number of network attempts; giving up 21:55:44 INFO - abort: reached maximum number of network attempts; giving up 21:55:44 ERROR - Return code: 255 21:55:44 INFO - rmtree: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 21:55:44 INFO - Using _rmtree_windows ... 21:55:44 INFO - retry: Calling _rmtree_windows with args: ('c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src',), kwargs: {}, attempt #1 21:55:44 INFO - retry: attempt #3 caught exception: repo checkout failed! 21:55:44 INFO - retry: Failed, sleeping 240 seconds before retrying 21:59:44 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src'), kwargs: {}, attempt #4 21:59:44 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 21:59:44 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 21:59:45 INFO - Mercurial Distributed SCM (version 3.9.1) 21:59:45 INFO - (see https://mercurial-scm.org for more information) 21:59:45 INFO - Copyright (C) 2005-2016 Matt Mackall and others 21:59:45 INFO - This is free software; see the source for copying conditions. There is NO 21:59:45 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 21:59:45 INFO - Return code: 0 21:59:45 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 21:59:45 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 21:59:45 INFO - checking encoding (cp1252)... 21:59:45 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 21:59:45 INFO - checking Python version (2.7.12) 21:59:45 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 21:59:45 INFO - checking Mercurial version (3.9.1) 21:59:45 INFO - checking Mercurial custom build () 21:59:45 INFO - checking module policy (c) 21:59:45 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 21:59:45 INFO - checking templates (c:\mozilla-build\hg\templates)... 21:59:45 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 21:59:45 INFO - checking commit editor... (notepad) 21:59:45 INFO - checking username (Mozilla Release Engineering ) 21:59:45 INFO - no problems detected 21:59:45 INFO - Return code: 0 21:59:45 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/projects/ash', 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src', '--sharebase', 'C:/builds/hg-shared', '--revision', '03438d67a48a8590220d787672054e81f77dc99c'] 21:59:45 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/projects/ash c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src --sharebase C:/builds/hg-shared --revision 03438d67a48a8590220d787672054e81f77dc99c 22:01:47 INFO - 22:01:47 INFO - searching [ <=> ] 3 22:01:47 INFO - searching [ <=> ] 4 22:01:47 INFO - searching [ <=> ] 6 22:01:47 INFO - 22:01:47 INFO - 22:01:47 INFO - changesets [==============> ] 6092/20426 22:01:47 INFO - changesets [=====================> ] 9210/20426 04s 22:01:47 INFO - changesets [===========================> ] 11811/20426 03s 22:01:47 INFO - changesets [=================================> ] 14216/20426 02s 22:01:47 INFO - changesets [======================================> ] 16398/20426 02s 22:01:47 INFO - changesets [=========================================> ] 17618/20426 02s 22:01:47 INFO - changesets [============================================> ] 18795/20426 01s 22:01:47 INFO - changesets [==============================================> ] 19934/20426 01s 22:01:47 INFO - 22:01:47 INFO - 22:01:47 INFO - manifests [ ] 4/20426 4h46m 22:01:47 INFO - manifests [ ] 6/20426 4h42m 22:01:47 INFO - manifests [ ] 8/20426 4h41m 22:01:47 INFO - manifests [ ] 10/20426 4h40m 22:01:47 INFO - manifests [ ] 12/20426 4h40m 22:01:47 INFO - manifests [ ] 14/20426 4h41m 22:01:47 INFO - manifests [ ] 16/20426 4h42m 22:01:47 INFO - manifests [ ] 18/20426 4h43m 22:01:47 INFO - manifests [ ] 20/20426 4h44m 22:01:47 INFO - manifests [ ] 22/20426 4h45m 22:01:47 INFO - manifests [ ] 30/20426 3h47m 22:01:47 INFO - manifests [ ] 32/20426 3h51m 22:01:47 INFO - manifests [ ] 37/20426 3h36m 22:01:47 INFO - manifests [ ] 40/20426 3h35m 22:01:47 INFO - manifests [ ] 42/20426 3h39m 22:01:47 INFO - manifests [ ] 44/20426 3h44m 22:01:47 INFO - manifests [ ] 46/20426 3h48m 22:01:47 INFO - manifests [ ] 48/20426 3h52m 22:01:47 INFO - manifests [ ] 50/20426 3h56m 22:01:47 INFO - manifests [ ] 52/20426 4h00m 22:01:47 INFO - manifests [ ] 54/20426 4h04m 22:01:47 INFO - manifests [ ] 55/20426 4h06m 22:01:47 INFO - manifests [ ] 56/20426 4h08m 22:01:47 INFO - manifests [ ] 57/20426 4h10m 22:01:47 INFO - manifests [ ] 58/20426 4h12m 22:01:47 INFO - manifests [ ] 59/20426 4h14m 22:01:47 INFO - manifests [ ] 60/20426 4h16m 22:01:47 INFO - manifests [ ] 61/20426 4h18m 22:01:47 INFO - manifests [ ] 62/20426 4h20m 22:01:47 INFO - manifests [ ] 63/20426 4h22m 22:01:47 INFO - manifests [ ] 64/20426 4h24m 22:01:47 INFO - manifests [ ] 65/20426 4h26m 22:01:47 INFO - manifests [ ] 66/20426 4h27m 22:01:47 INFO - manifests [ ] 67/20426 4h29m 22:01:47 INFO - manifests [ ] 68/20426 4h31m 22:01:47 INFO - manifests [ ] 69/20426 4h33m 22:01:47 INFO - manifests [ ] 70/20426 4h34m 22:01:47 INFO - manifests [ ] 71/20426 4h36m 22:01:47 INFO - manifests [ ] 72/20426 4h37m 22:01:47 INFO - manifests [ ] 73/20426 4h39m 22:01:47 INFO - manifests [ ] 74/20426 4h40m 22:01:47 INFO - manifests [ ] 75/20426 4h42m 22:01:47 INFO - manifests [ ] 76/20426 4h43m 22:01:47 INFO - manifests [ ] 77/20426 4h44m 22:01:47 INFO - manifests [ ] 78/20426 4h46m 22:01:47 INFO - manifests [ ] 79/20426 4h47m 22:01:47 INFO - manifests [ ] 80/20426 4h48m 22:01:47 INFO - manifests [ ] 81/20426 4h50m 22:01:47 INFO - manifests [ ] 82/20426 4h51m 22:01:47 INFO - manifests [ ] 83/20426 4h52m 22:01:47 INFO - manifests [ ] 84/20426 4h54m 22:01:47 INFO - manifests [ ] 85/20426 4h55m 22:01:47 INFO - manifests [ ] 86/20426 4h56m 22:01:47 INFO - manifests [ ] 87/20426 4h58m 22:01:47 INFO - manifests [ ] 88/20426 4h59m 22:01:47 INFO - manifests [ ] 89/20426 5h00m 22:01:47 INFO - manifests [ ] 90/20426 5h02m 22:01:47 INFO - manifests [ ] 91/20426 5h03m 22:01:47 INFO - manifests [ ] 92/20426 5h04m 22:01:47 INFO - manifests [ ] 93/20426 5h06m 22:01:47 INFO - manifests [ ] 94/20426 5h07m 22:01:47 INFO - manifests [ ] 95/20426 5h08m 22:01:47 INFO - manifests [ ] 96/20426 5h09m 22:01:47 INFO - manifests [ ] 97/20426 5h11m 22:01:47 INFO - manifests [ ] 98/20426 5h12m 22:01:47 INFO - manifests [ ] 99/20426 5h13m 22:01:47 INFO - manifests [ ] 100/20426 5h14m 22:01:47 INFO - manifests [ ] 107/20426 4h58m 22:01:47 INFO - manifests [==> ] 1625/20426 18m09s 22:01:47 INFO - manifests [==================> ] 8227/20426 2m21s 22:01:47 INFO - manifests [=======================> ] 10241/20426 1m36sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 22:01:47 INFO - (sharing from existing pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29) 22:01:47 INFO - searching for changes 22:01:47 INFO - adding changesets 22:01:47 INFO - adding manifests 22:01:47 INFO - 22:01:47 INFO - 22:01:47 INFO - transaction abort! 22:01:47 INFO - rollback completed 22:01:47 INFO - stream ended unexpectedly (got 0 bytes, expected 4) 22:01:47 INFO - (retrying after network failure on attempt 1 of 3) 22:01:47 INFO - (waiting 3.47s before retry) 22:04:50 INFO - 22:04:50 INFO - searching [ <=> ] 2 22:04:50 INFO - searching [ <=> ] 3 22:04:50 INFO - searching [ <=> ] 5 22:04:50 INFO - 22:04:50 INFO - 22:04:50 INFO - changesets [ ] 1/20426 22:04:50 INFO - changesets [========> ] 3729/20426 22:04:50 INFO - changesets [=================> ] 6964/20426 22:04:50 INFO - changesets [======================> ] 9699/20426 04s 22:04:50 INFO - changesets [===========================> ] 11926/20426 03s 22:04:50 INFO - changesets [=================================> ] 14207/20426 03s 22:04:50 INFO - changesets [======================================> ] 16325/20426 02s 22:04:50 INFO - changesets [========================================> ] 17492/20426 02s 22:04:50 INFO - changesets [===========================================> ] 18634/20426 01s 22:04:50 INFO - changesets [==============================================> ] 19732/20426 01s 22:04:50 INFO - 22:04:50 INFO - 22:04:50 INFO - manifests [ ] 1/20426 22:04:50 INFO - manifests [ ] 3/20426 3w01d 22:04:50 INFO - manifests [ ] 5/20426 11d01h 22:04:50 INFO - manifests [ ] 7/20426 7d10h 22:04:50 INFO - manifests [ ] 9/20426 5d15h 22:04:50 INFO - manifests [ ] 11/20426 4d13h 22:04:50 INFO - manifests [ ] 13/20426 3d20h 22:04:50 INFO - manifests [ ] 15/20426 3d07h 22:04:50 INFO - manifests [ ] 17/20426 2d22h 22:04:50 INFO - manifests [ ] 19/20426 2d15h 22:04:50 INFO - manifests [ ] 21/20426 2d09h 22:04:50 INFO - manifests [ ] 25/20426 2d00h 22:04:50 INFO - manifests [ ] 31/20426 1d15h 22:04:50 INFO - manifests [ ] 36/20426 1d10h 22:04:50 INFO - manifests [ ] 38/20426 1d08h 22:04:50 INFO - manifests [ ] 41/20426 29h35m 22:04:50 INFO - manifests [ ] 43/20426 28h25m 22:04:50 INFO - manifests [ ] 45/20426 27h21m 22:04:50 INFO - manifests [ ] 47/20426 26h24m 22:04:50 INFO - manifests [ ] 49/20426 25h32m 22:04:50 INFO - manifests [ ] 51/20426 24h43m 22:04:50 INFO - manifests [ ] 53/20426 23h59m 22:04:50 INFO - manifests [ ] 55/20426 23h19m 22:04:50 INFO - manifests [ ] 56/20426 23h00m 22:04:50 INFO - manifests [ ] 57/20426 22h41m 22:04:50 INFO - manifests [ ] 58/20426 22h24m 22:04:50 INFO - manifests [ ] 59/20426 22h07m 22:04:50 INFO - manifests [ ] 60/20426 21h51m 22:04:50 INFO - manifests [ ] 61/20426 21h35m 22:04:50 INFO - manifests [ ] 62/20426 21h20m 22:04:50 INFO - manifests [ ] 63/20426 21h05m 22:04:50 INFO - manifests [ ] 64/20426 20h51m 22:04:50 INFO - manifests [ ] 65/20426 20h37m 22:04:50 INFO - manifests [ ] 66/20426 20h24m 22:04:50 INFO - manifests [ ] 67/20426 20h11m 22:04:50 INFO - manifests [ ] 68/20426 19h59m 22:04:50 INFO - manifests [ ] 69/20426 19h47m 22:04:50 INFO - manifests [ ] 70/20426 19h35m 22:04:50 INFO - manifests [ ] 71/20426 19h24m 22:04:50 INFO - manifests [ ] 72/20426 19h12m 22:04:50 INFO - manifests [ ] 73/20426 19h02m 22:04:50 INFO - manifests [ ] 74/20426 18h51m 22:04:50 INFO - manifests [ ] 75/20426 18h41m 22:04:50 INFO - manifests [ ] 76/20426 18h31m 22:04:50 INFO - manifests [ ] 77/20426 18h22m 22:04:50 INFO - manifests [ ] 78/20426 18h12m 22:04:50 INFO - manifests [ ] 79/20426 18h03m 22:04:50 INFO - manifests [ ] 80/20426 17h55m 22:04:50 INFO - manifests [ ] 81/20426 17h46m 22:04:50 INFO - manifests [ ] 82/20426 17h38m 22:04:50 INFO - manifests [ ] 83/20426 17h30m 22:04:50 INFO - manifests [ ] 84/20426 17h22m 22:04:50 INFO - manifests [ ] 85/20426 17h14m 22:04:50 INFO - manifests [ ] 86/20426 17h07m 22:04:50 INFO - manifests [ ] 87/20426 16h59m 22:04:50 INFO - manifests [ ] 88/20426 16h52m 22:04:50 INFO - manifests [ ] 89/20426 16h45m 22:04:50 INFO - manifests [ ] 90/20426 16h39m 22:04:50 INFO - manifests [ ] 91/20426 16h32m 22:04:50 INFO - manifests [ ] 92/20426 16h26m 22:04:50 INFO - manifests [ ] 93/20426 16h19m 22:04:50 INFO - manifests [ ] 94/20426 16h13m 22:04:50 INFO - manifests [ ] 95/20426 16h07m 22:04:50 INFO - manifests [ ] 96/20426 16h02m 22:04:50 INFO - manifests [ ] 97/20426 15h56m 22:04:50 INFO - manifests [ ] 98/20426 15h51m 22:04:50 INFO - manifests [ ] 99/20426 15h45m 22:04:50 INFO - manifests [ ] 100/20426 15h40m 22:04:50 INFO - manifests [ ] 107/20426 14h42m 22:04:50 INFO - manifests [==> ] 1574/20426 55m19s 22:04:50 INFO - manifests [==================> ] 8101/20426 7m03s 22:04:50 INFO - manifests [=======================> ] 10240/20426 4m38sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 22:04:50 INFO - (existing repository shared store: C:\builds\hg-shared\8ba995b74e18334ab3707f27e9eb8f4e37ba3d29\.hg) 22:04:50 INFO - (pulling to obtain 03438d67a48a8590220d787672054e81f77dc99c) 22:04:50 INFO - searching for changes 22:04:50 INFO - adding changesets 22:04:50 INFO - adding manifests 22:04:50 INFO - 22:04:50 INFO - 22:04:50 INFO - transaction abort! 22:04:50 INFO - rollback completed 22:04:50 INFO - stream ended unexpectedly (got 165966 bytes, expected 892941153) 22:04:50 INFO - (retrying after network failure on attempt 2 of 3) 22:04:50 INFO - (waiting 6.07s before retry) 22:06:59 INFO - 22:06:59 INFO - searching [ <=> ] 2 22:06:59 INFO - searching [ <=> ] 3 22:06:59 INFO - searching [ <=> ] 5 22:06:59 INFO - 22:06:59 INFO - 22:06:59 INFO - changesets [ ] 1/20426 22:06:59 INFO - changesets [========> ] 3641/20426 22:06:59 INFO - changesets [================> ] 6867/20426 22:06:59 INFO - changesets [======================> ] 9594/20426 04s 22:06:59 INFO - changesets [===========================> ] 11844/20426 03s 22:06:59 INFO - changesets [================================> ] 14094/20426 03s 22:06:59 INFO - changesets [=====================================> ] 16186/20426 02s 22:06:59 INFO - changesets [========================================> ] 17416/20426 02s 22:06:59 INFO - changesets [===========================================> ] 18538/20426 01s 22:06:59 INFO - changesets [==============================================> ] 19624/20426 01s 22:06:59 INFO - 22:06:59 INFO - 22:06:59 INFO - manifests [ ] 1/20426 22:06:59 INFO - manifests [ ] 3/20426 5w03d 22:06:59 INFO - manifests [ ] 5/20426 2w05d 22:06:59 INFO - manifests [ ] 7/20426 12d11h 22:06:59 INFO - manifests [ ] 9/20426 9d10h 22:06:59 INFO - manifests [ ] 11/20426 7d14h 22:06:59 INFO - manifests [ ] 13/20426 6d08h 22:06:59 INFO - manifests [ ] 15/20426 5d11h 22:06:59 INFO - manifests [ ] 17/20426 4d19h 22:06:59 INFO - manifests [ ] 19/20426 4d07h 22:06:59 INFO - manifests [ ] 21/20426 3d21h 22:06:59 INFO - manifests [ ] 25/20426 3d06h 22:06:59 INFO - manifests [ ] 31/20426 2d15h 22:06:59 INFO - manifests [ ] 36/20426 2d06h 22:06:59 INFO - manifests [ ] 38/20426 2d04h 22:06:59 INFO - manifests [ ] 41/20426 2d00h 22:06:59 INFO - manifests [ ] 43/20426 1d22h 22:06:59 INFO - manifests [ ] 45/20426 1d20h 22:06:59 INFO - manifests [ ] 47/20426 1d19h 22:06:59 INFO - manifests [ ] 49/20426 1d17h 22:06:59 INFO - manifests [ ] 51/20426 1d16h 22:06:59 INFO - manifests [ ] 53/20426 1d14h 22:06:59 INFO - manifests [ ] 55/20426 1d13h 22:06:59 INFO - manifests [ ] 56/20426 1d13h 22:06:59 INFO - manifests [ ] 57/20426 1d12h 22:06:59 INFO - manifests [ ] 58/20426 1d12h 22:06:59 INFO - manifests [ ] 59/20426 1d11h 22:06:59 INFO - manifests [ ] 60/20426 1d11h 22:06:59 INFO - manifests [ ] 61/20426 1d10h 22:06:59 INFO - manifests [ ] 62/20426 1d10h 22:06:59 INFO - manifests [ ] 63/20426 1d09h 22:06:59 INFO - manifests [ ] 64/20426 1d09h 22:06:59 INFO - manifests [ ] 65/20426 1d08h 22:06:59 INFO - manifests [ ] 66/20426 1d08h 22:06:59 INFO - manifests [ ] 67/20426 1d08h 22:06:59 INFO - manifests [ ] 68/20426 1d07h 22:06:59 INFO - manifests [ ] 69/20426 1d07h 22:06:59 INFO - manifests [ ] 70/20426 1d07h 22:06:59 INFO - manifests [ ] 71/20426 29h45m 22:06:59 INFO - manifests [ ] 72/20426 29h25m 22:06:59 INFO - manifests [ ] 73/20426 29h05m 22:06:59 INFO - manifests [ ] 74/20426 28h47m 22:06:59 INFO - manifests [ ] 75/20426 28h28m 22:06:59 INFO - manifests [ ] 76/20426 28h11m 22:06:59 INFO - manifests [ ] 77/20426 27h53m 22:06:59 INFO - manifests [ ] 78/20426 27h37m 22:06:59 INFO - manifests [ ] 79/20426 27h20m 22:06:59 INFO - manifests [ ] 80/20426 27h04m 22:06:59 INFO - manifests [ ] 81/20426 26h49m 22:06:59 INFO - manifests [ ] 82/20426 26h34m 22:06:59 INFO - manifests [ ] 83/20426 26h19m 22:06:59 INFO - manifests [ ] 84/20426 26h05m 22:06:59 INFO - manifests [ ] 85/20426 25h51m 22:06:59 INFO - manifests [ ] 86/20426 25h38m 22:06:59 INFO - manifests [ ] 87/20426 25h24m 22:06:59 INFO - manifests [ ] 88/20426 25h11m 22:06:59 INFO - manifests [ ] 89/20426 24h59m 22:06:59 INFO - manifests [ ] 90/20426 24h46m 22:06:59 INFO - manifests [ ] 91/20426 24h34m 22:06:59 INFO - manifests [ ] 92/20426 24h23m 22:06:59 INFO - manifests [ ] 93/20426 24h11m 22:06:59 INFO - manifests [ ] 94/20426 24h00m 22:06:59 INFO - manifests [ ] 95/20426 23h49m 22:06:59 INFO - manifests [ ] 96/20426 23h38m 22:06:59 INFO - manifests [ ] 97/20426 23h28m 22:06:59 INFO - manifests [ ] 98/20426 23h18m 22:06:59 INFO - manifests [ ] 99/20426 23h08m 22:06:59 INFO - manifests [ ] 100/20426 22h58m 22:06:59 INFO - manifests [ ] 107/20426 21h31m 22:06:59 INFO - manifests [==> ] 1574/20426 1h21m 22:06:59 INFO - manifests [=================> ] 8059/20426 10m24sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 22:06:59 INFO - (existing repository shared store: C:\builds\hg-shared\8ba995b74e18334ab3707f27e9eb8f4e37ba3d29\.hg) 22:06:59 INFO - (pulling to obtain 03438d67a48a8590220d787672054e81f77dc99c) 22:06:59 INFO - searching for changes 22:06:59 INFO - adding changesets 22:06:59 INFO - adding manifests 22:06:59 INFO - 22:06:59 INFO - 22:06:59 INFO - transaction abort! 22:06:59 INFO - rollback completed 22:06:59 INFO - stream ended unexpectedly (got 16904 bytes, expected 1869837101) 22:06:59 INFO - Traceback (most recent call last): 22:06:59 INFO - File "mercurial\dispatch.pyc", line 204, in _runcatch 22:06:59 INFO - File "mercurial\dispatch.pyc", line 880, in _dispatch 22:06:59 INFO - File "mercurial\dispatch.pyc", line 637, in runcommand 22:06:59 INFO - File "mercurial\extensions.pyc", line 210, in closure 22:06:59 INFO - File "hgext\color.pyc", line 503, in colorcmd 22:06:59 INFO - File "mercurial\dispatch.pyc", line 1010, in _runcommand 22:06:59 INFO - File "mercurial\dispatch.pyc", line 971, in checkargs 22:06:59 INFO - File "mercurial\dispatch.pyc", line 877, in 22:06:59 INFO - File "mercurial\util.pyc", line 1036, in check 22:06:59 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 163, in robustcheckout 22:06:59 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 289, in _docheckout 22:06:59 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 172, in callself 22:06:59 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 348, in _docheckout 22:06:59 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 172, in callself 22:06:59 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 347, in _docheckout 22:06:59 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 262, in handlepullabort 22:06:59 INFO - Abort: reached maximum number of network attempts; giving up 22:06:59 INFO - abort: reached maximum number of network attempts; giving up 22:07:00 ERROR - Return code: 255 22:07:00 INFO - rmtree: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 22:07:00 INFO - Using _rmtree_windows ... 22:07:00 INFO - retry: Calling _rmtree_windows with args: ('c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src',), kwargs: {}, attempt #1 22:07:00 INFO - retry: attempt #4 caught exception: repo checkout failed! 22:07:00 INFO - retry: Failed, sleeping 300 seconds before retrying 22:12:00 INFO - retry: Calling _get_revision with args: (, 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src'), kwargs: {}, attempt #5 22:12:00 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 22:12:00 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 22:12:00 INFO - Mercurial Distributed SCM (version 3.9.1) 22:12:00 INFO - (see https://mercurial-scm.org for more information) 22:12:00 INFO - Copyright (C) 2005-2016 Matt Mackall and others 22:12:00 INFO - This is free software; see the source for copying conditions. There is NO 22:12:00 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 22:12:00 INFO - Return code: 0 22:12:00 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall'] 22:12:00 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall 22:12:00 INFO - checking encoding (cp1252)... 22:12:00 INFO - checking Python executable (c:\mozilla-build\hg\hg.exe) 22:12:00 INFO - checking Python version (2.7.12) 22:12:00 INFO - checking Python lib (c:\mozilla-build\hg\lib\library.zip)... 22:12:00 INFO - checking Mercurial version (3.9.1) 22:12:00 INFO - checking Mercurial custom build () 22:12:00 INFO - checking module policy (c) 22:12:00 INFO - checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)... 22:12:00 INFO - checking templates (c:\mozilla-build\hg\templates)... 22:12:00 INFO - checking default template (c:\mozilla-build\hg\templates\map-cmdline.default) 22:12:00 INFO - checking commit editor... (notepad) 22:12:00 INFO - checking username (Mozilla Release Engineering ) 22:12:00 INFO - no problems detected 22:12:00 INFO - Return code: 0 22:12:00 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/projects/ash', 'c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src', '--sharebase', 'C:/builds/hg-shared', '--revision', '03438d67a48a8590220d787672054e81f77dc99c'] 22:12:00 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/projects/ash c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src --sharebase C:/builds/hg-shared --revision 03438d67a48a8590220d787672054e81f77dc99c 22:14:02 INFO - 22:14:02 INFO - searching [ <=> ] 3 22:14:02 INFO - searching [ <=> ] 4 22:14:02 INFO - searching [ <=> ] 6 22:14:02 INFO - 22:14:02 INFO - 22:14:02 INFO - changesets [==============> ] 5881/20426 22:14:02 INFO - changesets [====================> ] 9044/20426 04s 22:14:02 INFO - changesets [===========================> ] 11737/20426 03s 22:14:02 INFO - changesets [================================> ] 13996/20426 03s 22:14:02 INFO - changesets [=====================================> ] 16249/20426 02s 22:14:02 INFO - changesets [=========================================> ] 17508/20426 02s 22:14:02 INFO - changesets [===========================================> ] 18681/20426 01s 22:14:02 INFO - changesets [==============================================> ] 19830/20426 01s 22:14:02 INFO - 22:14:02 INFO - 22:14:02 INFO - manifests [ ] 4/20426 4h44m 22:14:02 INFO - manifests [ ] 6/20426 4h40m 22:14:02 INFO - manifests [ ] 8/20426 4h39m 22:14:02 INFO - manifests [ ] 10/20426 4h38m 22:14:02 INFO - manifests [ ] 12/20426 4h39m 22:14:02 INFO - manifests [ ] 14/20426 4h39m 22:14:02 INFO - manifests [ ] 16/20426 4h40m 22:14:02 INFO - manifests [ ] 18/20426 4h42m 22:14:02 INFO - manifests [ ] 20/20426 4h43m 22:14:02 INFO - manifests [ ] 22/20426 4h44m 22:14:02 INFO - manifests [ ] 30/20426 3h46m 22:14:02 INFO - manifests [ ] 32/20426 3h51m 22:14:02 INFO - manifests [ ] 37/20426 3h36m 22:14:02 INFO - manifests [ ] 40/20426 3h35m 22:14:02 INFO - manifests [ ] 42/20426 3h40m 22:14:02 INFO - manifests [ ] 44/20426 3h44m 22:14:02 INFO - manifests [ ] 46/20426 3h48m 22:14:02 INFO - manifests [ ] 48/20426 3h52m 22:14:02 INFO - manifests [ ] 50/20426 3h57m 22:14:02 INFO - manifests [ ] 52/20426 4h01m 22:14:02 INFO - manifests [ ] 53/20426 4h03m 22:14:02 INFO - manifests [ ] 54/20426 4h04m 22:14:02 INFO - manifests [ ] 55/20426 4h07m 22:14:02 INFO - manifests [ ] 56/20426 4h09m 22:14:02 INFO - manifests [ ] 57/20426 4h11m 22:14:02 INFO - manifests [ ] 58/20426 4h13m 22:14:02 INFO - manifests [ ] 59/20426 4h15m 22:14:02 INFO - manifests [ ] 60/20426 4h17m 22:14:02 INFO - manifests [ ] 61/20426 4h19m 22:14:02 INFO - manifests [ ] 62/20426 4h21m 22:14:02 INFO - manifests [ ] 63/20426 4h23m 22:14:02 INFO - manifests [ ] 64/20426 4h25m 22:14:02 INFO - manifests [ ] 65/20426 4h26m 22:14:02 INFO - manifests [ ] 66/20426 4h28m 22:14:02 INFO - manifests [ ] 67/20426 4h30m 22:14:02 INFO - manifests [ ] 68/20426 4h32m 22:14:02 INFO - manifests [ ] 69/20426 4h34m 22:14:02 INFO - manifests [ ] 70/20426 4h35m 22:14:02 INFO - manifests [ ] 71/20426 4h37m 22:14:02 INFO - manifests [ ] 72/20426 4h39m 22:14:02 INFO - manifests [ ] 73/20426 4h40m 22:14:02 INFO - manifests [ ] 74/20426 4h42m 22:14:02 INFO - manifests [ ] 75/20426 4h43m 22:14:02 INFO - manifests [ ] 76/20426 4h44m 22:14:02 INFO - manifests [ ] 77/20426 4h46m 22:14:02 INFO - manifests [ ] 78/20426 4h47m 22:14:02 INFO - manifests [ ] 79/20426 4h49m 22:14:02 INFO - manifests [ ] 80/20426 4h50m 22:14:02 INFO - manifests [ ] 81/20426 4h51m 22:14:02 INFO - manifests [ ] 82/20426 4h53m 22:14:02 INFO - manifests [ ] 83/20426 4h54m 22:14:02 INFO - manifests [ ] 84/20426 4h55m 22:14:02 INFO - manifests [ ] 85/20426 4h57m 22:14:02 INFO - manifests [ ] 86/20426 4h58m 22:14:02 INFO - manifests [ ] 87/20426 4h59m 22:14:02 INFO - manifests [ ] 88/20426 5h00m 22:14:02 INFO - manifests [ ] 89/20426 5h02m 22:14:02 INFO - manifests [ ] 90/20426 5h03m 22:14:02 INFO - manifests [ ] 91/20426 5h04m 22:14:02 INFO - manifests [ ] 92/20426 5h05m 22:14:02 INFO - manifests [ ] 93/20426 5h07m 22:14:02 INFO - manifests [ ] 94/20426 5h08m 22:14:02 INFO - manifests [ ] 95/20426 5h09m 22:14:02 INFO - manifests [ ] 96/20426 5h10m 22:14:02 INFO - manifests [ ] 97/20426 5h12m 22:14:02 INFO - manifests [ ] 98/20426 5h13m 22:14:02 INFO - manifests [ ] 99/20426 5h14m 22:14:02 INFO - manifests [ ] 100/20426 5h15m 22:14:02 INFO - manifests [ ] 107/20426 4h59m 22:14:02 INFO - manifests [==> ] 1612/20426 18m22s 22:14:02 INFO - manifests [==================> ] 8127/20426 2m25sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 22:14:02 INFO - (sharing from existing pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29) 22:14:02 INFO - searching for changes 22:14:02 INFO - adding changesets 22:14:02 INFO - adding manifests 22:14:02 INFO - 22:14:02 INFO - 22:14:02 INFO - transaction abort! 22:14:02 INFO - rollback completed 22:14:02 INFO - stream ended unexpectedly (got 0 bytes, expected 4) 22:14:02 INFO - (retrying after network failure on attempt 1 of 3) 22:14:02 INFO - (waiting 3.01s before retry) 22:17:08 INFO - 22:17:08 INFO - searching [ <=> ] 2 22:17:08 INFO - searching [ <=> ] 3 22:17:08 INFO - searching [ <=> ] 5 22:17:08 INFO - 22:17:08 INFO - 22:17:08 INFO - changesets [ ] 1/20426 22:17:08 INFO - changesets [========> ] 3551/20426 22:17:08 INFO - changesets [================> ] 6792/20426 22:17:08 INFO - changesets [=====================> ] 9529/20426 04s 22:17:08 INFO - changesets [===========================> ] 11811/20426 03s 22:17:08 INFO - changesets [================================> ] 14046/20426 03s 22:17:08 INFO - changesets [=====================================> ] 16170/20426 02s 22:17:08 INFO - changesets [========================================> ] 17402/20426 02s 22:17:08 INFO - changesets [===========================================> ] 18531/20426 01s 22:17:08 INFO - changesets [==============================================> ] 19619/20426 01s 22:17:08 INFO - 22:17:08 INFO - 22:17:08 INFO - manifests [ ] 1/20426 22:17:08 INFO - manifests [ ] 3/20426 3w01d 22:17:08 INFO - manifests [ ] 5/20426 11d02h 22:17:08 INFO - manifests [ ] 7/20426 7d11h 22:17:08 INFO - manifests [ ] 9/20426 5d16h 22:17:08 INFO - manifests [ ] 11/20426 4d14h 22:17:08 INFO - manifests [ ] 13/20426 3d20h 22:17:08 INFO - manifests [ ] 15/20426 3d08h 22:17:08 INFO - manifests [ ] 17/20426 2d22h 22:17:08 INFO - manifests [ ] 19/20426 2d15h 22:17:08 INFO - manifests [ ] 21/20426 2d09h 22:17:08 INFO - manifests [ ] 25/20426 2d00h 22:17:08 INFO - manifests [ ] 31/20426 1d15h 22:17:08 INFO - manifests [ ] 36/20426 1d10h 22:17:08 INFO - manifests [ ] 38/20426 1d08h 22:17:08 INFO - manifests [ ] 41/20426 29h41m 22:17:08 INFO - manifests [ ] 43/20426 28h31m 22:17:08 INFO - manifests [ ] 45/20426 27h28m 22:17:08 INFO - manifests [ ] 47/20426 26h30m 22:17:08 INFO - manifests [ ] 49/20426 25h37m 22:17:08 INFO - manifests [ ] 51/20426 24h49m 22:17:08 INFO - manifests [ ] 53/20426 24h04m 22:17:08 INFO - manifests [ ] 55/20426 23h24m 22:17:08 INFO - manifests [ ] 56/20426 23h05m 22:17:08 INFO - manifests [ ] 57/20426 22h46m 22:17:08 INFO - manifests [ ] 58/20426 22h29m 22:17:08 INFO - manifests [ ] 59/20426 22h12m 22:17:08 INFO - manifests [ ] 60/20426 21h55m 22:17:08 INFO - manifests [ ] 61/20426 21h39m 22:17:08 INFO - manifests [ ] 62/20426 21h24m 22:17:08 INFO - manifests [ ] 63/20426 21h09m 22:17:08 INFO - manifests [ ] 64/20426 20h55m 22:17:08 INFO - manifests [ ] 65/20426 20h42m 22:17:08 INFO - manifests [ ] 66/20426 20h28m 22:17:08 INFO - manifests [ ] 67/20426 20h15m 22:17:08 INFO - manifests [ ] 68/20426 20h03m 22:17:08 INFO - manifests [ ] 69/20426 19h51m 22:17:08 INFO - manifests [ ] 70/20426 19h39m 22:17:08 INFO - manifests [ ] 71/20426 19h28m 22:17:08 INFO - manifests [ ] 72/20426 19h17m 22:17:08 INFO - manifests [ ] 73/20426 19h06m 22:17:08 INFO - manifests [ ] 74/20426 18h55m 22:17:08 INFO - manifests [ ] 75/20426 18h45m 22:17:08 INFO - manifests [ ] 76/20426 18h35m 22:17:08 INFO - manifests [ ] 77/20426 18h26m 22:17:08 INFO - manifests [ ] 78/20426 18h16m 22:17:08 INFO - manifests [ ] 79/20426 18h07m 22:17:08 INFO - manifests [ ] 80/20426 17h58m 22:17:08 INFO - manifests [ ] 81/20426 17h50m 22:17:08 INFO - manifests [ ] 82/20426 17h41m 22:17:08 INFO - manifests [ ] 83/20426 17h33m 22:17:08 INFO - manifests [ ] 84/20426 17h25m 22:17:08 INFO - manifests [ ] 85/20426 17h18m 22:17:08 INFO - manifests [ ] 86/20426 17h10m 22:17:08 INFO - manifests [ ] 87/20426 17h03m 22:17:08 INFO - manifests [ ] 88/20426 16h56m 22:17:08 INFO - manifests [ ] 89/20426 16h49m 22:17:08 INFO - manifests [ ] 90/20426 16h42m 22:17:08 INFO - manifests [ ] 91/20426 16h36m 22:17:08 INFO - manifests [ ] 92/20426 16h29m 22:17:08 INFO - manifests [ ] 93/20426 16h23m 22:17:08 INFO - manifests [ ] 94/20426 16h17m 22:17:08 INFO - manifests [ ] 95/20426 16h11m 22:17:08 INFO - manifests [ ] 96/20426 16h05m 22:17:08 INFO - manifests [ ] 97/20426 15h59m 22:17:08 INFO - manifests [ ] 98/20426 15h54m 22:17:08 INFO - manifests [ ] 99/20426 15h49m 22:17:08 INFO - manifests [ ] 100/20426 15h43m 22:17:08 INFO - manifests [ ] 107/20426 14h45m 22:17:08 INFO - manifests [==> ] 1572/20426 55m35s 22:17:08 INFO - manifests [=================> ] 8007/20426 7m13s 22:17:08 INFO - manifests [=======================> ] 10240/20426 4m39sensuring https://hg.mozilla.org/projects/ash@03438d67a48a8590220d787672054e81f77dc99c is available at c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 22:17:08 INFO - (existing repository shared store: C:\builds\hg-shared\8ba995b74e18334ab3707f27e9eb8f4e37ba3d29\.hg) 22:17:08 INFO - (pulling to obtain 03438d67a48a8590220d787672054e81f77dc99c) 22:17:08 INFO - searching for changes 22:17:08 INFO - adding changesets 22:17:08 INFO - adding manifests 22:17:08 INFO - 22:17:08 INFO - 22:17:08 INFO - transaction abort! 22:17:08 INFO - rollback completed 22:17:08 INFO - Traceback (most recent call last): 22:17:08 INFO - File "mercurial\dispatch.pyc", line 204, in _runcatch 22:17:08 INFO - File "mercurial\dispatch.pyc", line 880, in _dispatch 22:17:08 INFO - File "mercurial\dispatch.pyc", line 637, in runcommand 22:17:08 INFO - File "mercurial\extensions.pyc", line 210, in closure 22:17:08 INFO - File "hgext\color.pyc", line 503, in colorcmd 22:17:08 INFO - File "mercurial\dispatch.pyc", line 1010, in _runcommand 22:17:08 INFO - File "mercurial\dispatch.pyc", line 971, in checkargs 22:17:08 INFO - File "mercurial\dispatch.pyc", line 877, in 22:17:08 INFO - File "mercurial\util.pyc", line 1036, in check 22:17:08 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 163, in robustcheckout 22:17:08 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 289, in _docheckout 22:17:08 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 172, in callself 22:17:08 INFO - File "c:/builds/moz2_slave/ash-w64-pgo-000000000000000000/scripts/external_tools/robustcheckout.py", line 343, in _docheckout 22:17:08 INFO - File "mercurial\exchange.pyc", line 1186, in pull 22:17:08 INFO - File "mercurial\exchange.pyc", line 1329, in _pullbundle2 22:17:08 INFO - Abort: missing support for negative part header size: -854786048 22:17:08 INFO - abort: missing support for negative part header size: -854786048 22:17:08 ERROR - Return code: 255 22:17:08 INFO - rmtree: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\src 22:17:08 INFO - Using _rmtree_windows ... 22:17:08 INFO - retry: Calling _rmtree_windows with args: ('c:\\builds\\moz2_slave\\ash-w64-pgo-000000000000000000\\build\\src',), kwargs: {}, attempt #1 22:17:08 INFO - retry: attempt #5 caught exception: repo checkout failed! 22:17:08 FATAL - Automation Error: Can't checkout https://hg.mozilla.org/projects/ash! 22:17:08 FATAL - Caught exception: repo checkout failed! 22:17:08 FATAL - Caught exception: repo checkout failed! 22:17:08 FATAL - Caught exception: repo checkout failed! 22:17:08 FATAL - Caught exception: repo checkout failed! 22:17:08 FATAL - Caught exception: repo checkout failed! 22:17:08 FATAL - Running post_fatal callback... 22:17:08 ERROR - setting return code to 2 because fatal was called 22:17:08 WARNING - setting return code to 2 22:17:08 FATAL - Exiting -1 22:17:08 INFO - [mozharness: 2017-04-04 05:17:08.979000Z] Finished checkout-sources step (failed) 22:17:08 INFO - Running post-run listener: _summarize 22:17:08 ERROR - # TBPL FAILURE # 22:17:08 INFO - [mozharness: 2017-04-04 05:17:08.980000Z] FxDesktopBuild summary: 22:17:08 ERROR - # TBPL FAILURE # 22:17:08 INFO - Running post-run listener: copy_logs_to_upload_dir 22:17:08 INFO - Copying logs to upload dir... 22:17:08 INFO - mkdir: c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\build\upload\logs program finished with exit code -1 elapsedTime=2811.701000 ========= master_lag: 0.06 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 46 mins, 51 secs) (at 2017-04-03 22:17:11.919208) ========= ========= Started set props: buildid stage_platform builduid (results: 0, elapsed: 0 secs) (at 2017-04-03 22:17:11.922964) ========= 'bash' '-c' 'for file in `ls -1`; do cat $file; done' in dir c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\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-109 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-109 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 62 Stepping 4, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3e04 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=3224 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/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-109 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:20170403213001 builduid:da2ce914d9c74097857d04e3439df6ce stage_platform:win64-pgo program finished with exit code 0 elapsedTime=0.113000 buildid: '20170403213001' stage_platform: 'win64-pgo' builduid: 'da2ce914d9c74097857d04e3439df6ce' ========= master_lag: 0.03 ========= ========= Finished set props: buildid stage_platform builduid (results: 0, elapsed: 0 secs) (at 2017-04-03 22:17:12.063637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 22:17:12.064588) ========= 'rm' '-f' 'oauth.txt' in dir c:\builds\moz2_slave\ash-w64-pgo-000000000000000000\. (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-109 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-109 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 62 Stepping 4, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=3e04 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=3224 SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-pbUGoB4056/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-109 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.107000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 22:17:12.178930) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2017-04-03 22:17:12.179792) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2017-04-03 22:17:12.436501) ========= ========= Total master_lag: 0.22 =========