user-data powershell script fails for unknown reason

asked 2019-01-15 12:44:21 +0300

fblankendaal
1 ●1 ●1 ●2

Hi Guys,

i am running into a situation that my system is trying to run the script which is provided through the user-data field. i can see that the script is in the log file, and that its beeing placed onto the system.

if i run the script ( which i copied from the cloudinit.log ) locally to a file and then run it, all is fine, no problems what so ever (so the script is fine) but when the cloudinit runs it, it fails every time.

i also believe this is due to the fact that cloudinit-unattended is rebooting the system to set the hostname.

Here is a part of the log (verbose and debug are true)

2019-01-15 10:14:59.827 1844 INFO cloudbaseinit.init [-] Executing plugin 'UserDataPlugin'
2019-01-15 10:14:59.827 1844 DEBUG cloudbaseinit.metadata.services.base [-] Getting metadata from: http://ipaddress/latest/user-data _http_request c:\program files\cloudbase solutions\cloudbase-init\python\lib\site-packages\cloudbaseinit\metadata\services\base.py:255
2019-01-15 10:14:59.842 1844 DEBUG cloudbaseinit.plugins.common.userdata [-] User data content length: 3716 execute c:\program files\cloudbase solutions\cloudbase-init\python\lib\site-packages\cloudbaseinit\plugins\common\userdata.py:49
2019-01-15 10:14:59.842 1844 DEBUG cloudbaseinit.plugins.common.userdata [-] Processing userdata _process_user_data c:\program files\cloudbase solutions\cloudbase-init\python\lib\site-packages\cloudbaseinit\plugins\common\userdata.py:108
2019-01-15 10:14:59.842 1844 DEBUG cloudbaseinit.utils.classloader [-] Loading class 'cloudbaseinit.plugins.common.userdataplugins.parthandler.PartHandlerPlugin' load_class c:\program files\cloudbase solutions\cloudbase-init\python\lib\site-packages\cloudbaseinit\utils\classloader.py:27
2019-01-15 10:14:59.842 1844 DEBUG cloudbaseinit.utils.classloader [-] Loading class 'cloudbaseinit.plugins.common.userdataplugins.cloudconfig.CloudConfigPlugin' load_class c:\program files\cloudbase solutions\cloudbase-init\python\lib\site-packages\cloudbaseinit\utils\classloader.py:27
2019-01-15 10:14:59.951 1844 DEBUG cloudbaseinit.utils.classloader [-] Loading class 'cloudbaseinit.plugins.common.userdataplugins.cloudboothook.CloudBootHookPlugin' load_class c:\program files\cloudbase solutions\cloudbase-init\python\lib\site-packages\cloudbaseinit\utils\classloader.py:27
2019-01-15 10:14:59.967 1844 DEBUG cloudbaseinit.utils.classloader [-] Loading class 'cloudbaseinit.plugins.common.userdataplugins.shellscript.ShellScriptPlugin' load_class c:\program files\cloudbase solutions\cloudbase-init\python\lib\site-packages\cloudbaseinit\utils\classloader.py:27
2019-01-15 10:14:59.967 1844 DEBUG cloudbaseinit.utils.classloader [-] Loading class 'cloudbaseinit.plugins.common.userdataplugins.multipartmixed.MultipartMixedPlugin' load_class c:\program files\cloudbase solutions\cloudbase-init\python\lib\site-packages\cloudbaseinit\utils\classloader.py:27
2019-01-15 10:14:59.967 1844 DEBUG cloudbaseinit.utils.classloader [-] Loading class 'cloudbaseinit.plugins.common.userdataplugins.heat.HeatPlugin' load_class c:\program files\cloudbase solutions\cloudbase-init\python\lib\site-packages\cloudbaseinit\utils\classloader.py:27
2019-01-15 10:14:59.967 1844 DEBUG cloudbaseinit.plugins.common.userdata [-] User data content:
Content-Type: multipart/mixed; boundary="MIMEBOUNDARY"
MIME-Version: 1.0

--MIMEBOUNDARY
Content-Disposition: attachment; filename="cloudinit.ps1"
Content-Transfer-Encoding: 7bit
Content-Type: text/x-shellscript
Mime-Version: 1.0

#ps1_sysnative

$chefversion = 'xxx'
$chefenvironment = 'xxx'
$vaulttoken = 'xxx'
$validationcert = 'xxx'
$nodename = ($env:COMPUTERNAME).ToLower()

Start-Transcript -Path $env:SystemRoot\temp\transscript.log
do {
  Write-Host "waiting..."
  sleep 3
} until(Test-NetConnection omnitruck.chef.io -Port 443 | ? { $_.TcpTestSucceeded } )
write-output "ik wil lopen" | out-file "c:\bla.txt ...

(more)

edit retag flag offensive close merge delete

add a comment

2 answers

Sort by » oldest newest most voted

answered 2019-01-15 15:32:26 +0300

fblankendaal
1 ●1 ●1 ●2

