builder: mozilla-inbound-win32-debug
slave: b-2008-spot-101
starttime: 1485478036.25
results: failure (2)
buildid: 20170126163321
builduid: c4a4615f3333429999b0cdbd58a3ce98
revision: d9a53886e7942d3c13760dadce618cb4e3595e66

========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:16.250304) =========
master: http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:16.250754) =========

========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:16.251037) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:16.328775) =========

========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-26 16:47:16.329075) =========
'bash' '-c' 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'
 in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv
  BUILDBOT_PATH=C:\mozilla-build\buildbotve
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
  COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
  COMMONPROGRAMW6432=C:\Program Files\Common Files
  COMPUTERNAME=B-2008-SPOT-101
  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-101
  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=3288
  SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016
  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-101
  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-01-26 16:47:14--  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% 40.8K=0.3s

2017-01-26 16:47:15 (40.8 KB/s) - 'archiver_client.py' saved [12179/12179]

program finished with exit code 0
elapsedTime=1.851000
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-26 16:47:18.240879) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2017-01-26 16:47:18.241830) =========
'rm' '-rf' 'scripts' 'properties'
 in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-rf', 'scripts', 'properties']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv
  BUILDBOT_PATH=C:\mozilla-build\buildbotve
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
  COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
  COMMONPROGRAMW6432=C:\Program Files\Common Files
  COMPUTERNAME=B-2008-SPOT-101
  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-101
  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=3288
  SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016
  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-101
  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=3.110000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2017-01-26 16:47:21.359865) =========

========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-26 16:47:21.360830) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d9a53886e7942d3c13760dadce618cb4e3595e66 --destination scripts --debug'
 in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d9a53886e7942d3c13760dadce618cb4e3595e66 --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-101
  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-101
  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=3288
  SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016
  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-101
  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-01-26 16:47:19,240 truncating revision to first 12 chars
2017-01-26 16:47:19,240 Setting DEBUG logging.
2017-01-26 16:47:19,240 attempt 1/10
2017-01-26 16:47:19,240 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/d9a53886e794?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-01-26 16:47:21,112 unpacking tar archive at: mozilla-inbound-d9a53886e794/testing/mozharness/
2017-01-26 16:47:21,256 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=2.568000
========= master_lag: 0.01 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-26 16:47:23.941723) =========

========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:23.942675) =========
script_repo_revision: d9a53886e7942d3c13760dadce618cb4e3595e66
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:23.943088) =========

========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:23.943365) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:23.951889) =========

========= Started remove old nonce (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:23.952147) =========
'rm' '-f' 'nonce'
 in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-f', 'nonce']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv
  BUILDBOT_PATH=C:\mozilla-build\buildbotve
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
  COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
  COMMONPROGRAMW6432=C:\Program Files\Common Files
  COMPUTERNAME=B-2008-SPOT-101
  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-101
  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=3288
  SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016
  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-101
  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 remove old nonce (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:24.066680) =========

========= Started downloading to token (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:24.067521) =========
Slave: b-2008-spot-101
IP: 10.132.53.104
Duration: 25200
URI: https://mac-v2-signing6.srv.releng.scl3.mozilla.com:9110/token
========= Finished downloading to token (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:24.308771) =========

========= Started set props: toolsdir (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:24.309182) =========
'bash' '-c' 'pwd'
 in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\scripts (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'pwd']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv
  BUILDBOT_PATH=C:\mozilla-build\buildbotve
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
  COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
  COMMONPROGRAMW6432=C:\Program Files\Common Files
  COMPUTERNAME=B-2008-SPOT-101
  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-101
  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=3288
  SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016
  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-101
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\
  VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\
  VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\
  WINDIR=C:\Windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
  WIX_351728_PATH=c:/mozilla-build/wix-351728
  _=C:\mozilla-build\buildbotve\Scripts\python
 using PTY: False
/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts
program finished with exit code 0
elapsedTime=0.107000
toolsdir: '/c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts'
========= master_lag: 0.01 =========
========= Finished set props: toolsdir (results: 0, elapsed: 0 secs) (at 2017-01-26 16:47:24.424852) =========

========= Started 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 43 mins, 34 secs) (at 2017-01-26 16:47:24.425774) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/fx_desktop_build.py' '--config' 'builds/releng_base_windows_32_builds.py' '--custom-build-variant-cfg' 'debug' '--config' 'balrog/production.py' '--branch' 'mozilla-inbound' '--build-pool' 'production'
 in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (timeout 10800 secs) (maxTime 27000 secs)
 watching logfiles {}
 argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/fx_desktop_build.py', '--config', 'builds/releng_base_windows_32_builds.py', '--custom-build-variant-cfg', 'debug', '--config', 'balrog/production.py', '--branch', 'mozilla-inbound', '--build-pool', 'production']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv
  BINSCOPE=C:\Program Files (x86)\Microsoft\SDL BinScope\BinScope.exe
  BUILDBOT_PATH=C:\mozilla-build\buildbotve
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
  COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
  COMMONPROGRAMW6432=C:\Program Files\Common Files
  COMPUTERNAME=B-2008-SPOT-101
  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-101
  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,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110
  MOZ_SIGN_CMD=python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode: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 dmgv2: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
  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/m-in-w32-d-0000000000000000000/buildprops.json
  PS1=\[\033]0;$MSYSTEM:\w\007