updated 2019-01-15 16:27:59 +0300

Sure, thank you for your response

here is my unattended

[DEFAULT]

logdir=C:\Program Files\Cloudbase Solutions\Cloudbase-Init\log\

logfile=cloudbase-init-unattend.log

verbose=true

debug=true

metadata_services=cloudbaseinit.metadata.services.cloudstack.CloudStack, cloudbaseinit.metadata.services.ec2service.EC2Service plugins=cloudbaseinit.plugins.common.sethostname.SetHostNamePlugin

allow_reboot=false

stopserviceon_exit=false

checklatestversion=false

here is my normal

[DEFAULT]

username=Administrator

injectuserpassword=true

firstlogonbehaviour=no

verbose=true

debug=true

logdir=C:\Program Files\Cloudbase Solutions\Cloudbase-Init\log\

logfile=cloudbase-init.log

metadata_services=cloudbaseinit.metadata.services.cloudstack.CloudStack, cloudbaseinit.metadata.services.ec2service.EC2Service plugins=cloudbaseinit.plugins.common.setuserpassword.SetUserPasswordPlugin, cloudbaseinit.plugins.common.userdata.UserDataPlugin

allow_reboot=false

stopserviceon_exit=true

checklatestversion=false

i did some more digging in the windows setup logs, and now found indeed the reason, that it fails due to the reboot of the cloudinint sethostname

2019-01-15 14:12:08, Info [SETUPUGC.EXE] SetupUGC launched with command line [specialize] 2019-01-15 14:12:08, Info [SETUPUGC.EXE] SetupUGC running to process unattend settings for pass [specialize] 2019-01-15 14:12:08, Info [SETUPUGC.EXE] Processing any RunSynchronous setting... 2019-01-15 14:12:08, Info [SETUPUGC.EXE] Running commands is not yet in progress 2019-01-15 14:12:08, Info [0x090008] PANTHR CBlackboard::Open: C:\Windows\panther\commandexec\CommandExec succeeded. 2019-01-15 14:12:08, Info [SETUPUGC.EXE] Found run command in registry: description = [Run Cloudbase-Init to set the hostname] 2019-01-15 14:12:08, Info [SETUPUGC.EXE] Found run command in registry: credentials = { domain is not specified, username is not specified, password info not logged } 2019-01-15 14:12:08, Info [SETUPUGC.EXE] Found run command in registry: will observe the exit code's implied request after execution 2019-01-15 14:12:08, Info [SETUPUGC.EXE] Found run command in registry: path = [cmd.exe /c ""C:\Program Files\Cloudbase Solutions\Cloudbase-Init\Python\Scripts\cloudbase-init.exe" --config-file "C:\Program Files\Cloudbase Solutions\Cloudbase-Init\conf\cloudbase-init-unattend.conf" && exit 1 || exit 2"] 2019-01-15 14:12:08, Info [SETUPUGC.EXE] Found RunSynchronous command: [cmd.exe /c ""C:\Program Files\Cloudbase Solutions\Cloudbase-Init\Python\Scripts\cloudbase-init.exe" --config-file "C:\Program Files\Cloudbase Solutions\Cloudbase-Init\conf\cloudbase-init-unattend.conf" && exit 1 || exit 2"]

2019-01-15 14:12:30, Info [SETUPUGC.EXE] Finished executing [cmd.exe /c ""C:\Program Files\Cloudbase Solutions\Cloudbase-Init\Python\Scripts\cloudbase-init.exe" --config-file "C:\Program Files\Cloudbase Solutions\Cloudbase-Init\conf\cloudbase-init-unattend.conf" && exit 1 || exit 2"] synchronously

2019-01-15 14:12:30, Info [SETUPUGC.EXE] Process returned with exit code 0x1 2019-01-15 14:12:30, Info [SETUPUGC.EXE] Must reboot now. 2019-01-15 14:12:30, Info [0x090009] PANTHR CBlackboard::Close: c:\windows\panther\commandexec\commandexec. 2019-01-15 14:12:30, Info [SETUPUGC.EXE] A processor needs an immediate reboot; requesting a reboot-and-recall for SetupUGC 2019-01-15 14:12:30, Info [SETUPUGC.EXE] SetupUGC returning with exit code [3]

this matches the exact same time as the failure from the cloudinit log.

2019-01-15 14:12:30.717 1848 DEBUG cloudbaseinit.plugins.common.fileexecutils [-] Userdata stdout: b'' execfile c:\program files\cloudbase solutions\cloudbase-init\python\lib ... (more)

edit flag offensive delete link

Comments

I ended up doing the following. 1. Install the cloudbase-init, and disable the service. 2. adjust the unatteded.xml file, by running cloudbase-init unatteded manually ( like advised ) 3. adjust the unatteded.xml file. by setting the cloudbase-init service to automatic. next time it boots it works.

fblankendaal ( 2019-01-31 23:14:00 +0300 )edit

add a comment