\033[32m\]\u@\h \[\033[33m\w\033[0m\]
$ 
  PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\;C:\Program Files (x86)\AWS Tools\PowerShell\
  PUBLIC=C:\Users\Public
  PWD=c:/Users/cltbld
  RUNNER_CONFIG_CMD=C:\opt\runner\Scripts\python2.7.exe C:\opt\runner\Scripts\runner-script.py -c c:\opt\runner\runner.cfg
  SHELL=C:/mozilla-build/msys/bin/sh
  SHLVL=1
  SSH_AGENT_PID=3288
  SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016
  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-101
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  VS100COMNTOOLS=c:\Program Files (x86)\Microsoft Visual Studio 10.0\Common7\Tools\
  VS110COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 11.0\Common7\Tools\
  VS120COMNTOOLS=C:\Program Files (x86)\Microsoft Visual Studio 12.0\Common7\Tools\
  WINDIR=C:\Windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
  WIX_351728_PATH=c:/mozilla-build/wix-351728
  XPCOM_DEBUG_BREAK=stack-and-abort
  _=C:\mozilla-build\buildbotve\Scripts\python
 using PTY: False
16:47:22     INFO - MultiFileLogger online at 20170126 16:47:22 in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000
16:47:22     INFO - Script was run with '--custom-build-variant-cfg debug' and 'debug' matches a key in 'builds/releng_sub_windows_configs/32_debug.py'. Updating self.config with items from that key's value.
16:47:22     INFO - Script was run with '--branch mozilla-inbound' and 'mozilla-inbound' matches a key in 'builds/branch_specifics.py'. Updating self.config with items from that key's value.
16:47:22     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.
16:47:22     INFO - To generate a config file based upon options passed and config files used, run script as before but extend options with "--dump-config"
16:47:22     INFO - For a diff of where self.config got its items, run the script again as before but extend options with: "--dump-config-hierarchy"
16:47:22     INFO - Both --dump-config and --dump-config-hierarchy don't actually run any actions.
16:47:22     INFO - We are running this in buildbot, grab the build props
16:47:22     INFO - Using buildbot properties:
16:47:22     INFO - {
16:47:22     INFO -     "project": "", 
16:47:22     INFO -     "product": "firefox", 
16:47:22     INFO -     "script_repo_revision": "production", 
16:47:22     INFO -     "scheduler": "mozilla-inbound-firefox", 
16:47:22     INFO -     "repository": "", 
16:47:22     INFO -     "buildername": "WINNT 5.2 mozilla-inbound leak test build", 
16:47:22     INFO -     "buildid": "20170126163321", 
16:47:22     INFO -     "basedir": "/c/builds/moz2_slave/m-in-w32-d-0000000000000000000", 
16:47:22     INFO -     "buildnumber": 3150, 
16:47:22     INFO -     "platform": "win32-debug", 
16:47:22     INFO -     "master": "http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/", 
16:47:22     INFO -     "branch": "mozilla-inbound", 
16:47:22     INFO -     "revision": "d9a53886e7942d3c13760dadce618cb4e3595e66", 
16:47:22     INFO -     "slavename": "b-2008-spot-101", 
16:47:22     INFO -     "builduid": "c4a4615f3333429999b0cdbd58a3ce98", 
16:47:22     INFO -     "repo_path": "integration/mozilla-inbound"
16:47:22     INFO - }
16:47:22     INFO - Setting buildbot property stage_platform to win32-debug
16:47:22     INFO - mkdir: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties
16:47:22     INFO - Writing buildbot properties ['stage_platform'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\stage_platform
16:47:22     INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\stage_platform
16:47:22     INFO - Contents:
16:47:22     INFO -  stage_platform:win32-debug
16:47:22  WARNING - Try message not found.
16:47:22     INFO - Run as scripts/scripts/fx_desktop_build.py --config builds/releng_base_windows_32_builds.py --custom-build-variant-cfg debug --config balrog/production.py --branch mozilla-inbound --build-pool production
16:47:22     INFO - Determining buildid from buildbot properties
16:47:22     INFO - Setting buildbot property buildid to 20170126163321
16:47:22     INFO - Writing buildbot properties ['buildid'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\buildid
16:47:22     INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\buildid
16:47:22     INFO - Contents:
16:47:22     INFO -  buildid:20170126163321
16:47:22     INFO - Determining builduid from buildbot properties
16:47:22     INFO - Setting buildbot property builduid to c4a4615f3333429999b0cdbd58a3ce98
16:47:22     INFO - Writing buildbot properties ['builduid'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\builduid
16:47:22     INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\builduid
16:47:22     INFO - Contents:
16:47:22     INFO -  builduid:c4a4615f3333429999b0cdbd58a3ce98
16:47:22     INFO - Creating virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv
16:47:22     INFO - Running command: ['c:\\mozilla-build\\python27\\python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build
16:47:22     INFO - Copy/paste: c:\mozilla-build\python27\python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv
16:47:23     INFO -  The --no-site-packages flag is deprecated; it is now the default behavior.
16:47:23     INFO -  New python executable in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\python.exe
16:47:26     INFO -  Installing distribute................................................................................................................................................................................................done.
16:47:27     INFO -  Installing pip.................done.
16:47:27     INFO -  Overwriting c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\activate.ps1 with new content
16:47:27     INFO -  c:/mozilla-build/buildbotve/virtualenv.py:446: UnicodeWarning: Unicode unequal comparison failed to convert both arguments to Unicode - interpreting them as being unequal
16:47:27     INFO -    if c != content:
16:47:27     INFO - Return code: 0
16:47:27     INFO - Getting output from command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', '--version']
16:47:27     INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip --version
16:47:33     INFO - Reading from file tmpfile_stdout
16:47:33     INFO - Output received:
16:47:33     INFO -  pip 9.0.1 from c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\lib\site-packages\pip-9.0.1-py2.7.egg (python 2.7)
16:47:33     INFO - Using _rmtree_windows ...
16:47:33     INFO - Using _rmtree_windows ...
16:47:33     INFO - Installing requests==2.8.1 into virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv
16:47:33     INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'requests==2.8.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02435BA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x026F41B0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02372460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0288DA68>, '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 0x027C78C8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027339B0>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build', 'env': {'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 'SYSTEMROOT': 'C:\\Windows', 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 'SHELL': 'C:/mozilla-build/msys/bin/sh', 'IDLEIZER_HALT_ON_IDLE': 'true', 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 'WINDOWS_TRACING_FLAGS': '3', 'TOOLTOOL_HOME': '/c/builds', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 'PROCESSOR_LEVEL': '6', 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'MACHTYPE': 'i686-pc-msys', 'OS': 'Windows_NT', 'MOZ_AUTOMATION': '1', 'CVS_RSH': 'ssh', 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 'MOZ_OBJDIR': 'obj-firefox', 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 'SHLVL': '1', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 'EDITOR': 'emacs.exe', 'HOMEPATH': '\\', 'MOZBUILDDIR': 'C:\\mozilla-build', 'MOZILLABUILD': 'C:\\mozilla-build', 'LOGONSERVER': '\\\\B-2008-SPOT-101', 'OS_VERSION': '2008', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'COMPUTERNAME': 'B-2008-SPOT-101', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-101', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 'HOME': 'c:/Users/cltbld', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com: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,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 'PROCESSOR_ARCHITECTURE': 'x86', 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'OSTYPE': 'msys', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'PROMPT': '$P$G', 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'WINDIR': 'C:\\Windows', 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild', 'NUMBER_OF_PROCESSORS': '16', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'PUBLIC': 'C:\\Users\\Public', 'USERPROFILE': 'C:\\Users\\cltbld', 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 'LOG': '"c:\\runner\\buildbot-startup.log"', 'HOSTTYPE': 'i686', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel', 'LOGNAME': 'cltbld', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '3e04', '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', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'SSH_AGENT_PID': '3288', 'MAKE_MODE': 'unix', 'TERM': 'cygwin', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 'MSYSTEM': 'MINGW32', 'PROGRAMDATA': 'C:\\ProgramData', 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode: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 dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'FP_NO_HOST_CHECK': 'NO', 'OLDPWD': 'c:/Users/cltbld', 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 'PWD': 'c:/Users/cltbld', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files'}}, attempt #1
16:47:33     INFO - Running command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'requests==2.8.1'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build
16:47:33     INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip install --timeout 120 requests==2.8.1
16:47:33     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
16:47:33     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
16:47:33     INFO -  'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv',
16:47:33     INFO -  'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe',
16:47:33     INFO -  'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve',
16:47:33     INFO -  'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey',
16:47:33     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
16:47:33     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
16:47:33     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
16:47:33     INFO -  'COMPUTERNAME': 'B-2008-SPOT-101',
16:47:33     INFO -  'COMSPEC': 'C:\\Windows\\system32\\cmd.exe',
16:47:33     INFO -  'CVS_RSH': 'ssh',
16:47:33     INFO -  'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\',
16:47:33     INFO -  'EDITOR': 'emacs.exe',
16:47:33     INFO -  'FP_NO_HOST_CHECK': 'NO',
16:47:33     INFO -  'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared',
16:47:33     INFO -  'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared',
16:47:33     INFO -  'HOME': 'c:/Users/cltbld',
16:47:33     INFO -  'HOMEDRIVE': 'C:',
16:47:33     INFO -  'HOMEPATH': '\\',
16:47:33     INFO -  'HOSTTYPE': 'i686',
16:47:33     INFO -  'IDLEIZER_HALT_ON_IDLE': 'true',
16:47:33     INFO -  'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc',
16:47:33     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
16:47:33     INFO -  'LOG': '"c:\\runner\\buildbot-startup.log"',
16:47:33     INFO -  'LOGNAME': 'cltbld',
16:47:33     INFO -  'LOGONSERVER': '\\\\B-2008-SPOT-101',
16:47:33     INFO -  'MACHTYPE': 'i686-pc-msys',
16:47:33     INFO -  'MAKE_MODE': 'unix',
16:47:33     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build',
16:47:33     INFO -  'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild',
16:47:33     INFO -  'MOZILLABUILD': 'C:\\mozilla-build',
16:47:33     INFO -  'MOZ_AUTOMATION': '1',
16:47:33     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:47:33     INFO -  'MOZ_OBJDIR': 'obj-firefox',
16:47:33     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,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110',
16:47:33     INFO -  'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode: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 dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110',
16:47:33     INFO -  'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64',
16:47:33     INFO -  'MSYSTEM': 'MINGW32',
16:47:33     INFO -  'NUMBER_OF_PROCESSORS': '16',
16:47:33     INFO -  'OLDPWD': 'c:/Users/cltbld',
16:47:33     INFO -  'OS': 'Windows_NT',
16:47:33     INFO -  'OSTYPE': 'msys',
16:47:33     INFO -  'OS_VERSION': '2008',
16:47:33     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',
16:47:33     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
16:47:33     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
16:47:33     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
16:47:33     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel',
16:47:33     INFO -  'PROCESSOR_LEVEL': '6',
16:47:33     INFO -  'PROCESSOR_REVISION': '3e04',
16:47:33     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
16:47:33     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
16:47:33     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
16:47:33     INFO -  'PROGRAMW6432': 'C:\\Program Files',
16:47:33     INFO -  'PROMPT': '$P$G',
16:47:33     INFO -  'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json',
16:47:33     INFO -  'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ',
16:47:33     INFO -  'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\',
16:47:33     INFO -  'PUBLIC': 'C:\\Users\\Public',
16:47:33     INFO -  'PWD': 'c:/Users/cltbld',
16:47:33     INFO -  'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg',
16:47:33     INFO -  'SHELL': 'C:/mozilla-build/msys/bin/sh',
16:47:33     INFO -  'SHLVL': '1',
16:47:33     INFO -  'SSH_AGENT_PID': '3288',
16:47:33     INFO -  'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016',
16:47:33     INFO -  'SYSTEMDRIVE': 'C:',
16:47:33     INFO -  'SYSTEMROOT': 'C:\\Windows',
16:47:33     INFO -  'TEMP': 'C:/Users/cltbld/AppData/Local/Temp',
16:47:33     INFO -  'TERM': 'cygwin',
16:47:33     INFO -  'TINDERBOX_OUTPUT': '1',
16:47:33     INFO -  'TMP': 'C:/Users/cltbld/AppData/Local/Temp',
16:47:33     INFO -  'TOOLTOOL_CACHE': '/c/builds/tooltool_cache',
16:47:33     INFO -  'TOOLTOOL_HOME': '/c/builds',
16:47:33     INFO -  'USERDOMAIN': 'B-2008-SPOT-101',
16:47:33     INFO -  'USERNAME': 'cltbld',
16:47:33     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
16:47:33     INFO -  'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\',
16:47:33     INFO -  'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\',
16:47:33     INFO -  'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\',
16:47:33     INFO -  'WINDIR': 'C:\\Windows',
16:47:33     INFO -  'WINDOWS_TRACING_FLAGS': '3',
16:47:33     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
16:47:33     INFO -  'WIX_351728_PATH': 'c:/mozilla-build/wix-351728',
16:47:33     INFO -  'XPCOM_DEBUG_BREAK': 'stack-and-abort',
16:47:33     INFO -  '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'}
16:47:34     INFO -  Collecting requests==2.8.1
16:47:35     INFO -    Using cached requests-2.8.1-py2.py3-none-any.whl
16:47:35     INFO -  Installing collected packages: requests
16:47:35     INFO -    Found existing installation: requests 2.7.0
16:47:35     INFO -      Uninstalling requests-2.7.0:
16:47:37     INFO -        Successfully uninstalled requests-2.7.0
16:47:38     INFO -  Successfully installed requests-2.8.1
16:47:38     INFO -  c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\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.
16:47:38     INFO -    SNIMissingWarning
16:47:38     INFO -  c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\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.
16:47:38     INFO -    InsecurePlatformWarning
16:47:38     INFO - Return code: 0
16:47:38     INFO - Installing PyHawk-with-a-single-extra-commit==0.1.5 into virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv
16:47:38     INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'PyHawk-with-a-single-extra-commit==0.1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02435BA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x026F41B0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02372460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0288DA68>, '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 0x027C78C8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027339B0>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build', 'env': {'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 'SYSTEMROOT': 'C:\\Windows', 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 'SHELL': 'C:/mozilla-build/msys/bin/sh', 'IDLEIZER_HALT_ON_IDLE': 'true', 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 'WINDOWS_TRACING_FLAGS': '3', 'TOOLTOOL_HOME': '/c/builds', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 'PROCESSOR_LEVEL': '6', 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'MACHTYPE': 'i686-pc-msys', 'OS': 'Windows_NT', 'MOZ_AUTOMATION': '1', 'CVS_RSH': 'ssh', 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 'MOZ_OBJDIR': 'obj-firefox', 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 'SHLVL': '1', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 'EDITOR': 'emacs.exe', 'HOMEPATH': '\\', 'MOZBUILDDIR': 'C:\\mozilla-build', 'MOZILLABUILD': 'C:\\mozilla-build', 'LOGONSERVER': '\\\\B-2008-SPOT-101', 'OS_VERSION': '2008', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'COMPUTERNAME': 'B-2008-SPOT-101', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-101', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 'HOME': 'c:/Users/cltbld', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com: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,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 'PROCESSOR_ARCHITECTURE': 'x86', 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'OSTYPE': 'msys', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'PROMPT': '$P$G', 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'WINDIR': 'C:\\Windows', 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild', 'NUMBER_OF_PROCESSORS': '16', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'PUBLIC': 'C:\\Users\\Public', 'USERPROFILE': 'C:\\Users\\cltbld', 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 'LOG': '"c:\\runner\\buildbot-startup.log"', 'HOSTTYPE': 'i686', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel', 'LOGNAME': 'cltbld', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '3e04', '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', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'SSH_AGENT_PID': '3288', 'MAKE_MODE': 'unix', 'TERM': 'cygwin', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 'MSYSTEM': 'MINGW32', 'PROGRAMDATA': 'C:\\ProgramData', 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode: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 dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'FP_NO_HOST_CHECK': 'NO', 'OLDPWD': 'c:/Users/cltbld', 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 'PWD': 'c:/Users/cltbld', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files'}}, attempt #1
16:47:38     INFO - Running command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'PyHawk-with-a-single-extra-commit==0.1.5'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build
16:47:38     INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip install --timeout 120 PyHawk-with-a-single-extra-commit==0.1.5
16:47:38     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
16:47:38     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
16:47:38     INFO -  'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv',
16:47:38     INFO -  'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe',
16:47:38     INFO -  'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve',
16:47:38     INFO -  'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey',
16:47:38     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
16:47:38     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
16:47:38     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
16:47:38     INFO -  'COMPUTERNAME': 'B-2008-SPOT-101',
16:47:38     INFO -  'COMSPEC': 'C:\\Windows\\system32\\cmd.exe',
16:47:38     INFO -  'CVS_RSH': 'ssh',
16:47:38     INFO -  'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\',
16:47:38     INFO -  'EDITOR': 'emacs.exe',
16:47:38     INFO -  'FP_NO_HOST_CHECK': 'NO',
16:47:38     INFO -  'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared',
16:47:38     INFO -  'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared',
16:47:38     INFO -  'HOME': 'c:/Users/cltbld',
16:47:38     INFO -  'HOMEDRIVE': 'C:',
16:47:38     INFO -  'HOMEPATH': '\\',
16:47:38     INFO -  'HOSTTYPE': 'i686',
16:47:38     INFO -  'IDLEIZER_HALT_ON_IDLE': 'true',
16:47:38     INFO -  'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc',
16:47:38     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
16:47:38     INFO -  'LOG': '"c:\\runner\\buildbot-startup.log"',
16:47:38     INFO -  'LOGNAME': 'cltbld',
16:47:38     INFO -  'LOGONSERVER': '\\\\B-2008-SPOT-101',
16:47:38     INFO -  'MACHTYPE': 'i686-pc-msys',
16:47:38     INFO -  'MAKE_MODE': 'unix',
16:47:38     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build',
16:47:38     INFO -  'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild',
16:47:38     INFO -  'MOZILLABUILD': 'C:\\mozilla-build',
16:47:38     INFO -  'MOZ_AUTOMATION': '1',
16:47:38     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:47:38     INFO -  'MOZ_OBJDIR': 'obj-firefox',
16:47:38     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,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110',
16:47:38     INFO -  'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode: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 dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110',
16:47:38     INFO -  'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64',
16:47:38     INFO -  'MSYSTEM': 'MINGW32',
16:47:38     INFO -  'NUMBER_OF_PROCESSORS': '16',
16:47:38     INFO -  'OLDPWD': 'c:/Users/cltbld',
16:47:38     INFO -  'OS': 'Windows_NT',
16:47:38     INFO -  'OSTYPE': 'msys',
16:47:38     INFO -  'OS_VERSION': '2008',
16:47:38     INFO -  'PATH': 'C:/mozilla-build/nsis-3.0b1;C:/mozilla-build/python27;C:/mozilla-build/buildbotve/scripts;C:\\mozilla-build\\nsis-3.0b1;C:\\mozilla-build\\nsis-2.46u;C:\\mozilla-build\\python27;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\msys\\local\\bin;c:\\mozilla-build\\wget;c:\\mozilla-build\\7zip;c:\\mozilla-build\\blat261\\full;c:\\mozilla-build\\python;c:\\mozilla-build\\svn-win32-1.6.3\\bin;c:\\mozilla-build\\upx203w;c:\\mozilla-build\\emacs-24.3\\bin;c:\\mozilla-build\\info-zip;c:\\mozilla-build\\nsis-2.46u;c:\\mozilla-build\\nsis-3.0a2;c:\\mozilla-build\\wix-351728;c:\\mozilla-build\\hg;c:\\mozilla-build\\python\\Scripts;c:\\mozilla-build\\kdiff3;c:\\mozilla-build\\yasm;c:\\mozilla-build\\mozmake;.;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\msys\\mingw\\bin;C:\\mozilla-build\\msys\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\facter\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\hiera\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\ruby\\bin;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\sys\\tools\\bin;c:\\Windows\\system32;c:\\Windows;c:\\Windows\\System32\\Wbem;c:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;c:\\Program Files\\Amazon\\cfn-bootstrap\\;c:\\Program Files (x86)\\Windows Kits\\8.0\\Windows Performance Toolkit\\;c:\\Program Files (x86)\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\Tools\\Binn\\;c:\\Program Files\\Microsoft SQL Server\\100\\DTS\\Binn\\;c:\\Program Files (x86)\\Windows Kits\\8.1\\Windows Performance Toolkit\\;c:\\Program Files\\Microsoft SQL Server\\110\\Tools\\Binn\\;c:\\Program Files (x86)\\Microsoft SDKs\\TypeScript\\1.0\\;c:\\Program Files (x86)\\Puppet Labs\\Puppet\\bin;C:\\mozilla-build\\msys\\bin;c:\\mozilla-build\\python27\\scripts;c:\\mozilla-build\\wget;c:\\mozilla-build\\info-zip;c:\\mozilla-build;c:\\mozilla-build\\vim\\vim72;c:\\mozilla-build\\python27;c:\\mozilla-build\\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',
16:47:38     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
16:47:38     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
16:47:38     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
16:47:38     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel',
16:47:38     INFO -  'PROCESSOR_LEVEL': '6',
16:47:38     INFO -  'PROCESSOR_REVISION': '3e04',
16:47:38     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
16:47:38     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
16:47:38     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
16:47:38     INFO -  'PROGRAMW6432': 'C:\\Program Files',
16:47:38     INFO -  'PROMPT': '$P$G',
16:47:38     INFO -  'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json',
16:47:38     INFO -  'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ',
16:47:38     INFO -  'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\',
16:47:38     INFO -  'PUBLIC': 'C:\\Users\\Public',
16:47:38     INFO -  'PWD': 'c:/Users/cltbld',
16:47:38     INFO -  'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg',
16:47:38     INFO -  'SHELL': 'C:/mozilla-build/msys/bin/sh',
16:47:38     INFO -  'SHLVL': '1',
16:47:38     INFO -  'SSH_AGENT_PID': '3288',
16:47:38     INFO -  'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016',
16:47:38     INFO -  'SYSTEMDRIVE': 'C:',
16:47:38     INFO -  'SYSTEMROOT': 'C:\\Windows',
16:47:38     INFO -  'TEMP': 'C:/Users/cltbld/AppData/Local/Temp',
16:47:38     INFO -  'TERM': 'cygwin',
16:47:38     INFO -  'TINDERBOX_OUTPUT': '1',
16:47:38     INFO -  'TMP': 'C:/Users/cltbld/AppData/Local/Temp',
16:47:38     INFO -  'TOOLTOOL_CACHE': '/c/builds/tooltool_cache',
16:47:38     INFO -  'TOOLTOOL_HOME': '/c/builds',
16:47:38     INFO -  'USERDOMAIN': 'B-2008-SPOT-101',
16:47:38     INFO -  'USERNAME': 'cltbld',
16:47:38     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
16:47:38     INFO -  'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\',
16:47:38     INFO -  'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\',
16:47:38     INFO -  'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\',
16:47:38     INFO -  'WINDIR': 'C:\\Windows',
16:47:38     INFO -  'WINDOWS_TRACING_FLAGS': '3',
16:47:38     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
16:47:38     INFO -  'WIX_351728_PATH': 'c:/mozilla-build/wix-351728',
16:47:38     INFO -  'XPCOM_DEBUG_BREAK': 'stack-and-abort',
16:47:38     INFO -  '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'}
16:47:39     INFO -  Requirement already satisfied: PyHawk-with-a-single-extra-commit==0.1.5 in .\venv\lib\site-packages
16:47:39     INFO - Return code: 0
16:47:39     INFO - Installing taskcluster==0.0.26 into virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv
16:47:39     INFO - retry: Calling run_command with args: [['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'taskcluster==0.0.26']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02435BA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x026F41B0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02372460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0288DA68>, '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 0x027C78C8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x027339B0>, 'level': 'critical'}], 'cwd': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build', 'env': {'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\', 'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe', 'SYSTEMROOT': 'C:\\Windows', 'XPCOM_DEBUG_BREAK': 'stack-and-abort', 'SHELL': 'C:/mozilla-build/msys/bin/sh', 'IDLEIZER_HALT_ON_IDLE': 'true', 'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared', 'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg', 'WINDOWS_TRACING_FLAGS': '3', 'TOOLTOOL_HOME': '/c/builds', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'COMSPEC': 'C:\\Windows\\system32\\cmd.exe', 'PROCESSOR_LEVEL': '6', 'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'MACHTYPE': 'i686-pc-msys', 'OS': 'Windows_NT', 'MOZ_AUTOMATION': '1', 'CVS_RSH': 'ssh', 'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc', 'MOZ_OBJDIR': 'obj-firefox', 'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ', 'TEMP': 'C:/Users/cltbld/AppData/Local/Temp', 'SHLVL': '1', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv', 'EDITOR': 'emacs.exe', 'HOMEPATH': '\\', 'MOZBUILDDIR': 'C:\\mozilla-build', 'MOZILLABUILD': 'C:\\mozilla-build', 'LOGONSERVER': '\\\\B-2008-SPOT-101', 'OS_VERSION': '2008', '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'TMP': 'C:/Users/cltbld/AppData/Local/Temp', 'COMPUTERNAME': 'B-2008-SPOT-101', 'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\', 'USERDOMAIN': 'B-2008-SPOT-101', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'TOOLTOOL_CACHE': '/c/builds/tooltool_cache', 'HOME': 'c:/Users/cltbld', 'MOZ_SIGNING_SERVERS': 'gpg:sha2signcode:sha2signcodestub:osslsigncode:signcode:mar:mar_sha384:jar:emevoucher:signing4.srv.releng.scl3.mozilla.com: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,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared', 'PROCESSOR_ARCHITECTURE': 'x86', 'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'OSTYPE': 'msys', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'PROMPT': '$P$G', 'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64', 'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'WINDIR': 'C:\\Windows', 'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild', 'NUMBER_OF_PROCESSORS': '16', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'PUBLIC': 'C:\\Users\\Public', 'USERPROFILE': 'C:\\Users\\cltbld', 'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\', 'LOG': '"c:\\runner\\buildbot-startup.log"', 'HOSTTYPE': 'i686', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel', 'LOGNAME': 'cltbld', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '3e04', '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', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'SSH_AGENT_PID': '3288', 'MAKE_MODE': 'unix', 'TERM': 'cygwin', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'WIX_351728_PATH': 'c:/mozilla-build/wix-351728', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\', 'MSYSTEM': 'MINGW32', 'PROGRAMDATA': 'C:\\ProgramData', 'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016', 'TINDERBOX_OUTPUT': '1', 'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode: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 dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110', 'FP_NO_HOST_CHECK': 'NO', 'OLDPWD': 'c:/Users/cltbld', 'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\', 'PWD': 'c:/Users/cltbld', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files'}}, attempt #1
16:47:39     INFO - Running command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', 'taskcluster==0.0.26'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build
16:47:39     INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip install --timeout 120 taskcluster==0.0.26
16:47:39     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
16:47:39     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
16:47:39     INFO -  'APR_ICONV_PATH': 'c:/mozilla-build/svn-win32-1.6.3/iconv',
16:47:39     INFO -  'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe',
16:47:39     INFO -  'BUILDBOT_PATH': 'C:\\mozilla-build\\buildbotve',
16:47:39     INFO -  'CHOCOLATEYINSTALL': 'C:\\ProgramData\\chocolatey',
16:47:39     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
16:47:39     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
16:47:39     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
16:47:39     INFO -  'COMPUTERNAME': 'B-2008-SPOT-101',
16:47:39     INFO -  'COMSPEC': 'C:\\Windows\\system32\\cmd.exe',
16:47:39     INFO -  'CVS_RSH': 'ssh',
16:47:39     INFO -  'DXSDK_DIR': 'C:\\Program Files (x86)\\Microsoft DirectX SDK (June 2010)\\',
16:47:39     INFO -  'EDITOR': 'emacs.exe',
16:47:39     INFO -  'FP_NO_HOST_CHECK': 'NO',
16:47:39     INFO -  'GIT_SHARE_BASE_DIR': 'C:/mozilla-build/msys/builds/git-shared',
16:47:39     INFO -  'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared',
16:47:39     INFO -  'HOME': 'c:/Users/cltbld',
16:47:39     INFO -  'HOMEDRIVE': 'C:',
16:47:39     INFO -  'HOMEPATH': '\\',
16:47:39     INFO -  'HOSTTYPE': 'i686',
16:47:39     INFO -  'IDLEIZER_HALT_ON_IDLE': 'true',
16:47:39     INFO -  'INPUTRC': 'C:/mozilla-build/msys/etc/inputrc',
16:47:39     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
16:47:39     INFO -  'LOG': '"c:\\runner\\buildbot-startup.log"',
16:47:39     INFO -  'LOGNAME': 'cltbld',
16:47:39     INFO -  'LOGONSERVER': '\\\\B-2008-SPOT-101',
16:47:39     INFO -  'MACHTYPE': 'i686-pc-msys',
16:47:39     INFO -  'MAKE_MODE': 'unix',
16:47:39     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build',
16:47:39     INFO -  'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild',
16:47:39     INFO -  'MOZILLABUILD': 'C:\\mozilla-build',
16:47:39     INFO -  'MOZ_AUTOMATION': '1',
16:47:39     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:47:39     INFO -  'MOZ_OBJDIR': 'obj-firefox',
16:47:39     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,dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110',
16:47:39     INFO -  'MOZ_SIGN_CMD': 'python /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/signtool.py --cachedir /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/signing_cache -t /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/token -n /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/nonce -c /c/builds/moz2_slave/m-in-w32-d-0000000000000000000/scripts/release/signing/host.cert -H gpg:sha2signcode: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 dmgv2:mac-v2-signing7.srv.releng.scl3.mozilla.com:9110',
16:47:39     INFO -  'MOZ_TOOLS': 'C:\\mozilla-build\\moztools-x64',
16:47:39     INFO -  'MSYSTEM': 'MINGW32',
16:47:39     INFO -  'NUMBER_OF_PROCESSORS': '16',
16:47:39     INFO -  'OLDPWD': 'c:/Users/cltbld',
16:47:39     INFO -  'OS': 'Windows_NT',
16:47:39     INFO -  'OSTYPE': 'msys',
16:47:39     INFO -  'OS_VERSION': '2008',
16:47:39     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',
16:47:39     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
16:47:39     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
16:47:39     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
16:47:39     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 62 Stepping 4, GenuineIntel',
16:47:39     INFO -  'PROCESSOR_LEVEL': '6',
16:47:39     INFO -  'PROCESSOR_REVISION': '3e04',
16:47:39     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
16:47:39     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
16:47:39     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
16:47:39     INFO -  'PROGRAMW6432': 'C:\\Program Files',
16:47:39     INFO -  'PROMPT': '$P$G',
16:47:39     INFO -  'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json',
16:47:39     INFO -  'PS1': '\\[\\033]0;$MSYSTEM:\\w\\007\n\\033[32m\\]\\u@\\h \\[\\033[33m\\w\\033[0m\\]\n$ ',
16:47:39     INFO -  'PSMODULEPATH': 'C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\;C:\\Program Files (x86)\\AWS Tools\\PowerShell\\',
16:47:39     INFO -  'PUBLIC': 'C:\\Users\\Public',
16:47:39     INFO -  'PWD': 'c:/Users/cltbld',
16:47:39     INFO -  'RUNNER_CONFIG_CMD': 'C:\\opt\\runner\\Scripts\\python2.7.exe C:\\opt\\runner\\Scripts\\runner-script.py -c c:\\opt\\runner\\runner.cfg',
16:47:39     INFO -  'SHELL': 'C:/mozilla-build/msys/bin/sh',
16:47:39     INFO -  'SHLVL': '1',
16:47:39     INFO -  'SSH_AGENT_PID': '3288',
16:47:39     INFO -  'SSH_AUTH_SOCK': 'C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016',
16:47:39     INFO -  'SYSTEMDRIVE': 'C:',
16:47:39     INFO -  'SYSTEMROOT': 'C:\\Windows',
16:47:39     INFO -  'TEMP': 'C:/Users/cltbld/AppData/Local/Temp',
16:47:39     INFO -  'TERM': 'cygwin',
16:47:39     INFO -  'TINDERBOX_OUTPUT': '1',
16:47:39     INFO -  'TMP': 'C:/Users/cltbld/AppData/Local/Temp',
16:47:39     INFO -  'TOOLTOOL_CACHE': '/c/builds/tooltool_cache',
16:47:39     INFO -  'TOOLTOOL_HOME': '/c/builds',
16:47:39     INFO -  'USERDOMAIN': 'B-2008-SPOT-101',
16:47:39     INFO -  'USERNAME': 'cltbld',
16:47:39     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
16:47:39     INFO -  'VS100COMNTOOLS': 'c:\\Program Files (x86)\\Microsoft Visual Studio 10.0\\Common7\\Tools\\',
16:47:39     INFO -  'VS110COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 11.0\\Common7\\Tools\\',
16:47:39     INFO -  'VS120COMNTOOLS': 'C:\\Program Files (x86)\\Microsoft Visual Studio 12.0\\Common7\\Tools\\',
16:47:39     INFO -  'WINDIR': 'C:\\Windows',
16:47:39     INFO -  'WINDOWS_TRACING_FLAGS': '3',
16:47:39     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
16:47:39     INFO -  'WIX_351728_PATH': 'c:/mozilla-build/wix-351728',
16:47:39     INFO -  'XPCOM_DEBUG_BREAK': 'stack-and-abort',
16:47:39     INFO -  '_': 'C:\\mozilla-build\\buildbotve\\Scripts\\python'}
16:47:39     INFO -  Requirement already satisfied: taskcluster==0.0.26 in .\venv\lib\site-packages
16:47:39     INFO -  Collecting requests<=2.7.0,>=2.4.3 (from taskcluster==0.0.26)
16:47:39     INFO -    Using cached requests-2.7.0-py2.py3-none-any.whl
16:47:39     INFO -  Requirement already satisfied: PyHawk_with_a_single_extra_commit==0.1.5 in .\venv\lib\site-packages (from taskcluster==0.0.26)
16:47:39     INFO -  Requirement already satisfied: slugid in .\venv\lib\site-packages (from taskcluster==0.0.26)
16:47:39     INFO -  Installing collected packages: requests
16:47:39     INFO -    Found existing installation: requests 2.8.1
16:47:39     INFO -      Uninstalling requests-2.8.1:
16:47:40     INFO -        Successfully uninstalled requests-2.8.1
16:47:41     INFO -  Successfully installed requests-2.7.0
16:47:42     INFO - Return code: 0
16:47:42     INFO - Done creating virtualenv c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv.
16:47:42     INFO - Getting output from command: ['c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\venv\\Scripts\\pip', 'freeze']
16:47:42     INFO - Copy/paste: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\venv\Scripts\pip freeze
16:47:42     INFO - Reading from file tmpfile_stdout
16:47:42     INFO - Using _rmtree_windows ...
16:47:42     INFO - Using _rmtree_windows ...
16:47:42     INFO - Current package versions:
16:47:42     INFO -   PyHawk-with-a-single-extra-commit == 0.1.5
16:47:42     INFO -   requests == 2.7.0
16:47:42     INFO -   slugid == 1.0.7
16:47:42     INFO -   taskcluster == 0.0.26
16:47:42     INFO - Dumping config to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\logs\localconfig.json.
16:47:42     INFO - {'app_ini_path': '%(obj_dir)s/dist/bin/application.ini',
16:47:42     INFO -  'append_to_log': False,
16:47:42     INFO -  'balrog_credentials_file': 'oauth.txt',
16:47:42     INFO -  'balrog_servers': ({'balrog_api_root': 'https://aus4-admin.mozilla.org/api',
16:47:42     INFO -                      'balrog_usernames': {'Fennec': 'ffxbld',
16:47:42     INFO -                                           'firefox': 'ffxbld',
16:47:42     INFO -                                           'mobile': 'ffxbld',
16:47:42     INFO -                                           'thunderbird': 'tbirdbld'},
16:47:42     INFO -                      'ignore_failures': False,
16:47:42     INFO -                      'url_replacements': (('http://archive.mozilla.org/pub',
16:47:42     INFO -                                            'http://download.cdn.mozilla.net/pub'),)},),
16:47:42     INFO -  'base_name': 'WINNT_5.2_%(branch)s',
16:47:42     INFO -  'base_work_dir': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000',
16:47:42     INFO -  'branch': 'mozilla-inbound',
16:47:42     INFO -  'build_pool': 'production',
16:47:42     INFO -  'build_resources_path': '%(abs_src_dir)s/obj-firefox/.mozbuild/build_resources.json',
16:47:42     INFO -  'build_variant': 'debug',
16:47:42     INFO -  'buildbot_json_path': 'buildprops.json',
16:47:42     INFO -  'check_test_env': {'MINIDUMP_SAVE_PATH': '%(base_work_dir)s/minidumps',
16:47:42     INFO -                     'MINIDUMP_STACKWALK': '%(abs_tools_dir)s/breakpad/win32/minidump_stackwalk.exe'},
16:47:42     INFO -  'clobberer_url': 'https://api.pub.build.mozilla.org/clobberer/lastclobber',
16:47:42     INFO -  'clone_by_revision': False,
16:47:42     INFO -  'clone_with_purge': False,
16:47:42     INFO -  'config_files': ('builds/releng_base_windows_32_builds.py',
16:47:42     INFO -                   'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\scripts\\scripts\\..\\configs\\builds/releng_sub_windows_configs/32_debug.py',
16:47:42     INFO -                   'balrog/production.py',
16:47:42     INFO -                   'builds/branch_specifics.py',
16:47:42     INFO -                   'builds/build_pool_specifics.py'),
16:47:42     INFO -  'debug_build': True,
16:47:42     INFO -  'default_actions': ('clobber',
16:47:42     INFO -                      'clone-tools',
16:47:42     INFO -                      'checkout-sources',
16:47:42     INFO -                      'build',
16:47:42     INFO -                      'upload-files',
16:47:42     INFO -                      'sendchange',
16:47:42     INFO -                      'check-test',
16:47:42     INFO -                      'generate-build-stats',
16:47:42     INFO -                      'update'),
16:47:42     INFO -  'enable_ccache': False,
16:47:42     INFO -  'enable_count_ctors': False,
16:47:42     INFO -  'enable_pymake': True,
16:47:42     INFO -  'enable_signing': True,
16:47:42     INFO -  'enable_talos_sendchange': False,
16:47:42     INFO -  'enable_unittest_sendchange': True,
16:47:42     INFO -  'env': {'BINSCOPE': 'C:/Program Files (x86)/Microsoft/SDL BinScope/BinScope.exe',
16:47:42     INFO -          'HG_SHARE_BASE_DIR': 'C:/builds/hg-shared',
16:47:42     INFO -          'MOZBUILD_STATE_PATH': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\.mozbuild',
16:47:42     INFO -          'MOZ_AUTOMATION': '1',
16:47:42     INFO -          'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:47:42     INFO -          'MOZ_OBJDIR': 'obj-firefox',
16:47:42     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',
16:47:42     INFO -          'PROPERTIES_FILE': 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\buildprops.json',
16:47:42     INFO -          'TINDERBOX_OUTPUT': '1',
16:47:42     INFO -          'TOOLTOOL_CACHE': '/c/builds/tooltool_cache',
16:47:42     INFO -          'TOOLTOOL_HOME': '/c/builds',
16:47:42     INFO -          'XPCOM_DEBUG_BREAK': 'stack-and-abort'},
16:47:42     INFO -  'exes': {'buildbot': ('c:\\mozilla-build\\python27\\python.exe',
16:47:42     INFO -                        'c:\\mozilla-build\\buildbotve\\scripts\\buildbot'),
16:47:42     INFO -           'make': ('c:\\mozilla-build\\python27\\python.exe',
16:47:42     INFO -                    'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\build\\pymake\\make.py'),
16:47:42     INFO -           'python2.7': 'c:\\mozilla-build\\python27\\python.exe',
16:47:42     INFO -           'virtualenv': ('c:\\mozilla-build\\python27\\python.exe',
16:47:42     INFO -                          'c:/mozilla-build/buildbotve/virtualenv.py')},
16:47:42     INFO -  'graph_selector': '/server/collect.cgi',
16:47:42     INFO -  'graph_server': 'graphs.mozilla.org',
16:47:42     INFO -  'include_post_upload_builddir': False,
16:47:42     INFO -  'is_automation': True,
16:47:42     INFO -  'latest_mar_dir': '/pub/mozilla.org/firefox/nightly/latest-%(branch)s',
16:47:42     INFO -  'log_level': 'info',
16:47:42     INFO -  'log_to_console': True,
16:47:42     INFO -  'max_build_output_timeout': 4800,
16:47:42     INFO -  'nightly_build': False,
16:47:42     INFO -  'nightly_promotion_branches': ('mozilla-central', 'mozilla-aurora'),
16:47:42     INFO -  'objdir': 'obj-firefox',
16:47:42     INFO -  'old_packages': ('%(objdir)s/dist/firefox-*',
16:47:42     INFO -                   '%(objdir)s/dist/fennec*',
16:47:42     INFO -                   '%(objdir)s/dist/seamonkey*',
16:47:42     INFO -                   '%(objdir)s/dist/thunderbird*',
16:47:42     INFO -                   '%(objdir)s/dist/install/sea/*.exe'),
16:47:42     INFO -  'opt_config_files': (),
16:47:42     INFO -  'periodic_clobber': 168,
16:47:42     INFO -  'pgo_build': False,
16:47:42     INFO -  'pgo_platforms': ('linux', 'linux64', 'win32', 'win64'),
16:47:42     INFO -  'platform': 'win32',
16:47:42     INFO -  'platform_supports_post_upload_to_latest': True,
16:47:42     INFO -  'publish_nightly_en_US_routes': True,
16:47:42     INFO -  'release_to_try_builds': False,
16:47:42     INFO -  'repo_base': 'https://hg.mozilla.org',
16:47:42     INFO -  'repo_path': 'integration/mozilla-inbound',
16:47:42     INFO -  'scm_level': 1,
16:47:42     INFO -  'sendchange_masters': ('buildbot-master81.build.mozilla.org:9301',),
16:47:42     INFO -  'src_mozconfig': 'browser/config/mozconfigs/win32/debug',
16:47:42     INFO -  'stage_platform': 'win32-debug',
16:47:42     INFO -  'stage_product': 'firefox',
16:47:42     INFO -  'stage_server': 'upload.ffxbld.productdelivery.prod.mozaws.net',
16:47:42     INFO -  'stage_ssh_key': 'ffxbld_rsa',
16:47:42     INFO -  'stage_username': 'ffxbld',
16:47:42     INFO -  'taskcluster_credentials_file': 'oauth.txt',
16:47:42     INFO -  'taskcluster_index': 'index',
16:47:42     INFO -  'tinderbox_build_dir': None,
16:47:42     INFO -  'to_tinderbox_dated': True,
16:47:42     INFO -  'tools_repo': 'https://hg.mozilla.org/build/tools',
16:47:42     INFO -  'tooltool_bootstrap': 'setup.sh',
16:47:42     INFO -  'tooltool_manifest_src': 'browser/config/tooltool-manifests/win32/releng.manifest',
16:47:42     INFO -  'tooltool_script': ('c:\\mozilla-build\\python27\\python.exe',
16:47:42     INFO -                      'C:/mozilla-build/tooltool.py'),
16:47:42     INFO -  'tooltool_url': 'https://api.pub.build.mozilla.org/tooltool/',
16:47:42     INFO -  'upload_env': {'UPLOAD_HOST': '%(stage_server)s',
16:47:42     INFO -                 'UPLOAD_SSH_KEY': '/c/Users/cltbld/.ssh/%(stage_ssh_key)s',
16:47:42     INFO -                 'UPLOAD_TO_TEMP': '1',
16:47:42     INFO -                 'UPLOAD_USER': '%(stage_username)s'},
16:47:42     INFO -  'use_clobberer': True,
16:47:42     INFO -  'vcs_share_base': 'C:/builds/hg-shared',
16:47:42     INFO -  'virtualenv_modules': ('requests==2.8.1',
16:47:42     INFO -                         'PyHawk-with-a-single-extra-commit==0.1.5',
16:47:42     INFO -                         'taskcluster==0.0.26'),
16:47:42     INFO -  'virtualenv_path': 'venv',
16:47:42     INFO -  'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
16:47:42     INFO -  'who': '',
16:47:42     INFO -  'work_dir': 'build'}
16:47:42     INFO - [mozharness: 2017-01-27 00:47:42.721000Z] Skipping get-secrets step.
16:47:42     INFO - [mozharness: 2017-01-27 00:47:42.721000Z] Running clobber step.
16:47:42     INFO - Running main action method: clobber
16:47:42     INFO - retry: Calling run_command with args: [['python', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\scripts\\external_tools\\clobberer.py', '-s', 'scripts', '-s', 'logs', '-s', 'buildprops.json', '-s', 'token', '-s', 'oauth.txt', '-t', '168', 'https://api.pub.build.mozilla.org/clobberer/lastclobber', u'mozilla-inbound', u'WINNT 5.2 mozilla-inbound leak test build', 'm-in-w32-d-0000000000000000000', u'b-2008-spot-101', u'http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/']], kwargs: {'error_list': [{'substr': 'Error contacting server', 'explanation': 'Error contacting server for clobberer information.', 'level': 'error'}], 'cwd': 'c:\\builds\\moz2_slave'}, attempt #1
16:47:42     INFO - Running command: ['python', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\scripts\\external_tools\\clobberer.py', '-s', 'scripts', '-s', 'logs', '-s', 'buildprops.json', '-s', 'token', '-s', 'oauth.txt', '-t', '168', 'https://api.pub.build.mozilla.org/clobberer/lastclobber', u'mozilla-inbound', u'WINNT 5.2 mozilla-inbound leak test build', 'm-in-w32-d-0000000000000000000', u'b-2008-spot-101', u'http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/'] in c:\builds\moz2_slave
16:47:42     INFO - Copy/paste: python c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\scripts\external_tools\clobberer.py -s scripts -s logs -s buildprops.json -s token -s oauth.txt -t 168 https://api.pub.build.mozilla.org/clobberer/lastclobber mozilla-inbound "WINNT 5.2 mozilla-inbound leak test build" m-in-w32-d-0000000000000000000 b-2008-spot-101 http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/
16:56:22     INFO -  Checking clobber URL: https://api.pub.build.mozilla.org/clobberer/lastclobber?master=http%3A%2F%2Fbuildbot-master73.bb.releng.usw2.mozilla.com%3A8001%2F&slave=b-2008-spot-101&builddir=m-in-w32-d-0000000000000000000&branch=mozilla-inbound&buildername=WINNT+5.2+mozilla-inbound+leak+test+build
16:56:22     INFO -  m-in-w32-d-0000000000000000000:Our last clobber date:  2017-01-19 15:28:46
16:56:22     INFO -  m-in-w32-d-0000000000000000000:Server clobber date:    2017-01-19 15:28:46
16:56:22     INFO -  m-in-w32-d-0000000000000000000:More than 604800.0 seconds have passed since our last clobber
16:56:22     INFO -  m-in-w32-d-0000000000000000000:Clobbering...
16:56:22     INFO -  Removing archiver_client.py
16:56:22     INFO -  Removing build/
16:56:22     INFO -  Skipping buildprops.json
16:56:22     INFO -  Skipping last-clobber
16:56:22     INFO -  Skipping logs
16:56:22     INFO -  Skipping oauth.txt
16:56:22     INFO -  Removing properties/
16:56:22     INFO -  Skipping scripts
16:56:22     INFO -  Skipping token
16:56:22     INFO -  TinderboxPrint: periodic clobber
16:56:22     INFO - Return code: 0
16:56:22     INFO - [mozharness: 2017-01-27 00:56:22.035000Z] Finished clobber step (success)
16:56:22     INFO - [mozharness: 2017-01-27 00:56:22.035000Z] Running clone-tools step.
16:56:22     INFO - Running main action method: clone_tools
16:56:22     INFO - retry: Calling _get_revision with args: (<mozharness.base.vcs.mercurial.MercurialVCS object at 0x028BF770>, 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\tools'), kwargs: {}, attempt #1
16:56:22  WARNING - did not specify revision or branch; assuming "default"
16:56:22     INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version']
16:56:22     INFO - Copy/paste: hg --config ui.merge=internal:merge --version
16:56:23     INFO -  Mercurial Distributed SCM (version 3.9.1)
16:56:23     INFO -  (see https://mercurial-scm.org for more information)
16:56:23     INFO -  Copyright (C) 2005-2016 Matt Mackall and others
16:56:23     INFO -  This is free software; see the source for copying conditions. There is NO
16:56:23     INFO -  warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.
16:56:23     INFO - Return code: 0
16:56:23     INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall']
16:56:23     INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall
16:56:23     INFO -  checking encoding (cp1252)...
16:56:23     INFO -  checking Python executable (c:\mozilla-build\hg\hg.exe)
16:56:23     INFO -  checking Python version (2.7.12)
16:56:23     INFO -  checking Python lib (c:\mozilla-build\hg\lib\library.zip)...
16:56:23     INFO -  checking Mercurial version (3.9.1)
16:56:23     INFO -  checking Mercurial custom build ()
16:56:23     INFO -  checking module policy (c)
16:56:23     INFO -  checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)...
16:56:23     INFO -  checking templates (c:\mozilla-build\hg\templates)...
16:56:23     INFO -  checking default template (c:\mozilla-build\hg\templates\map-cmdline.default)
16:56:23     INFO -  checking commit editor... (notepad)
16:56:23     INFO -  checking username (Mozilla Release Engineering <release@mozilla.com>)
16:56:23     INFO -  no problems detected
16:56:23     INFO - Return code: 0
16:56:23     INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/build/tools', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\tools', '--sharebase', 'C:/builds/hg-shared', '--branch', 'default']
16:56:23     INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/build/tools c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\tools --sharebase C:/builds/hg-shared --branch default
16:56:25     INFO -  ensuring https://hg.mozilla.org/build/tools@default is available at c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\tools
16:56:25     INFO -  (sharing from existing pooled repository 7ae7fb134bf7aec6ec96a062ff47a69053dd2973)
16:56:25     INFO -  searching for changes
16:56:25     INFO -  no changes found
16:56:25     INFO -  (pulling to obtain default)
16:56:25     INFO -  (remote resolved default to 2f05fab852562524a1a795c7d366a4a6f74e45cb; result is not deterministic)
16:56:25     INFO -  (revision already present locally; not pulling)
16:56:29     INFO -  
16:56:29     INFO - updating [===================================================>   ] 900/936 01s
16:56:29     INFO -                                                                                
16:56:29     INFO - 936 files updated, 0 files merged, 0 files removed, 0 files unresolved
16:56:29     INFO -  updated to 2f05fab852562524a1a795c7d366a4a6f74e45cb
16:56:29     INFO - Return code: 0
16:56:29     INFO - [mozharness: 2017-01-27 00:56:29.295000Z] Finished clone-tools step (success)
16:56:29     INFO - [mozharness: 2017-01-27 00:56:29.295000Z] Running checkout-sources step.
16:56:29     INFO - Running main action method: checkout_sources
16:56:29     INFO - retry: Calling _get_revision with args: (<mozharness.base.vcs.mercurial.MercurialVCS object at 0x028BF770>, 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src'), kwargs: {}, attempt #1
16:56:29     INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version']
16:56:29     INFO - Copy/paste: hg --config ui.merge=internal:merge --version
16:56:29     INFO -  Mercurial Distributed SCM (version 3.9.1)
16:56:29     INFO -  (see https://mercurial-scm.org for more information)
16:56:29     INFO -  Copyright (C) 2005-2016 Matt Mackall and others
16:56:29     INFO -  This is free software; see the source for copying conditions. There is NO
16:56:29     INFO -  warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.
16:56:29     INFO - Return code: 0
16:56:29     INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall']
16:56:29     INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall
16:56:29     INFO -  checking encoding (cp1252)...
16:56:29     INFO -  checking Python executable (c:\mozilla-build\hg\hg.exe)
16:56:29     INFO -  checking Python version (2.7.12)
16:56:29     INFO -  checking Python lib (c:\mozilla-build\hg\lib\library.zip)...
16:56:29     INFO -  checking Mercurial version (3.9.1)
16:56:29     INFO -  checking Mercurial custom build ()
16:56:29     INFO -  checking module policy (c)
16:56:29     INFO -  checking installed modules (c:\mozilla-build\hg\lib\library.zip\mercurial)...
16:56:29     INFO -  checking templates (c:\mozilla-build\hg\templates)...
16:56:29     INFO -  checking default template (c:\mozilla-build\hg\templates\map-cmdline.default)
16:56:29     INFO -  checking commit editor... (notepad)
16:56:29     INFO -  checking username (Mozilla Release Engineering <release@mozilla.com>)
16:56:29     INFO -  no problems detected
16:56:29     INFO - Return code: 0
16:56:29     INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\scripts\\external_tools\\robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/integration/mozilla-inbound', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src', '--sharebase', 'C:/builds/hg-shared', '--revision', 'd9a53886e7942d3c13760dadce618cb4e3595e66']
16:56:29     INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\scripts\external_tools\robustcheckout.py robustcheckout https://hg.mozilla.org/integration/mozilla-inbound c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src --sharebase C:/builds/hg-shared --revision d9a53886e7942d3c13760dadce618cb4e3595e66
16:57:05     INFO -  
16:57:05     INFO - files [======================>                                         ] 35/97
16:57:05     INFO - files [=========================================>                  ] 68/97 01s
16:57:05     INFO -                                                                                
16:57:05     INFO - ensuring https://hg.mozilla.org/integration/mozilla-inbound@d9a53886e7942d3c13760dadce618cb4e3595e66 is available at c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src
16:57:05     INFO -  (sharing from existing pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29)
16:57:05     INFO -  searching for changes
16:57:05     INFO -  adding changesets
16:57:05     INFO -  adding manifests
16:57:05     INFO -  adding file changes
16:57:05     INFO -  added 35 changesets with 115 changes to 92 files (-1 heads)
17:04:03     INFO -  
17:04:03     INFO - updating [                                                     ]    100/156973
17:04:03     INFO - updating [                                                     ]    500/156973
17:04:03     INFO - updating [                                               ]   1200/156973 5m08s
17:04:03     INFO - updating [                                               ]   1800/156973 4m59s
17:04:03     INFO - updating [                                               ]   2100/156973 6m12s
17:04:03     INFO - updating [                                               ]   2300/156973 6m53s
17:04:03     INFO - updating [                                               ]   2400/156973 8m33s
17:04:03     INFO - updating [                                               ]   2700/156973 8m35s
17:04:03     INFO - updating [                                               ]   3200/156973 8m28s
17:04:03     INFO - updating [                                              ]   3300/156973 10m04s
17:04:03     INFO - updating [                                              ]   3400/156973 10m44s
17:04:03     INFO - updating [>                                             ]   3600/156973 10m53s
17:04:03     INFO - updating [>                                             ]   4000/156973 10m35s
17:04:03     INFO - updating [>                                             ]   4300/156973 10m34s
17:04:03     INFO - updating [>                                             ]   4700/156973 10m19s
17:04:03     INFO - updating [>                                              ]   5300/156973 9m38s
17:04:03     INFO - updating [>                                              ]   5500/156973 9m47s
17:04:03     INFO - updating [>                                              ]   5800/156973 9m47s
17:04:03     INFO - updating [>                                              ]   6200/156973 9m37s
17:04:03     INFO - updating [>                                              ]   6500/156973 9m38s
17:04:03     INFO - updating [=>                                             ]   6800/156973 9m39s
17:04:03     INFO - updating [=>                                             ]   7100/156973 9m38s
17:04:03     INFO - updating [=>                                             ]   7500/156973 9m27s
17:04:03     INFO - updating [=>                                             ]   7800/156973 9m25s
17:04:03     INFO - updating [=>                                             ]   8200/156973 9m23s
17:04:03     INFO - updating [=>                                             ]   8800/156973 9m01s
17:04:03     INFO - updating [=>                                             ]   9100/156973 9m01s
17:04:03     INFO - updating [=>                                             ]   9700/156973 8m43s
17:04:03     INFO - updating [==>                                            ]  10400/156973 8m21s
17:04:03     INFO - updating [==>                                            ]  10800/156973 8m25s
17:04:03     INFO - updating [==>                                            ]  11400/156973 8m10s
17:04:03     INFO - updating [==>                                            ]  12100/156973 7m56s
17:04:03     INFO - updating [==>                                            ]  12700/156973 7m46s
17:04:03     INFO - updating [==>                                            ]  13200/156973 7m40s
17:04:03     INFO - updating [===>                                           ]  13700/156973 7m34s
17:04:03     INFO - updating [===>                                           ]  14100/156973 7m33s
17:04:03     INFO - updating [===>                                           ]  14400/156973 7m39s
17:04:03     INFO - updating [===>                                           ]  14500/156973 7m46s
17:04:03     INFO - updating [===>                                           ]  15300/156973 7m30s
17:04:03     INFO - updating [===>                                           ]  15500/156973 7m34s
17:04:03     INFO - updating [===>                                           ]  16000/156973 7m29s
17:04:03     INFO - updating [===>                                           ]  16300/156973 7m33s
17:04:03     INFO - updating [====>                                          ]  16700/156973 7m30s
17:04:03     INFO - updating [====>                                          ]  17200/156973 7m24s
17:04:03     INFO - updating [====>                                          ]  17800/156973 7m15s
17:04:03     INFO - updating [====>                                          ]  18700/156973 7m02s
17:04:03     INFO - updating [====>                                          ]  19500/156973 6m49s
17:04:03     INFO - updating [=====>                                         ]  20100/156973 6m43s
17:04:03     INFO - updating [=====>                                         ]  20500/156973 6m42s
17:04:03     INFO - updating [=====>                                         ]  21200/156973 6m34s
17:04:03     INFO - updating [=====>                                         ]  21500/156973 6m35s
17:04:03     INFO - updating [=====>                                         ]  22100/156973 6m29s
17:04:03     INFO - updating [=====>                                         ]  23100/156973 6m15s
17:04:03     INFO - updating [======>                                        ]  23700/156973 6m10s
17:04:03     INFO - updating [======>                                        ]  24000/156973 6m12s
17:04:03     INFO - updating [======>                                        ]  24500/156973 6m10s
17:04:03     INFO - updating [======>                                        ]  25000/156973 6m07s
17:04:03     INFO - updating [======>                                        ]  25600/156973 6m05s
17:04:03     INFO - updating [======>                                        ]  26000/156973 6m04s
17:04:03     INFO - updating [======>                                        ]  26300/156973 6m06s
17:04:03     INFO - updating [======>                                        ]  26500/156973 6m09s
17:04:03     INFO - updating [=======>                                       ]  26900/156973 6m10s
17:04:03     INFO - updating [=======>                                       ]  27100/156973 6m13s
17:04:03     INFO - updating [=======>                                       ]  27500/156973 6m12s
17:04:03     INFO - updating [=======>                                       ]  27800/156973 6m16s
17:04:03     INFO - updating [=======>                                       ]  27900/156973 6m19s
17:04:03     INFO - updating [=======>                                       ]  28100/156973 6m21s
17:04:03     INFO - updating [=======>                                       ]  28200/156973 6m24s
17:04:03     INFO - updating [=======>                                       ]  28500/156973 6m25s
17:04:03     INFO - updating [=======>                                       ]  28900/156973 6m25s
17:04:03     INFO - updating [=======>                                       ]  29100/156973 6m26s
17:04:03     INFO - updating [=======>                                       ]  29400/156973 6m27s
17:04:03     INFO - updating [=======>                                       ]  29700/156973 6m27s
17:04:03     INFO - updating [========>                                      ]  30100/156973 6m25s
17:04:03     INFO - updating [========>                                      ]  30700/156973 6m21s
17:04:03     INFO - updating [========>                                      ]  31300/156973 6m16s
17:04:03     INFO - updating [========>                                      ]  31600/156973 6m16s
17:04:03     INFO - updating [========>                                      ]  32300/156973 6m10s
17:04:03     INFO - updating [========>                                      ]  33100/156973 6m03s
17:04:03     INFO - updating [=========>                                     ]  33800/156973 5m58s
17:04:03     INFO - updating [=========>                                     ]  34400/156973 5m54s
17:04:03     INFO - updating [=========>                                     ]  35000/156973 5m50s
17:04:03     INFO - updating [=========>                                     ]  35200/156973 5m52s
17:04:03     INFO - updating [=========>                                     ]  36000/156973 5m46s
17:04:03     INFO - updating [=========>                                     ]  36700/156973 5m41s
17:04:03     INFO - updating [==========>                                    ]  37200/156973 5m38s
17:04:03     INFO - updating [==========>                                    ]  37800/156973 5m35s
17:04:03     INFO - updating [==========>                                    ]  38400/156973 5m32s
17:04:03     INFO - updating [==========>                                    ]  38700/156973 5m32s
17:04:03     INFO - updating [==========>                                    ]  39000/156973 5m31s
17:04:03     INFO - updating [==========>                                    ]  39400/156973 5m30s
17:04:03     INFO - updating [==========>                                    ]  39700/156973 5m30s
17:04:03     INFO - updating [==========>                                    ]  40000/156973 5m33s
17:04:03     INFO - updating [===========>                                   ]  40200/156973 5m35s
17:04:03     INFO - updating [===========>                                   ]  40400/156973 5m36s
17:04:03     INFO - updating [===========>                                   ]  40700/156973 5m37s
17:04:03     INFO - updating [===========>                                   ]  40900/156973 5m38s
17:04:03     INFO - updating [===========>                                   ]  41000/156973 5m41s
17:04:03     INFO - updating [===========>                                   ]  41100/156973 5m43s
17:04:03     INFO - updating [===========>                                   ]  41600/156973 5m41s
17:04:03     INFO - updating [===========>                                   ]  42100/156973 5m39s
17:04:03     INFO - updating [===========>                                   ]  42300/156973 5m40s
17:04:03     INFO - updating [===========>                                   ]  42600/156973 5m39s
17:04:03     INFO - updating [===========>                                   ]  42900/156973 5m43s
17:04:03     INFO - updating [===========>                                   ]  43100/156973 5m44s
17:04:03     INFO - updating [============>                                  ]  43600/156973 5m42s
17:04:03     INFO - updating [============>                                  ]  43800/156973 5m47s
17:04:03     INFO - updating [============>                                  ]  44100/156973 5m48s
17:04:03     INFO - updating [============>                                  ]  44800/156973 5m43s
17:04:03     INFO - updating [============>                                  ]  45500/156973 5m39s
17:04:03     INFO - updating [============>                                  ]  45800/156973 5m39s
17:04:03     INFO - updating [============>                                  ]  46300/156973 5m38s
17:04:03     INFO - updating [============>                                  ]  46600/156973 5m38s
17:04:03     INFO - updating [=============>                                 ]  47000/156973 5m36s
17:04:03     INFO - updating [=============>                                 ]  47300/156973 5m38s
17:04:03     INFO - updating [=============>                                 ]  47600/156973 5m37s
17:04:03     INFO - updating [=============>                                 ]  47900/156973 5m37s
17:04:03     INFO - updating [=============>                                 ]  48100/156973 5m38s
17:04:03     INFO - updating [=============>                                 ]  48500/156973 5m36s
17:04:03     INFO - updating [=============>                                 ]  48700/156973 5m37s
17:04:03     INFO - updating [=============>                                 ]  49000/156973 5m36s
17:04:03     INFO - updating [=============>                                 ]  49400/156973 5m35s
17:04:03     INFO - updating [=============>                                 ]  49700/156973 5m35s
17:04:03     INFO - updating [==============>                                ]  50200/156973 5m35s
17:04:03     INFO - updating [==============>                                ]  50300/156973 5m37s
17:04:03     INFO - updating [==============>                                ]  50800/156973 5m38s
17:04:03     INFO - updating [==============>                                ]  51400/156973 5m35s
17:04:03     INFO - updating [==============>                                ]  52100/156973 5m30s
17:04:03     INFO - updating [==============>                                ]  53100/156973 5m23s
17:04:03     INFO - updating [===============>                               ]  54000/156973 5m17s
17:04:03     INFO - updating [===============>                               ]  55000/156973 5m11s
17:04:03     INFO - updating [===============>                               ]  56000/156973 5m04s
17:04:03     INFO - updating [===============>                               ]  56500/156973 5m02s
17:04:03     INFO - updating [===============>                               ]  56600/156973 5m05s
17:04:03     INFO - updating [===============>                               ]  56700/156973 5m07s
17:04:03     INFO - updating [================>                              ]  56900/156973 5m09s
17:04:03     INFO - updating [================>                              ]  57100/156973 5m14s
17:04:03     INFO - updating [================>                              ]  57400/156973 5m13s
17:04:03     INFO - updating [================>                              ]  58200/156973 5m08s
17:04:03     INFO - updating [================>                              ]  59000/156973 5m03s
17:04:03     INFO - updating [================>                              ]  59800/156973 4m58s
17:04:03     INFO - updating [=================>                             ]  60500/156973 4m55s
17:04:03     INFO - updating [=================>                             ]  61200/156973 4m51s
17:04:03     INFO - updating [=================>                             ]  62300/156973 4m45s
17:04:03     INFO - updating [=================>                             ]  63400/156973 4m38s
17:04:03     INFO - updating [==================>                            ]  64400/156973 4m32s
17:04:03     INFO - updating [==================>                            ]  65400/156973 4m27s
17:04:03     INFO - updating [==================>                            ]  65500/156973 4m29s
17:04:03     INFO - updating [==================>                            ]  65600/156973 4m30s
17:04:03     INFO - updating [==================>                            ]  65800/156973 4m31s
17:04:03     INFO - updating [==================>                            ]  66200/156973 4m30s
17:04:03     INFO - updating [==================>                            ]  66700/156973 4m28s
17:04:03     INFO - updating [===================>                           ]  67000/156973 4m28s
17:04:03     INFO - updating [===================>                           ]  67300/156973 4m27s
17:04:03     INFO - updating [===================>                           ]  68000/156973 4m24s
17:04:03     INFO - updating [===================>                           ]  68100/156973 4m26s
17:04:03     INFO - updating [===================>                           ]  68500/156973 4m26s
17:04:03     INFO - updating [===================>                           ]  69400/156973 4m21s
17:04:03     INFO - updating [===================>                           ]  70100/156973 4m18s
17:04:03     INFO - updating [====================>                          ]  71100/156973 4m12s
17:04:03     INFO - updating [====================>                          ]  71900/156973 4m08s
17:04:03     INFO - updating [====================>                          ]  73000/156973 4m03s
17:04:03     INFO - updating [=====================>                         ]  73800/156973 3m59s
17:04:03     INFO - updating [=====================>                         ]  74600/156973 3m55s
17:04:03     INFO - updating [=====================>                         ]  75300/156973 3m52s
17:04:03     INFO - updating [=====================>                         ]  76300/156973 3m48s
17:04:03     INFO - updating [======================>                        ]  77300/156973 3m43s
17:04:03     INFO - updating [======================>                        ]  77800/156973 3m42s
17:04:03     INFO - updating [======================>                        ]  78000/156973 3m43s
17:04:03     INFO - updating [======================>                        ]  79200/156973 3m37s
17:04:03     INFO - updating [======================>                        ]  79900/156973 3m34s
17:04:03     INFO - updating [=======================>                       ]  80600/156973 3m32s
17:04:03     INFO - updating [=======================>                       ]  81700/156973 3m27s
17:04:03     INFO - updating [=======================>                       ]  82400/156973 3m24s
17:04:03     INFO - updating [=======================>                       ]  83300/156973 3m20s
17:04:03     INFO - updating [========================>                      ]  84200/156973 3m17s
17:04:03     INFO - updating [========================>                      ]  85000/156973 3m14s
17:04:03     INFO - updating [========================>                      ]  85700/156973 3m11s
17:04:03     INFO - updating [========================>                      ]  86700/156973 3m07s
17:04:03     INFO - updating [=========================>                     ]  87200/156973 3m05s
17:04:03     INFO - updating [=========================>                     ]  87800/156973 3m03s
17:04:03     INFO - updating [=========================>                     ]  88200/156973 3m04s
17:04:03     INFO - updating [=========================>                     ]  89200/156973 3m00s
17:04:03     INFO - updating [=========================>                     ]  90000/156973 2m57s
17:04:03     INFO - updating [==========================>                    ]  90700/156973 2m55s
17:04:03     INFO - updating [==========================>                    ]  91000/156973 2m56s
17:04:03     INFO - updating [==========================>                    ]  91500/156973 2m55s
17:04:03     INFO - updating [==========================>                    ]  91800/156973 2m54s
17:04:03     INFO - updating [==========================>                    ]  92200/156973 2m53s
17:04:03     INFO - updating [==========================>                    ]  92700/156973 2m52s
17:04:03     INFO - updating [==========================>                    ]  93100/156973 2m51s
17:04:03     INFO - updating [==========================>                    ]  93300/156973 2m51s
17:04:03     INFO - updating [===========================>                   ]  93700/156973 2m50s
17:04:03     INFO - updating [===========================>                   ]  94100/156973 2m49s
17:04:03     INFO - updating [===========================>                   ]  94500/156973 2m48s
17:04:03     INFO - updating [===========================>                   ]  94900/156973 2m47s
17:04:03     INFO - updating [===========================>                   ]  95300/156973 2m46s
17:04:03     INFO - updating [===========================>                   ]  95600/156973 2m46s
17:04:03     INFO - updating [===========================>                   ]  96100/156973 2m44s
17:04:03     INFO - updating [============================>                  ]  96900/156973 2m41s
17:04:03     INFO - updating [============================>                  ]  97900/156973 2m38s
17:04:03     INFO - updating [============================>                  ]  98300/156973 2m36s
17:04:03     INFO - updating [============================>                  ]  99000/156973 2m34s
17:04:03     INFO - updating [============================>                  ]  99200/156973 2m34s
17:04:03     INFO - updating [============================>                  ]  99700/156973 2m32s
17:04:03     INFO - updating [============================>                  ] 100100/156973 2m31s
17:04:03     INFO - updating [=============================>                 ] 100800/156973 2m29s
17:04:03     INFO - updating [=============================>                 ] 101200/156973 2m28s
17:04:03     INFO - updating [=============================>                 ] 101500/156973 2m28s
17:04:03     INFO - updating [=============================>                 ] 102100/156973 2m26s
17:04:03     INFO - updating [=============================>                 ] 102400/156973 2m25s
17:04:03     INFO - updating [=============================>                 ] 102700/156973 2m25s
17:04:03     INFO - updating [=============================>                 ] 102800/156973 2m26s
17:04:03     INFO - updating [=============================>                 ] 103000/156973 2m26s
17:04:03     INFO - updating [=============================>                 ] 103500/156973 2m24s
17:04:03     INFO - updating [==============================>                ] 104000/156973 2m23s
17:04:03     INFO - updating [==============================>                ] 104300/156973 2m22s
17:04:03     INFO - updating [==============================>                ] 104600/156973 2m22s
17:04:03     INFO - updating [==============================>                ] 105200/156973 2m20s
17:04:03     INFO - updating [==============================>                ] 105700/156973 2m19s
17:04:03     INFO - updating [==============================>                ] 106000/156973 2m18s
17:04:03     INFO - updating [==============================>                ] 106500/156973 2m17s
17:04:03     INFO - updating [===============================>               ] 106900/156973 2m15s
17:04:03     INFO - updating [===============================>               ] 107200/156973 2m15s
17:04:03     INFO - updating [===============================>               ] 107400/156973 2m15s
17:04:03     INFO - updating [===============================>               ] 107500/156973 2m15s
17:04:03     INFO - updating [===============================>               ] 107700/156973 2m15s
17:04:03     INFO - updating [===============================>               ] 108100/156973 2m14s
17:04:03     INFO - updating [===============================>               ] 108500/156973 2m13s
17:04:03     INFO - updating [===============================>               ] 109200/156973 2m10s
17:04:03     INFO - updating [===============================>               ] 109500/156973 2m10s
17:04:03     INFO - updating [===============================>               ] 110100/156973 2m08s
17:04:03     INFO - updating [================================>              ] 110400/156973 2m07s
17:04:03     INFO - updating [================================>              ] 111200/156973 2m05s
17:04:03     INFO - updating [================================>              ] 111700/156973 2m04s
17:04:03     INFO - updating [================================>              ] 112000/156973 2m03s
17:04:03     INFO - updating [================================>              ] 112300/156973 2m02s
17:04:03     INFO - updating [================================>              ] 113100/156973 2m00s
17:04:03     INFO - updating [=================================>             ] 114100/156973 1m56s
17:04:03     INFO - updating [=================================>             ] 114400/156973 1m56s
17:04:03     INFO - updating [=================================>             ] 114700/156973 1m55s
17:04:03     INFO - updating [=================================>             ] 115100/156973 1m54s
17:04:03     INFO - updating [=================================>             ] 115400/156973 1m53s
17:04:03     INFO - updating [=================================>             ] 115700/156973 1m53s
17:04:03     INFO - updating [=================================>             ] 115800/156973 1m53s
17:04:03     INFO - updating [=================================>             ] 116000/156973 1m53s
17:04:03     INFO - updating [=================================>             ] 116500/156973 1m51s
17:04:03     INFO - updating [==================================>            ] 116900/156973 1m50s
17:04:03     INFO - updating [==================================>            ] 117000/156973 1m50s
17:04:03     INFO - updating [==================================>            ] 117200/156973 1m50s
17:04:03     INFO - updating [==================================>            ] 117400/156973 1m50s
17:04:03     INFO - updating [==================================>            ] 118100/156973 1m47s
17:04:03     INFO - updating [==================================>            ] 118500/156973 1m46s
17:04:03     INFO - updating [==================================>            ] 118900/156973 1m45s
17:04:03     INFO - updating [==================================>            ] 119500/156973 1m43s
17:04:03     INFO - updating [==================================>            ] 119700/156973 1m43s
17:04:03     INFO - updating [==================================>            ] 119900/156973 1m43s
17:04:03     INFO - updating [===================================>           ] 120800/156973 1m40s
17:04:03     INFO - updating [===================================>           ] 121500/156973 1m38s
17:04:03     INFO - updating [===================================>           ] 122500/156973 1m35s
17:04:03     INFO - updating [===================================>           ] 123300/156973 1m32s
17:04:03     INFO - updating [====================================>          ] 124000/156973 1m30s
17:04:03     INFO - updating [====================================>          ] 124800/156973 1m28s
17:04:03     INFO - updating [====================================>          ] 125800/156973 1m24s
17:04:03     INFO - updating [====================================>          ] 126700/156973 1m22s
17:04:03     INFO - updating [=====================================>         ] 127500/156973 1m19s
17:04:03     INFO - updating [=====================================>         ] 128200/156973 1m17s
17:04:03     INFO - updating [=====================================>         ] 129100/156973 1m15s
17:04:03     INFO - updating [=====================================>         ] 130200/156973 1m11s
17:04:03     INFO - updating [======================================>        ] 130900/156973 1m09s
17:04:03     INFO - updating [======================================>        ] 131700/156973 1m07s
17:04:03     INFO - updating [======================================>        ] 132200/156973 1m06s
17:04:03     INFO - updating [======================================>        ] 133100/156973 1m03s
17:04:03     INFO - updating [=======================================>       ] 134000/156973 1m00s
17:04:03     INFO - updating [=========================================>       ] 134800/156973 58s
17:04:03     INFO - updating [=========================================>       ] 135700/156973 56s
17:04:03     INFO - updating [=========================================>       ] 136500/156973 53s
17:04:03     INFO - updating [=========================================>       ] 136700/156973 53s
17:04:03     INFO - updating [=========================================>       ] 137500/156973 51s
17:04:03     INFO - updating [==========================================>      ] 138100/156973 49s
17:04:03     INFO - updating [==========================================>      ] 139000/156973 47s
17:04:03     INFO - updating [==========================================>      ] 139800/156973 45s
17:04:03     INFO - updating [==========================================>      ] 140500/156973 43s
17:04:03     INFO - updating [===========================================>     ] 141300/156973 41s
17:04:03     INFO - updating [===========================================>     ] 142300/156973 38s
17:04:03     INFO - updating [===========================================>     ] 142700/156973 37s
17:04:03     INFO - updating [===========================================>     ] 143200/156973 36s
17:04:03     INFO - updating [===========================================>     ] 143800/156973 34s
17:04:03     INFO - updating [============================================>    ] 144400/156973 33s
17:04:03     INFO - updating [============================================>    ] 145300/156973 30s
17:04:03     INFO - updating [============================================>    ] 146000/156973 28s
17:04:03     INFO - updating [============================================>    ] 146700/156973 27s
17:04:03     INFO - updating [=============================================>   ] 147500/156973 24s
17:04:03     INFO - updating [=============================================>   ] 148000/156973 23s
17:04:03     INFO - updating [=============================================>   ] 148400/156973 22s
17:04:03     INFO - updating [=============================================>   ] 148800/156973 21s
17:04:03     INFO - updating [=============================================>   ] 149200/156973 20s
17:04:03     INFO - updating [=============================================>   ] 149500/156973 19s
17:04:03     INFO - updating [=============================================>   ] 149700/156973 19s
17:04:03     INFO - updating [=============================================>   ] 150000/156973 18s
17:04:03     INFO - updating [=============================================>   ] 150300/156973 18s
17:04:03     INFO - updating [==============================================>  ] 150600/156973 17s
17:04:03     INFO - updating [==============================================>  ] 150900/156973 16s
17:04:03     INFO - updating [==============================================>  ] 151300/156973 15s
17:04:03     INFO - updating [==============================================>  ] 151400/156973 15s
17:04:03     INFO - updating [==============================================>  ] 151700/156973 14s
17:04:03     INFO - updating [==============================================>  ] 152000/156973 13s
17:04:03     INFO - updating [==============================================>  ] 152300/156973 13s
17:04:03     INFO - updating [==============================================>  ] 152700/156973 12s
17:04:03     INFO - updating [==============================================>  ] 153300/156973 10s
17:04:03     INFO - updating [==============================================>  ] 153700/156973 09s
17:04:03     INFO - updating [===============================================> ] 154200/156973 08s
17:04:03     INFO - updating [===============================================> ] 154600/156973 07s
17:04:03     INFO - updating [===============================================> ] 155100/156973 05s
17:04:03     INFO - updating [===============================================> ] 155300/156973 05s
17:04:03     INFO - updating [===============================================> ] 155600/156973 04s
17:04:03     INFO - updating [===============================================> ] 155800/156973 04s
17:04:03     INFO - updating [===============================================> ] 156000/156973 03s
17:04:03     INFO - updating [===============================================> ] 156200/156973 03s
17:04:03     INFO - updating [===============================================> ] 156400/156973 02s
17:04:03     INFO - updating [===============================================> ] 156900/156973 01s
17:04:03     INFO -                                                                                
17:04:03     INFO - 156973 files updated, 0 files merged, 0 files removed, 0 files unresolved
17:04:03     INFO -  updated to d9a53886e7942d3c13760dadce618cb4e3595e66
17:04:03     INFO - Return code: 0
17:04:03     INFO - Setting buildbot property comments to Merge m-c to inbound, a=merge
17:04:03     INFO - 
17:04:03     INFO - MozReview-Commit-ID: 5CIctUOTFma
17:04:03     INFO - mkdir: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties
17:04:03     INFO - Writing buildbot properties ['comments'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\comments
17:04:03     INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\comments
17:04:03     INFO - Contents:
17:04:03     INFO -  comments:Merge m-c to inbound, a=merge
17:04:03     INFO -  
17:04:03     INFO -  MozReview-Commit-ID: 5CIctUOTFma
17:04:03     INFO - Setting buildbot property got_revision to d9a53886e7942d3c13760dadce618cb4e3595e66
17:04:03     INFO - Writing buildbot properties ['got_revision'] to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\got_revision
17:04:03     INFO - Writing to file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties\got_revision
17:04:03     INFO - Contents:
17:04:03     INFO -  got_revision:d9a53886e7942d3c13760dadce618cb4e3595e66
17:04:03     INFO - [mozharness: 2017-01-27 01:04:03.535000Z] Finished checkout-sources step (success)
17:04:03     INFO - [mozharness: 2017-01-27 01:04:03.535000Z] Skipping setup-mock step.
17:04:03     INFO - [mozharness: 2017-01-27 01:04:03.535000Z] Running build step.
17:04:03     INFO - Running main action method: build
17:04:03     INFO - removing old packages...
17:04:03     INFO - There wasn't any old packages to remove.
17:04:03     INFO - Using in-tree mozconfig
17:04:03     INFO - Reading from file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\browser/config/mozconfigs/win32/debug
17:04:03     INFO - Contents:
17:04:03     INFO -  . "$topsrcdir/build/mozconfig.win-common"
17:04:03     INFO -  MOZ_AUTOMATION_L10N_CHECK=0
17:04:03     INFO -  . "$topsrcdir/browser/config/mozconfigs/common"
17:04:03     INFO -  
17:04:03     INFO -  ac_add_options --enable-debug
17:04:03     INFO -  ac_add_options --enable-dmd
17:04:03     INFO -  ac_add_options --enable-profiling  # needed for --enable-dmd to work on Windows
17:04:03     INFO -  ac_add_options --enable-verify-mar
17:04:03     INFO -  
17:04:03     INFO -  # Needed to enable breakpad in application.ini
17:04:03     INFO -  export MOZILLA_OFFICIAL=1
17:04:03     INFO -  
17:04:03     INFO -  # Enable Telemetry
17:04:03     INFO -  export MOZ_TELEMETRY_REPORTING=1
17:04:03     INFO -  
17:04:03     INFO -  . $topsrcdir/build/win32/mozconfig.vs-latest
17:04:03     INFO -  
17:04:03     INFO -  # Package js shell.
17:04:03     INFO -  export MOZ_PACKAGE_JSSHELL=1
17:04:03     INFO -  
17:04:03     INFO -  ac_add_options --with-branding=browser/branding/nightly
17:04:03     INFO -  
17:04:03     INFO -  . "$topsrcdir/build/mozconfig.common.override"
17:04:03     INFO -  . "$topsrcdir/build/mozconfig.cache"
17:04:03     INFO - mozconfig content:
17:04:03     INFO - . "$topsrcdir/build/mozconfig.win-common"
17:04:03     INFO - MOZ_AUTOMATION_L10N_CHECK=0
17:04:03     INFO - . "$topsrcdir/browser/config/mozconfigs/common"
17:04:03     INFO - 
17:04:03     INFO - ac_add_options --enable-debug
17:04:03     INFO - ac_add_options --enable-dmd
17:04:03     INFO - ac_add_options --enable-profiling  # needed for --enable-dmd to work on Windows
17:04:03     INFO - ac_add_options --enable-verify-mar
17:04:03     INFO - 
17:04:03     INFO - # Needed to enable breakpad in application.ini
17:04:03     INFO - export MOZILLA_OFFICIAL=1
17:04:03     INFO - 
17:04:03     INFO - # Enable Telemetry
17:04:03     INFO - export MOZ_TELEMETRY_REPORTING=1
17:04:03     INFO - 
17:04:03     INFO - . $topsrcdir/build/win32/mozconfig.vs-latest
17:04:03     INFO - 
17:04:03     INFO - # Package js shell.
17:04:03     INFO - export MOZ_PACKAGE_JSSHELL=1
17:04:03     INFO - 
17:04:03     INFO - ac_add_options --with-branding=browser/branding/nightly
17:04:03     INFO - 
17:04:03     INFO - . "$topsrcdir/build/mozconfig.common.override"
17:04:03     INFO - . "$topsrcdir/build/mozconfig.cache"
17:04:03     INFO - Copying c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\browser/config/mozconfigs/win32/debug to c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\.mozconfig
17:04:03     INFO - ['sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\tools\\scripts\\tooltool\\tooltool_wrapper.sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'https://api.pub.build.mozilla.org/tooltool/', 'setup.sh', 'c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', '/c/builds/tooltool_cache']
17:04:03     INFO - Running command: ['sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\tools\\scripts\\tooltool\\tooltool_wrapper.sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'https://api.pub.build.mozilla.org/tooltool/', 'setup.sh', 'c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', '/c/builds/tooltool_cache'] in c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src
17:04:03     INFO - Copy/paste: sh c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\tools\scripts\tooltool\tooltool_wrapper.sh c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\browser/config/tooltool-manifests/win32/releng.manifest https://api.pub.build.mozilla.org/tooltool/ setup.sh c:\mozilla-build\python27\python.exe C:/mozilla-build/tooltool.py -c /c/builds/tooltool_cache
17:04:03     INFO -  c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\src\browser/config/tooltool-manifests/win32/releng.manifest content
17:04:03     INFO -  =======================================================
17:04:03     INFO -  [
17:04:03     INFO -  {
17:04:03     INFO -  "size": 266240,
17:04:03     INFO -  "digest": "bb345b0e700ffab4d09436981f14b5de84da55a3f18a7f09ebc4364a4488acdeab8d46f447b12ac70f2da1444a68b8ce8b8675f0dae2ccf845e966d1df0f0869",
17:04:03     INFO -  "algorithm": "sha512",
17:04:03     INFO -  "filename": "mozmake.exe"
17:04:03     INFO -  },
17:04:03     INFO -  {
17:04:03     INFO -  "version": "rustc 1.14.0 (e8a012324 2016-12-16) repack",
17:04:03     INFO -  "size": 63983591,
17:04:03     INFO -  "digest": "7bd57c81e57a984a83de75dce214e344cee870bf7e1955ae3831f5a3d638fd4d2d1dca6d434a3264f98bc3b3f00dedb55d0b6d28afd9015d231e8a8f3075dea0",
17:04:03     INFO -  "algorithm": "sha512",
17:04:03     INFO -  "filename": "rustc.tar.bz2",
17:04:03     INFO -  "unpack": true
17:04:03     INFO -  },
17:04:03     INFO -  {
17:04:03     INFO -  "version": "sccache rev b21198a7183a2fe226ff49348b1c0b51bae9f4f8",
17:04:03     INFO -  "algorithm": "sha512",
17:04:03     INFO -  "visibility": "public",
17:04:03     INFO -  "filename": "sccache2.tar.bz2",
17:04:03     INFO -  "unpack": true,
17:04:03     INFO -  "digest": "7dee5c5602b3830cb8ac45ebaa8542714bbac0e50eabbff58a06972a02ceeab75ed7c56ff22a23f760b8317ae8e9a01cdecfaf75a7acbd2a4cdd817967170d2e",
17:04:03     INFO -  "size": 1179901
17:04:03     INFO -  },
17:04:03     INFO -  {
17:04:03     INFO -  "version": "Visual Studio 2015 Update 3 14.0.25425.01 / SDK 10.0.14393.0",
17:04:03     INFO -  "size": 326656969,
17:04:03     INFO -  "digest": "babc414ffc0457d27f5a1ed24a8e4873afbe2f1c1a4075469a27c005e1babc3b2a788f643f825efedff95b79686664c67ec4340ed535487168a3482e68559bc7",
17:04:03     INFO -  "algorithm": "sha512",
17:04:03     INFO -  "filename": "vs2015u3.zip",
17:04:03     INFO -  "unpack": true
17:04:03     INFO -  },
17:04:03     INFO -  {
17:04:03     INFO -  "version": "makecab rev d2bc6797648b7a834782714a55d339d2fd4e58c8",
17:04:03     INFO -  "algorithm": "sha512",
17:04:03     INFO -  "visibility": "public",
17:04:03     INFO -  "filename": "makecab.tar.bz2",
17:04:03     INFO -  "unpack": true,
17:04:03     INFO -  "digest": "196ac6a567c85559957dfe511c3d8654d23c94d5603259e19ccafe9d71e0e4ccee63ccc9a778f2699654b786cda54266108b7d4db543d01bb0b42545b4e6ec75",
17:04:03     INFO -  "size": 297118
17:04:03     INFO -  }
17:04:03     INFO -  ]
17:04:03     INFO -  =======================================================
17:04:03     INFO -  Fetching...
17:04:04     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:04:04     INFO -  INFO - File mozmake.exe retrieved from local cache c:/builds/tooltool_cache
17:04:04     INFO -  INFO - File rustc.tar.bz2 retrieved from local cache c:/builds/tooltool_cache
17:04:05     INFO -  INFO - File sccache2.tar.bz2 retrieved from local cache c:/builds/tooltool_cache
17:04:05     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:04:05     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:04:05     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:04:05     INFO -  INFO - File makecab.tar.bz2 retrieved from local cache c:/builds/tooltool_cache
17:04:05     INFO -  INFO - untarring "rustc.tar.bz2"
17:04:13     INFO -  INFO - untarring "sccache2.tar.bz2"
17:04:13     INFO -  INFO - untarring "makecab.tar.bz2"
17:04:13     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:04:43     INFO -  retry: Calling <function run_with_timeout at 0x02867C70> with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #2
17:04:43     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:04:44     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:04:44     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:04:44     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:04:44     INFO -  INFO - rm tree: rustc
17:04:44     INFO -  INFO - untarring "rustc.tar.bz2"
17:04:52     INFO -  INFO - rm tree: sccache2
17:04:52     INFO -  INFO - untarring "sccache2.tar.bz2"
17:04:52     INFO -  INFO - untarring "makecab.tar.bz2"
17:04:52     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:05:37     INFO -  retry: Calling <function run_with_timeout at 0x02867C70> with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #3
17:05:37     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:05:38     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:05:38     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:05:38     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:05:38     INFO -  INFO - rm tree: rustc
17:05:38     INFO -  INFO - untarring "rustc.tar.bz2"
17:05:45     INFO -  INFO - rm tree: sccache2
17:05:45     INFO -  INFO - untarring "sccache2.tar.bz2"
17:05:46     INFO -  INFO - untarring "makecab.tar.bz2"
17:05:46     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:06:54     INFO -  retry: Calling <function run_with_timeout at 0x02867C70> with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #4
17:06:54     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:06:55     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:06:55     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:06:55     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:06:55     INFO -  INFO - rm tree: rustc
17:06:55     INFO -  INFO - untarring "rustc.tar.bz2"
17:07:03     INFO -  INFO - rm tree: sccache2
17:07:03     INFO -  INFO - untarring "sccache2.tar.bz2"
17:07:03     INFO -  INFO - untarring "makecab.tar.bz2"
17:07:03     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:08:44     INFO -  retry: Calling <function run_with_timeout at 0x02867C70> with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #5
17:08:44     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:08:45     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:08:45     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:08:45     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:08:45     INFO -  INFO - rm tree: rustc
17:08:45     INFO -  INFO - untarring "rustc.tar.bz2"
17:08:53     INFO -  INFO - rm tree: sccache2
17:08:53     INFO -  INFO - untarring "sccache2.tar.bz2"
17:08:53     INFO -  INFO - untarring "makecab.tar.bz2"
17:08:53     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:11:25     INFO -  retry: Calling <function run_with_timeout at 0x02867C70> with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #6
17:11:25     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:11:26     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:11:26     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:11:26     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:11:26     INFO -  INFO - rm tree: rustc
17:11:26     INFO -  INFO - untarring "rustc.tar.bz2"
17:11:33     INFO -  INFO - rm tree: sccache2
17:11:33     INFO -  INFO - untarring "sccache2.tar.bz2"
17:11:34     INFO -  INFO - untarring "makecab.tar.bz2"
17:11:34     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:15:20     INFO -  retry: Calling <function run_with_timeout at 0x02867C70> with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #7
17:15:21     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:15:21     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:15:21     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:15:21     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:15:21     INFO -  INFO - rm tree: rustc
17:15:22     INFO -  INFO - untarring "rustc.tar.bz2"
17:15:29     INFO -  INFO - rm tree: sccache2
17:15:29     INFO -  INFO - untarring "sccache2.tar.bz2"
17:15:29     INFO -  INFO - untarring "makecab.tar.bz2"
17:15:29     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:20:29     INFO -  retry: Calling <function run_with_timeout at 0x02867C70> with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #8
17:20:29     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:20:30     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:20:30     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:20:30     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:20:30     INFO -  INFO - rm tree: rustc
17:20:30     INFO -  INFO - untarring "rustc.tar.bz2"
17:20:38     INFO -  INFO - rm tree: sccache2
17:20:38     INFO -  INFO - untarring "sccache2.tar.bz2"
17:20:38     INFO -  INFO - untarring "makecab.tar.bz2"
17:20:38     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:25:38     INFO -  retry: Calling <function run_with_timeout at 0x02867C70> with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #9
17:25:38     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:25:38     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:25:38     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:25:39     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:25:39     INFO -  INFO - rm tree: rustc
17:25:39     INFO -  INFO - untarring "rustc.tar.bz2"
17:25:46     INFO -  INFO - rm tree: sccache2
17:25:46     INFO -  INFO - untarring "sccache2.tar.bz2"
17:25:47     INFO -  INFO - untarring "makecab.tar.bz2"
17:25:47     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:30:47     INFO -  retry: Calling <function run_with_timeout at 0x02867C70> with args: (['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'], 300, None, None, False, True), kwargs: {}, attempt #10
17:30:47     INFO -  Executing: ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache']
17:30:47     INFO -  INFO - File vs2015u3.zip not present in local cache folder c:/builds/tooltool_cache
17:30:47     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:30:48     INFO -  INFO - ...failed to fetch 'vs2015u3.zip' from https://api.pub.build.mozilla.org/tooltool/
17:30:48     INFO -  INFO - rm tree: rustc
17:30:48     INFO -  INFO - untarring "rustc.tar.bz2"
17:30:55     INFO -  INFO - rm tree: sccache2
17:30:55     INFO -  INFO - untarring "sccache2.tar.bz2"
17:30:55     INFO -  INFO - untarring "makecab.tar.bz2"
17:30:56     INFO -  ERROR - The following files failed: 'vs2015u3.zip'
17:30:56     INFO -  retry: Giving up on <function run_with_timeout at 0x02867C70>
17:30:56     INFO -  Unable to successfully run ['c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', 'c:/builds/tooltool_cache', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--overwrite', '-m', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'fetch', '-c', 'c:/builds/tooltool_cache'] after 10 attempts
17:30:56    ERROR - Return code: 1
17:30:56    ERROR - 1 not in success codes: [0]
17:30:56  WARNING - setting return code to 2
17:30:56    FATAL - Halting on failure while running ['sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\tools\\scripts\\tooltool\\tooltool_wrapper.sh', 'c:\\builds\\moz2_slave\\m-in-w32-d-0000000000000000000\\build\\src\\browser/config/tooltool-manifests/win32/releng.manifest', 'https://api.pub.build.mozilla.org/tooltool/', 'setup.sh', 'c:\\mozilla-build\\python27\\python.exe', 'C:/mozilla-build/tooltool.py', '-c', '/c/builds/tooltool_cache']
17:30:56    FATAL - Running post_fatal callback...
17:30:56    FATAL - Exiting 2
17:30:56     INFO - [mozharness: 2017-01-27 01:30:56.268000Z] Finished build step (failed)
17:30:56     INFO - Running post-run listener: _summarize
17:30:56    ERROR - # TBPL FAILURE #
17:30:56     INFO - [mozharness: 2017-01-27 01:30:56.268000Z] FxDesktopBuild summary:
17:30:56    ERROR - # TBPL FAILURE #
17:30:56     INFO - Running post-run listener: copy_logs_to_upload_dir
17:30:56     INFO - Copying logs to upload dir...
17:30:56     INFO - mkdir: c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\upload\logs
program finished with exit code 2
elapsedTime=2614.178000
========= master_lag: 0.07 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 43 mins, 34 secs) (at 2017-01-26 17:30:58.671399) =========

========= Started set props: got_revision MozReview-Commit-ID comments (results: 0, elapsed: 0 secs) (at 2017-01-26 17:30:58.683115) =========
'bash' '-c' 'for file in `ls -1`; do cat $file; done'
 in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\properties (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv
  BUILDBOT_PATH=C:\mozilla-build\buildbotve
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
  COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
  COMMONPROGRAMW6432=C:\Program Files\Common Files
  COMPUTERNAME=B-2008-SPOT-101
  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-101
  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=3288
  SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016
  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-101
  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
comments:Merge m-c to inbound, a=merge

MozReview-Commit-ID: 5CIctUOTFma
got_revision:d9a53886e7942d3c13760dadce618cb4e3595e66
program finished with exit code 0
elapsedTime=0.107000
got_revision: 'd9a53886e7942d3c13760dadce618cb4e3595e66'
MozReview-Commit-ID: '5CIctUOTFma'
comments: 'Merge m-c to inbound, a=merge'
========= master_lag: 0.01 =========
========= Finished set props: got_revision MozReview-Commit-ID comments (results: 0, elapsed: 0 secs) (at 2017-01-26 17:30:58.798789) =========

========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-26 17:30:58.799702) =========
'rm' '-f' 'oauth.txt'
 in dir c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-f', 'oauth.txt']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  APR_ICONV_PATH=c:/mozilla-build/svn-win32-1.6.3/iconv
  BUILDBOT_PATH=C:\mozilla-build\buildbotve
  CHOCOLATEYINSTALL=C:\ProgramData\chocolatey
  COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files
  COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
  COMMONPROGRAMW6432=C:\Program Files\Common Files
  COMPUTERNAME=B-2008-SPOT-101
  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-101
  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=3288
  SSH_AUTH_SOCK=C:/Users/cltbld/AppData/Local/Temp/ssh-bjKyCp4016/agent.4016
  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-101
  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-01-26 17:30:58.914517) =========

========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2017-01-26 17:30:58.915424) =========

========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2017-01-26 17:30:59.191584) =========

========= Total master_lag: 0.18 =========