· 6 years ago · Oct 31, 2019, 05:06 PM
12019-10-23 04:35:11+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
22019-10-23 04:35:11+00:00 :: ISOHelper:getCDROMDrive() :: Identified a CDROM ISO based drive as : D:\
32019-10-23 04:35:16+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
4 at System.Net.HttpWebRequest.GetResponse()
5 at nvcloudinit.nvcloudinit.isAWSEnv()
62019-10-23 04:35:16+00:00 :: nvcloudinit:isAVAEnv() :: Exception trying to query XenStore :System.Management.ManagementException: Not found
7 at System.Management.ManagementException.ThrowWithExtendedInfo(ManagementStatus errorCode)
8 at System.Management.ManagementObject.Initialize(Boolean getObject)
9 at System.Management.ManagementBaseObject.get_ClassName()
10 at System.Management.ManagementClass.GetInstances(EnumerationOptions options)
11 at System.Management.ManagementClass.GetInstances()
12 at nvcloudinit.XenStoreSession..ctor()
13 at nvcloudinit.nvcloudinit.isAVAEnv()
142019-10-23 04:35:16+00:00 :: nvcloudinit:OnStart() :: No http endpoint found but found ISO with no metadata file , so assuming physical host and not generating new metadata/userdata files
152019-10-23 04:35:16+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
162019-10-23 04:35:16+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [c:\asgard\conf\userdata.txt]
172019-10-23 04:35:16+00:00 :: UserDataHelper:GetUserData() :: Failed getting userdata file.
182019-10-23 04:35:16+00:00 :: UserDataHelper:IsMIMESection() :: Failed checking if [disk-network-info] is a MIME section in userdata.
192019-10-23 04:35:16+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
202019-10-23 04:35:16+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
212019-10-23 04:35:16+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
222019-10-23 04:35:16+00:00 :: RegistryWriter:FlipDeadGWRegistry() :: Setting regkey for @DeadGWDetectDefault
232019-10-23 04:35:16+00:00 :: NetworkHelper:disableDeadGWDetection() :: Succesfully disabled dead gateway in the network
242019-10-23 04:35:16+00:00 :: NetworkHelper:disableNetBt() :: Ensuring TcpipNetbiosOptions is set to: 2
252019-10-23 04:35:21+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
26 at System.Net.HttpWebRequest.GetResponse()
27 at nvcloudinit.nvcloudinit.isAWSEnv()
282019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Setting secondary IP based on interface name....
292019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Name: Ethernet
302019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Description: Red Hat VirtIO Ethernet Adapter
312019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: ===============================
322019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Interface type: Ethernet
332019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Operational status: Up
342019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: MAC: 525400123456
352019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Gateways:
362019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: 10.0.2.2
372019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: IP Addresses:
382019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: fe80::d9c:b69b:81a9:b109%12 0.0.0.0
392019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: 10.0.2.15 255.255.255.0
402019-10-23 04:35:21+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Adapter has DHCP Ip allocation config, so cannot process secondary Ips
412019-10-23 04:35:21+00:00 :: ActivationHelper:getLicenseStatus() :: Determining Windows licensing details and status
422019-10-23 04:35:22+00:00 :: ActivationHelper:getLicenseStatus() :: Windows(R), ServerStandardEval edition: Windows(R) Operating System, TIMEBASED_EVAL channel
432019-10-23 04:35:22+00:00 :: ActivationHelper:getLicenseStatus() :: License status: Licensed
442019-10-23 04:35:22+00:00 :: ActivationHelper:getLicenseStatus() :: Grace period remaining: 118d 17h
452019-10-23 04:35:22+00:00 :: ActivationHelper:getLicenseStatus() :: Name:
462019-10-23 04:35:22+00:00 :: ActivationHelper:getLicenseStatus() :: Port:0
472019-10-23 04:35:22+00:00 :: nvcloudinit:OnStart() :: STATUS : Windows is already activated
482019-10-23 04:35:22+00:00 :: nvcloudinit:OnStart() :: No domain specified in userdata, so no action for joining the domain
492019-10-23 04:35:22+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputdiskList.txt]
502019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
512019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
522019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
532019-10-23 04:35:29+00:00 :: DiskInitializationHelper:initializeDisk() :: Checking Disk 0 ...
542019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputpartitionList.txt]
552019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
562019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
572019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
582019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() :: -------------------Logging diskpart partion output -------------------
592019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() ::
602019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() :: Microsoft DiskPart version 6.3.9600
612019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() ::
622019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() :: Copyright (C) 1999-2013 Microsoft Corporation.
632019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() :: On computer: BASEGAME-NGN-WK
642019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() ::
652019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() :: Disk 0 is now the selected disk.
662019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() ::
672019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() :: Partition ### Type Size Offset
682019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() :: ------------- ---------------- ------- -------
692019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() :: Partition 1 Primary 2000 MB 1024 KB
702019-10-23 04:35:29+00:00 :: DiskInitializationHelper:checkPartition() :: --------------------------------------------------------------------
712019-10-23 04:35:29+00:00 :: DiskInitializationHelper:initializeDisk() :: Disk 0 allready initialized, skipping disk
722019-10-23 04:35:29+00:00 :: nvcloudinit:OnStart() :: Completed initializing disk
732019-10-23 04:35:29+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Skipping extend for volume 'A'
742019-10-23 04:35:29+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Extending volume 'C' on unallocated space
752019-10-23 04:35:29+00:00 :: DiskInitializationHelper:extendVolume() :: Extending volume 'C'
762019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt]
772019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT : Microsoft DiskPart version 6.3.9600
78
79Copyright (C) 1999-2013 Microsoft Corporation.
80On computer: BASEGAME-NGN-WK
81
82Volume 2 is the selected volume.
83
84DiskPart successfully extended the volume.
852019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
862019-10-23 04:35:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
872019-10-23 04:35:29+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Skipping extend for volume 'D'
882019-10-23 04:35:29+00:00 :: nvcloudinit:OnStart() :: Completed extending volumes
892019-10-23 04:35:29+00:00 :: InitHelper:GetInitScriptList() :: List of init scripts to execute in the order below :
902019-10-23 04:35:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat
912019-10-23 04:35:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat
922019-10-23 04:35:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat
932019-10-23 04:35:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat
942019-10-23 04:35:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat
952019-10-23 04:35:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat
962019-10-23 04:35:29+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat'
972019-10-23 04:35:31+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
98c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
99REM --- Registering instance and generating CD config file
100
101c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe C:\Asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py || GOTO :ERROR
1022019-10-23 04:35:31,092 DEBUG __main__ - Attempting to acquire lock at: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
1032019-10-23 04:35:31,092 DEBUG __main__ - Lock acquired: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
1042019-10-23 04:35:31,092 INFO __main__ - Querying the CS for my CD props
1052019-10-23 04:35:31,092 DEBUG asgard_util_local.local - Reading file contents from: C:\Asgard\conf\userdata.txt
1062019-10-23 04:35:31,092 DEBUG asgard_util_local.local - WARNING: Returning none for get_file_contents of C:\Asgard\conf\userdata.txt, because the file doesn't exist
1072019-10-23 04:35:31,092 DEBUG asgard_util_local.local - Reading file contents from: C:\Asgard\conf\userdata.txt
1082019-10-23 04:35:31,092 DEBUG asgard_util_local.local - WARNING: Returning none for get_file_contents of C:\Asgard\conf\userdata.txt, because the file doesn't exist
1092019-10-23 04:35:31,092 INFO __main__ - Successfully deleted C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
1102019-10-23 04:35:31,092 ERROR __main__ - Unhandled top level exception
111Traceback (most recent call last):
112 File "C:\Asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 591, in <module>
113 rc = main(sub_name, new_cs_data_file_path)
114 File "C:\Asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 513, in main
115 cd_props = get_my_cd_props()
116 File "C:\Asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 126, in get_my_cd_props
117 new_mappings = Local.get_codedeploy_mappings(user="code_deploy") or {}
118 File "c:\asgard\framework-python\asgard_util_local\local.py", line 284, in get_codedeploy_mappings
119 return Local.read_from_cs(user, "vm_mapping_aws_codedeploy", throw_on_missing_root_key=False, version=version)
120 File "c:\asgard\framework-python\asgard_util_local\local.py", line 112, in read_from_cs
121 return Local._read_from_cs(path_to_get, cs_creds=cs_creds, cs_users=[cs_user], version=version, throw_on_missing_root_key=throw_on_missing_root_key)
122 File "c:\asgard\framework-python\asgard_util_local\local.py", line 121, in _read_from_cs
123 raise Exception("Config service credentials could not be found")
124Exception: Config service credentials could not be found
1252019-10-23 04:35:31,092 INFO __main__ - ngn_codedeploy_register exiting with RC -1
126REM --- ERROR: Failed to configure or start the CodeDeploy-Agent. start.bat exiting with RC 1
127
1282019-10-23 04:35:31+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
1292019-10-23 04:35:31+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' : 1
1302019-10-23 04:35:31+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat'
1312019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' :
132c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
133
134c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_DIR=C:\Asgard\conf\ZabbixAgent
135
136c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_TARGET_FILE=C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
137
138c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_TARGET_DIR=C:\Asgard\services\ZabbixAgent
139
140c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SCRIPT=C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py
141
142c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SRC_FILE=C:\Asgard\services\ZabbixAgent\zabbix_agentd.conf
143
144c:\asgard\nvcloudinit\scripts\per-boot>REM --- Generate the config file
145
146c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py --input-template C:\Asgard\services\ZabbixAgent\zabbix_agentd.conf --output-template C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
147Creating logger...
1482019-10-23 04:35:31,624 INFO __main__ - Starting
1492019-10-23 04:35:32,686 INFO __main__ - Detected that this VM is hosted in NGN
1502019-10-23 04:35:32,686 ERROR __main__ - Unhandled top level exception
151Traceback (most recent call last):
152 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 493, in <module>
153 rc = main(options.input_template, options.output_template)
154 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 434, in main
155 config_vals['[[HOSTNAME]]'] = get_zabbix_hostname()
156 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 418, in get_zabbix_hostname
157 (machine_type, ip) = get_machine_type_and_primary_ip()
158 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 365, in get_machine_type_and_primary_ip
159 vm_type_info = read_vm_type_info()
160 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 257, in read_vm_type_info
161 vm_type_from_userdata = read_userdata_section('vm-type-info')
162 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 233, in read_userdata_section
163 raise IOError("Missing required dependency {}".format(path_to_userdata))
164IOError: Missing required dependency C:\Asgard\conf\userdata.txt
1652019-10-23 04:35:32,686 INFO __main__ - Done, returning -1
166
167c:\asgard\nvcloudinit\scripts\per-boot>SET RC=-1
168"ERROR: Failed to populate config file"
169
1702019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : c:\python27\lib\site-packages\setuptools-22.0.0-py2.7.egg\pkg_resources\__init__.py:1268: UserWarning: C:\Windows\system32\config\systemprofile\AppData\Roaming\Python-Eggs is writable by group/others and vulnerable to attack when used with get_resource_filename. Consider a more secure location (set with .set_extraction_path or the PYTHON_EGG_CACHE environment variable).
171
1722019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : -1
1732019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat'
1742019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : "common_env_on_boot.bat starting"
175"common_env_on_boot.bat calling C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py""
176
177c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py" || GOTO :SHOWERR
178setup_environment starting ...
179Detected platform:
180 Windows ......... : True
181 Strict Windows OS : True
182 Cygwin .......... : False
183 Posix ........... : False
184 Linux ........... : False
185Checking file: c:\asgard\services\CommonEnvironment\resources\windows\ag_env.bat ...
186All required files validated
187Setting up common environment ...
188Reading SDB value from zone property cache
189WARNING: Failed to lookup AG_STATEDB from subnet-info and ZoneProperties. Leaving any currently set value for AG_STATEDB intact
190Creating common directories ...
191c:\asgard already exists; skipping
192c:\asgard\logs already exists; skipping
193c:\asgard\services already exists; skipping
194c:\asgard\conf already exists; skipping
195Saving environment ({'AG_HOME': 'c:\\asgard', 'AG_LOGS': 'c:\\asgard\\logs'}) to: c:\asgard\conf\asgard_env.bat ...
196Wrote environment setup file: c:\asgard\conf\asgard_env.bat
197A reboot is required to complete setup
198---- ERROR: "c:\asgard\services\CommonEnvironment\bin\setup_environment.py" returned 212
199
2002019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' :
2012019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : 212
2022019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat'
2032019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : "Setting up Disk and Network based on userdata"
204"Executing C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py"
205
206c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py || GOTO FAIL
207
208c:\asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
209---- Successfully configured Disk and Network on the host
210
2112019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 2019-10-23 04:35:32,921 - disk-network-init - ERROR - No disk or network related config found in userdata, skipping initialization
212Traceback (most recent call last):
213 File "c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py", line 126, in <module>
214 disk_network_info = get_subset_mime(USERDATA_FILE, "disk-network-info")
215 File "c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py", line 18, in get_subset_mime
216 msg = email.message_from_file(open(userdata))
217IOError: [Errno 2] No such file or directory: 'c:\\asgard\\conf\\userdata.txt'
218
2192019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 0
2202019-10-23 04:35:32+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat'
2212019-10-23 04:35:34+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
222c:\asgard\nvcloudinit\scripts\per-boot>REM
223
224c:\asgard\nvcloudinit\scripts\per-boot>REM Enables the Asgard OSL LogPruner
225
226c:\asgard\nvcloudinit\scripts\per-boot>REM
227
228c:\asgard\nvcloudinit\scripts\per-boot>SET PYTHON_PATH=C:\Python27\python.exe
229
230c:\asgard\nvcloudinit\scripts\per-boot>IF EXIST C:\Python27\python.exe GOTO FOUND_PYTHON
231
232c:\asgard\nvcloudinit\scripts\per-boot>IF NOT "c:\asgard" == "" GOTO SCHEDULE
233
234c:\asgard\nvcloudinit\scripts\per-boot>SET INSTALL_DIR=c:\asgard\services\LogPruner
235
236c:\asgard\nvcloudinit\scripts\per-boot>SET TASK_NAME="Asgard OSL LogPruner"
237
238c:\asgard\nvcloudinit\scripts\per-boot>REM Create the task
239
240c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Query /TN "Asgard OSL LogPruner" || GOTO CREATE_TASK
241
242c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Create /RU SYSTEM /ST 00:00 /SC HOURLY /MO 2 /TN "Asgard OSL LogPruner" /TR "\"C:\Python27\python.exe\" c:\asgard\services\LogPruner\prunelogs_win.py 2>&1" || GOTO :EOF
243SUCCESS: The scheduled task "Asgard OSL LogPruner" has successfully been created.
244
2452019-10-23 04:35:34+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' : ERROR: The system cannot find the file specified.
246
247
2482019-10-23 04:35:34+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' : 0
2492019-10-23 04:35:34+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat'
2502019-10-23 04:35:35+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' :
251c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py
252****** Executing command : net stop nxlog
253****** Return code of the command: 0
254****** Stdout of the execution :
255
256
257The nxlog service was stopped successfully.
258
259
260
261
262c:\asgard
263Falling back to see if nvcloudinit based config can be read to get update server location
264
2652019-10-23 04:35:35+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : Traceback (most recent call last):
266 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 147, in <module>
267 log_address = getLogServer()
268 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 44, in getLogServer
269 log_server = getUserdataValue("LoggingServerAddresses")
270 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 112, in getUserdataValue
271 print str(traceback.print_exc())
272NameError: global name 'traceback' is not defined
273
2742019-10-23 04:35:35+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : 1
2752019-10-23 04:35:35+00:00 :: InitHelper:ProcessInitScripts() :: Completed processing/executing init scripts found in the system
2762019-10-23 04:35:35+00:00 :: RegistryWriter:WriteNVCRegistry() :: Setting regkey for nvcloudinit @Execution
2772019-10-23 04:35:35+00:00 :: nvcloudinit:OnStart() :: NVCloudInit stopping
2782019-10-23 04:35:35+00:00 :: nvcloudinit:OnStop() :: Service Stopping
2792019-10-24 07:56:16+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
2802019-10-24 07:56:22+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
281 at System.Net.HttpWebRequest.GetResponse()
282 at nvcloudinit.nvcloudinit.isAWSEnv()
2832019-10-24 07:56:22+00:00 :: nvcloudinit:isAVAEnv() :: Exception trying to query XenStore :System.Management.ManagementException: Not found
284 at System.Management.ManagementException.ThrowWithExtendedInfo(ManagementStatus errorCode)
285 at System.Management.ManagementObject.Initialize(Boolean getObject)
286 at System.Management.ManagementBaseObject.get_ClassName()
287 at System.Management.ManagementClass.GetInstances(EnumerationOptions options)
288 at System.Management.ManagementClass.GetInstances()
289 at nvcloudinit.XenStoreSession..ctor()
290 at nvcloudinit.nvcloudinit.isAVAEnv()
2912019-10-24 07:56:22+00:00 :: nvcloudinit:OnStart() :: No http or ISO endpoint found , so assuming physical host and not generating new metadata/userdata files
2922019-10-24 07:56:22+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
2932019-10-24 07:56:22+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [c:\asgard\conf\userdata.txt]
2942019-10-24 07:56:22+00:00 :: UserDataHelper:GetUserData() :: Failed getting userdata file.
2952019-10-24 07:56:22+00:00 :: UserDataHelper:IsMIMESection() :: Failed checking if [disk-network-info] is a MIME section in userdata.
2962019-10-24 07:56:22+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
2972019-10-24 07:56:22+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
2982019-10-24 07:56:22+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
2992019-10-24 07:56:27+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
300 at System.Net.HttpWebRequest.GetResponse()
301 at nvcloudinit.nvcloudinit.isAWSEnv()
3022019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Setting secondary IP based on interface name....
3032019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Name: Ethernet 2
3042019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Description: Intel(R) PRO/1000 MT Network Connection
3052019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: =======================================
3062019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Interface type: Ethernet
3072019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Operational status: Up
3082019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: MAC: 5254004E573D
3092019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Gateways:
3102019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: 192.168.122.1
3112019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: IP Addresses:
3122019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: fe80::f575:804b:60a6:90da%14 0.0.0.0
3132019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: 192.168.122.40 255.255.255.0
3142019-10-24 07:56:27+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Adapter has DHCP Ip allocation config, so cannot process secondary Ips
3152019-10-24 07:56:27+00:00 :: ActivationHelper:getLicenseStatus() :: Determining Windows licensing details and status
3162019-10-24 07:56:28+00:00 :: ActivationHelper:getLicenseStatus() :: Windows(R), ServerStandardEval edition: Windows(R) Operating System, TIMEBASED_EVAL channel
3172019-10-24 07:56:28+00:00 :: ActivationHelper:getLicenseStatus() :: License status: Licensed
3182019-10-24 07:56:28+00:00 :: ActivationHelper:getLicenseStatus() :: Grace period remaining: 117d 1h
3192019-10-24 07:56:28+00:00 :: ActivationHelper:getLicenseStatus() :: Name:
3202019-10-24 07:56:28+00:00 :: ActivationHelper:getLicenseStatus() :: Port:0
3212019-10-24 07:56:28+00:00 :: nvcloudinit:OnStart() :: STATUS : Windows is already activated
3222019-10-24 07:56:28+00:00 :: nvcloudinit:OnStart() :: No domain specified in userdata, so no action for joining the domain
3232019-10-24 07:56:28+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputdiskList.txt]
3242019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
3252019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
3262019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
3272019-10-24 07:56:29+00:00 :: DiskInitializationHelper:initializeDisk() :: Checking Disk 0 ...
3282019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputpartitionList.txt]
3292019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
3302019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
3312019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
3322019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() :: -------------------Logging diskpart partion output -------------------
3332019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() ::
3342019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() :: Microsoft DiskPart version 6.3.9600
3352019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() ::
3362019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() :: Copyright (C) 1999-2013 Microsoft Corporation.
3372019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() :: On computer: BASEGAME-NGN-WK
3382019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() ::
3392019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() :: Disk 0 is now the selected disk.
3402019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() ::
3412019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() :: Partition ### Type Size Offset
3422019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() :: ------------- ---------------- ------- -------
3432019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() :: Partition 1 Primary 2000 MB 1024 KB
3442019-10-24 07:56:29+00:00 :: DiskInitializationHelper:checkPartition() :: --------------------------------------------------------------------
3452019-10-24 07:56:29+00:00 :: DiskInitializationHelper:initializeDisk() :: Disk 0 allready initialized, skipping disk
3462019-10-24 07:56:29+00:00 :: nvcloudinit:OnStart() :: Completed initializing disk
3472019-10-24 07:56:29+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Extending volume 'C' on unallocated space
3482019-10-24 07:56:29+00:00 :: DiskInitializationHelper:extendVolume() :: Extending volume 'C'
3492019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt]
3502019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT : Microsoft DiskPart version 6.3.9600
351
352Copyright (C) 1999-2013 Microsoft Corporation.
353On computer: BASEGAME-NGN-WK
354
355Volume 1 is the selected volume.
356
357There is not enough usable free space on specified disk(s) to extend the
358volume.
3592019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
3602019-10-24 07:56:29+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : -2147024809
3612019-10-24 07:56:29+00:00 :: nvcloudinit:OnStart() :: Completed extending volumes
3622019-10-24 07:56:29+00:00 :: InitHelper:GetInitScriptList() :: List of init scripts to execute in the order below :
3632019-10-24 07:56:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat
3642019-10-24 07:56:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat
3652019-10-24 07:56:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat
3662019-10-24 07:56:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat
3672019-10-24 07:56:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat
3682019-10-24 07:56:29+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat
3692019-10-24 07:56:29+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat'
3702019-10-24 07:56:31+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
371c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
372REM --- Registering instance and generating CD config file
373
374c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py || GOTO :ERROR
3752019-10-24 19:56:31,433 DEBUG __main__ - Attempting to acquire lock at: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
3762019-10-24 19:56:31,433 DEBUG __main__ - Lock acquired: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
3772019-10-24 19:56:31,433 INFO __main__ - Querying the CS for my CD props
3782019-10-24 19:56:31,433 DEBUG asgard_util_local.local - Reading file contents from: c:\asgard\conf\userdata.txt
3792019-10-24 19:56:31,433 DEBUG asgard_util_local.local - WARNING: Returning none for get_file_contents of c:\asgard\conf\userdata.txt, because the file doesn't exist
3802019-10-24 19:56:31,433 DEBUG asgard_util_local.local - Reading file contents from: c:\asgard\conf\userdata.txt
3812019-10-24 19:56:31,433 DEBUG asgard_util_local.local - WARNING: Returning none for get_file_contents of c:\asgard\conf\userdata.txt, because the file doesn't exist
3822019-10-24 19:56:31,433 INFO __main__ - Successfully deleted C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
3832019-10-24 19:56:31,433 ERROR __main__ - Unhandled top level exception
384Traceback (most recent call last):
385 File "c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 591, in <module>
386 rc = main(sub_name, new_cs_data_file_path)
387 File "c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 513, in main
388 cd_props = get_my_cd_props()
389 File "c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 126, in get_my_cd_props
390 new_mappings = Local.get_codedeploy_mappings(user="code_deploy") or {}
391 File "c:\asgard\framework-python\asgard_util_local\local.py", line 284, in get_codedeploy_mappings
392 return Local.read_from_cs(user, "vm_mapping_aws_codedeploy", throw_on_missing_root_key=False, version=version)
393 File "c:\asgard\framework-python\asgard_util_local\local.py", line 112, in read_from_cs
394 return Local._read_from_cs(path_to_get, cs_creds=cs_creds, cs_users=[cs_user], version=version, throw_on_missing_root_key=throw_on_missing_root_key)
395 File "c:\asgard\framework-python\asgard_util_local\local.py", line 121, in _read_from_cs
396 raise Exception("Config service credentials could not be found")
397Exception: Config service credentials could not be found
3982019-10-24 19:56:31,447 INFO __main__ - ngn_codedeploy_register exiting with RC -1
399REM --- ERROR: Failed to configure or start the CodeDeploy-Agent. start.bat exiting with RC 1
400
4012019-10-24 07:56:31+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
4022019-10-24 07:56:31+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' : 1
4032019-10-24 07:56:31+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat'
4042019-10-24 07:56:32+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' :
405c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
406
407c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_DIR=C:\Asgard\conf\ZabbixAgent
408
409c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_TARGET_FILE=C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
410
411c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_TARGET_DIR=c:\asgard\services\ZabbixAgent
412
413c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SCRIPT=c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py
414
415c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SRC_FILE=c:\asgard\services\ZabbixAgent\zabbix_agentd.conf
416
417c:\asgard\nvcloudinit\scripts\per-boot>REM --- Generate the config file
418
419c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py --input-template c:\asgard\services\ZabbixAgent\zabbix_agentd.conf --output-template C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
420Creating logger...
4212019-10-24 19:56:31,667 INFO __main__ - Starting
4222019-10-24 19:56:32,792 INFO __main__ - Detected that this VM is hosted in NGN
4232019-10-24 19:56:32,792 ERROR __main__ - Unhandled top level exception
424Traceback (most recent call last):
425 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 493, in <module>
426 rc = main(options.input_template, options.output_template)
427 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 434, in main
428 config_vals['[[HOSTNAME]]'] = get_zabbix_hostname()
429 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 418, in get_zabbix_hostname
430 (machine_type, ip) = get_machine_type_and_primary_ip()
431 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 365, in get_machine_type_and_primary_ip
432 vm_type_info = read_vm_type_info()
433 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 257, in read_vm_type_info
434 vm_type_from_userdata = read_userdata_section('vm-type-info')
435 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 233, in read_userdata_section
436 raise IOError("Missing required dependency {}".format(path_to_userdata))
437IOError: Missing required dependency c:\asgard\conf\userdata.txt
4382019-10-24 19:56:32,792 INFO __main__ - Done, returning -1
439
440c:\asgard\nvcloudinit\scripts\per-boot>SET RC=-1
441"ERROR: Failed to populate config file"
442
4432019-10-24 07:56:32+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : c:\python27\lib\site-packages\setuptools-22.0.0-py2.7.egg\pkg_resources\__init__.py:1268: UserWarning: C:\Windows\system32\config\systemprofile\AppData\Roaming\Python-Eggs is writable by group/others and vulnerable to attack when used with get_resource_filename. Consider a more secure location (set with .set_extraction_path or the PYTHON_EGG_CACHE environment variable).
444
4452019-10-24 07:56:32+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : -1
4462019-10-24 07:56:32+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat'
4472019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : "common_env_on_boot.bat starting"
448"common_env_on_boot.bat calling C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py""
449
450c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py" || GOTO :SHOWERR
451setup_environment starting ...
452Detected platform:
453 Windows ......... : True
454 Strict Windows OS : True
455 Cygwin .......... : False
456 Posix ........... : False
457 Linux ........... : False
458Checking file: c:\asgard\services\CommonEnvironment\resources\windows\ag_env.bat ...
459All required files validated
460Setting up common environment ...
461Reading SDB value from zone property cache
462WARNING: Failed to lookup AG_STATEDB from subnet-info and ZoneProperties. Leaving any currently set value for AG_STATEDB intact
463Creating common directories ...
464c:\asgard already exists; skipping
465c:\asgard\logs already exists; skipping
466c:\asgard\services already exists; skipping
467c:\asgard\conf already exists; skipping
468Saving environment ({'AG_HOME': 'c:\\asgard', 'AG_LOGS': 'c:\\asgard\\logs'}) to: c:\asgard\conf\asgard_env.bat ...
469Wrote environment setup file: c:\asgard\conf\asgard_env.bat
470
471c:\asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
472---- common_env_on_boot.bat was successful
473
4742019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' :
4752019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : 0
4762019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat'
4772019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : "Setting up Disk and Network based on userdata"
478"Executing C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py"
479
480c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py || GOTO FAIL
481
482c:\asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
483---- Successfully configured Disk and Network on the host
484
4852019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 2019-10-24 19:56:33,121 - disk-network-init - ERROR - No disk or network related config found in userdata, skipping initialization
486Traceback (most recent call last):
487 File "c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py", line 126, in <module>
488 disk_network_info = get_subset_mime(USERDATA_FILE, "disk-network-info")
489 File "c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py", line 18, in get_subset_mime
490 msg = email.message_from_file(open(userdata))
491IOError: [Errno 2] No such file or directory: 'c:\\asgard\\conf\\userdata.txt'
492
4932019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 0
4942019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat'
4952019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
496c:\asgard\nvcloudinit\scripts\per-boot>REM
497
498c:\asgard\nvcloudinit\scripts\per-boot>REM Enables the Asgard OSL LogPruner
499
500c:\asgard\nvcloudinit\scripts\per-boot>REM
501
502c:\asgard\nvcloudinit\scripts\per-boot>SET PYTHON_PATH=C:\Python27\python.exe
503
504c:\asgard\nvcloudinit\scripts\per-boot>IF EXIST C:\Python27\python.exe GOTO FOUND_PYTHON
505
506c:\asgard\nvcloudinit\scripts\per-boot>IF NOT "c:\asgard" == "" GOTO SCHEDULE
507
508c:\asgard\nvcloudinit\scripts\per-boot>SET INSTALL_DIR=c:\asgard\services\LogPruner
509
510c:\asgard\nvcloudinit\scripts\per-boot>SET TASK_NAME="Asgard OSL LogPruner"
511
512c:\asgard\nvcloudinit\scripts\per-boot>REM Create the task
513
514c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Query /TN "Asgard OSL LogPruner" || GOTO CREATE_TASK
515
516Folder: \
517TaskName Next Run Time Status
518======================================== ====================== ===============
519Asgard OSL LogPruner 10/24/2019 8:00:00 PM Ready
520
521c:\asgard\nvcloudinit\scripts\per-boot>schtasks /End /TN "Asgard OSL LogPruner" || GOTO :EOF
522SUCCESS: The scheduled task "Asgard OSL LogPruner" has been terminated successfully.
523
524c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Delete /F /TN "Asgard OSL LogPruner" || GOTO :EOF
525SUCCESS: The scheduled task "Asgard OSL LogPruner" was successfully deleted.
526
527c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Create /RU SYSTEM /ST 00:00 /SC HOURLY /MO 2 /TN "Asgard OSL LogPruner" /TR "\"C:\Python27\python.exe\" c:\asgard\services\LogPruner\prunelogs_win.py 2>&1" || GOTO :EOF
528SUCCESS: The scheduled task "Asgard OSL LogPruner" has successfully been created.
529
5302019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
5312019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' : 0
5322019-10-24 07:56:33+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat'
5332019-10-24 07:56:34+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' :
534c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py
535****** Executing command : net stop nxlog
536****** Return code of the command: 0
537****** Stdout of the execution :
538
539
540The nxlog service was stopped successfully.
541
542
543
544
545c:\asgard
546Falling back to see if nvcloudinit based config can be read to get update server location
547
5482019-10-24 07:56:34+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : Traceback (most recent call last):
549 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 147, in <module>
550 log_address = getLogServer()
551 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 44, in getLogServer
552 log_server = getUserdataValue("LoggingServerAddresses")
553 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 112, in getUserdataValue
554 print str(traceback.print_exc())
555NameError: global name 'traceback' is not defined
556
5572019-10-24 07:56:34+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : 1
5582019-10-24 07:56:34+00:00 :: InitHelper:ProcessInitScripts() :: Completed processing/executing init scripts found in the system
5592019-10-24 07:56:34+00:00 :: RegistryWriter:WriteNVCRegistry() :: Setting regkey for nvcloudinit @Execution
5602019-10-24 07:56:34+00:00 :: nvcloudinit:OnStart() :: NVCloudInit stopping
5612019-10-24 07:56:34+00:00 :: nvcloudinit:OnStop() :: Service Stopping
5622019-10-24 08:03:01+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
5632019-10-24 08:03:06+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
564 at System.Net.HttpWebRequest.GetResponse()
565 at nvcloudinit.nvcloudinit.isAWSEnv()
5662019-10-24 08:03:07+00:00 :: nvcloudinit:isAVAEnv() :: Exception trying to query XenStore :System.Management.ManagementException: Not found
567 at System.Management.ManagementException.ThrowWithExtendedInfo(ManagementStatus errorCode)
568 at System.Management.ManagementObject.Initialize(Boolean getObject)
569 at System.Management.ManagementBaseObject.get_ClassName()
570 at System.Management.ManagementClass.GetInstances(EnumerationOptions options)
571 at System.Management.ManagementClass.GetInstances()
572 at nvcloudinit.XenStoreSession..ctor()
573 at nvcloudinit.nvcloudinit.isAVAEnv()
5742019-10-24 08:03:07+00:00 :: nvcloudinit:OnStart() :: No http or ISO endpoint found , so assuming physical host and not generating new metadata/userdata files
5752019-10-24 08:03:07+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
5762019-10-24 08:03:07+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [c:\asgard\conf\userdata.txt]
5772019-10-24 08:03:07+00:00 :: UserDataHelper:GetUserData() :: Failed getting userdata file.
5782019-10-24 08:03:07+00:00 :: UserDataHelper:IsMIMESection() :: Failed checking if [disk-network-info] is a MIME section in userdata.
5792019-10-24 08:03:07+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
5802019-10-24 08:03:07+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
5812019-10-24 08:03:07+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
5822019-10-24 08:03:12+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
583 at System.Net.HttpWebRequest.GetResponse()
584 at nvcloudinit.nvcloudinit.isAWSEnv()
5852019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Setting secondary IP based on interface name....
5862019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Name: Ethernet 2
5872019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Description: Intel(R) PRO/1000 MT Network Connection
5882019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: =======================================
5892019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Interface type: Ethernet
5902019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Operational status: Up
5912019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: MAC: 5254004E573D
5922019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Gateways:
5932019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: 192.168.122.1
5942019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: IP Addresses:
5952019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: fe80::f575:804b:60a6:90da%14 0.0.0.0
5962019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: 192.168.122.40 255.255.255.0
5972019-10-24 08:03:12+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Adapter has DHCP Ip allocation config, so cannot process secondary Ips
5982019-10-24 08:03:12+00:00 :: ActivationHelper:getLicenseStatus() :: Determining Windows licensing details and status
5992019-10-24 08:03:12+00:00 :: ActivationHelper:getLicenseStatus() :: Windows(R), ServerStandardEval edition: Windows(R) Operating System, TIMEBASED_EVAL channel
6002019-10-24 08:03:12+00:00 :: ActivationHelper:getLicenseStatus() :: License status: Licensed
6012019-10-24 08:03:12+00:00 :: ActivationHelper:getLicenseStatus() :: Grace period remaining: 117d 1h
6022019-10-24 08:03:12+00:00 :: ActivationHelper:getLicenseStatus() :: Name:
6032019-10-24 08:03:12+00:00 :: ActivationHelper:getLicenseStatus() :: Port:0
6042019-10-24 08:03:12+00:00 :: nvcloudinit:OnStart() :: STATUS : Windows is already activated
6052019-10-24 08:03:12+00:00 :: nvcloudinit:OnStart() :: No domain specified in userdata, so no action for joining the domain
6062019-10-24 08:03:12+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputdiskList.txt]
6072019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
6082019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
6092019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
6102019-10-24 08:03:14+00:00 :: DiskInitializationHelper:initializeDisk() :: Checking Disk 0 ...
6112019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputpartitionList.txt]
6122019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
6132019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
6142019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
6152019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() :: -------------------Logging diskpart partion output -------------------
6162019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() ::
6172019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() :: Microsoft DiskPart version 6.3.9600
6182019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() ::
6192019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() :: Copyright (C) 1999-2013 Microsoft Corporation.
6202019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() :: On computer: BASEGAME-NGN-WK
6212019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() ::
6222019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() :: Disk 0 is now the selected disk.
6232019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() ::
6242019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() :: Partition ### Type Size Offset
6252019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() :: ------------- ---------------- ------- -------
6262019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() :: Partition 1 Primary 2000 MB 1024 KB
6272019-10-24 08:03:14+00:00 :: DiskInitializationHelper:checkPartition() :: --------------------------------------------------------------------
6282019-10-24 08:03:14+00:00 :: DiskInitializationHelper:initializeDisk() :: Disk 0 allready initialized, skipping disk
6292019-10-24 08:03:14+00:00 :: nvcloudinit:OnStart() :: Completed initializing disk
6302019-10-24 08:03:14+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Extending volume 'C' on unallocated space
6312019-10-24 08:03:14+00:00 :: DiskInitializationHelper:extendVolume() :: Extending volume 'C'
6322019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt]
6332019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT : Microsoft DiskPart version 6.3.9600
634
635Copyright (C) 1999-2013 Microsoft Corporation.
636On computer: BASEGAME-NGN-WK
637
638Volume 1 is the selected volume.
639
640There is not enough usable free space on specified disk(s) to extend the
641volume.
6422019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
6432019-10-24 08:03:14+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : -2147024809
6442019-10-24 08:03:14+00:00 :: nvcloudinit:OnStart() :: Completed extending volumes
6452019-10-24 08:03:14+00:00 :: InitHelper:GetInitScriptList() :: List of init scripts to execute in the order below :
6462019-10-24 08:03:14+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat
6472019-10-24 08:03:14+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat
6482019-10-24 08:03:14+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat
6492019-10-24 08:03:14+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat
6502019-10-24 08:03:14+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat
6512019-10-24 08:03:14+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat
6522019-10-24 08:03:14+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat'
6532019-10-24 08:03:15+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
654c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
655REM --- Registering instance and generating CD config file
656
657c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py || GOTO :ERROR
6582019-10-24 20:03:15,296 DEBUG __main__ - Attempting to acquire lock at: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
6592019-10-24 20:03:15,296 DEBUG __main__ - Lock acquired: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
6602019-10-24 20:03:15,312 INFO __main__ - Querying the CS for my CD props
6612019-10-24 20:03:15,312 DEBUG asgard_util_local.local - Reading file contents from: c:\asgard\conf\userdata.txt
6622019-10-24 20:03:15,312 DEBUG asgard_util_local.local - WARNING: Returning none for get_file_contents of c:\asgard\conf\userdata.txt, because the file doesn't exist
6632019-10-24 20:03:15,312 DEBUG asgard_util_local.local - Reading file contents from: c:\asgard\conf\userdata.txt
6642019-10-24 20:03:15,312 DEBUG asgard_util_local.local - WARNING: Returning none for get_file_contents of c:\asgard\conf\userdata.txt, because the file doesn't exist
6652019-10-24 20:03:15,312 INFO __main__ - Successfully deleted C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
6662019-10-24 20:03:15,312 ERROR __main__ - Unhandled top level exception
667Traceback (most recent call last):
668 File "c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 591, in <module>
669 rc = main(sub_name, new_cs_data_file_path)
670 File "c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 513, in main
671 cd_props = get_my_cd_props()
672 File "c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 126, in get_my_cd_props
673 new_mappings = Local.get_codedeploy_mappings(user="code_deploy") or {}
674 File "c:\asgard\framework-python\asgard_util_local\local.py", line 284, in get_codedeploy_mappings
675 return Local.read_from_cs(user, "vm_mapping_aws_codedeploy", throw_on_missing_root_key=False, version=version)
676 File "c:\asgard\framework-python\asgard_util_local\local.py", line 112, in read_from_cs
677 return Local._read_from_cs(path_to_get, cs_creds=cs_creds, cs_users=[cs_user], version=version, throw_on_missing_root_key=throw_on_missing_root_key)
678 File "c:\asgard\framework-python\asgard_util_local\local.py", line 121, in _read_from_cs
679 raise Exception("Config service credentials could not be found")
680Exception: Config service credentials could not be found
6812019-10-24 20:03:15,312 INFO __main__ - ngn_codedeploy_register exiting with RC -1
682REM --- ERROR: Failed to configure or start the CodeDeploy-Agent. start.bat exiting with RC 1
683
6842019-10-24 08:03:15+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
6852019-10-24 08:03:15+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' : 1
6862019-10-24 08:03:15+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat'
6872019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' :
688c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
689
690c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_DIR=C:\Asgard\conf\ZabbixAgent
691
692c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_TARGET_FILE=C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
693
694c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_TARGET_DIR=c:\asgard\services\ZabbixAgent
695
696c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SCRIPT=c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py
697
698c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SRC_FILE=c:\asgard\services\ZabbixAgent\zabbix_agentd.conf
699
700c:\asgard\nvcloudinit\scripts\per-boot>REM --- Generate the config file
701
702c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py --input-template c:\asgard\services\ZabbixAgent\zabbix_agentd.conf --output-template C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
703Creating logger...
7042019-10-24 20:03:15,515 INFO __main__ - Starting
7052019-10-24 20:03:16,625 INFO __main__ - Detected that this VM is hosted in NGN
7062019-10-24 20:03:16,625 ERROR __main__ - Unhandled top level exception
707Traceback (most recent call last):
708 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 493, in <module>
709 rc = main(options.input_template, options.output_template)
710 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 434, in main
711 config_vals['[[HOSTNAME]]'] = get_zabbix_hostname()
712 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 418, in get_zabbix_hostname
713 (machine_type, ip) = get_machine_type_and_primary_ip()
714 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 365, in get_machine_type_and_primary_ip
715 vm_type_info = read_vm_type_info()
716 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 257, in read_vm_type_info
717 vm_type_from_userdata = read_userdata_section('vm-type-info')
718 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 233, in read_userdata_section
719 raise IOError("Missing required dependency {}".format(path_to_userdata))
720IOError: Missing required dependency c:\asgard\conf\userdata.txt
7212019-10-24 20:03:16,625 INFO __main__ - Done, returning -1
722
723c:\asgard\nvcloudinit\scripts\per-boot>SET RC=-1
724"ERROR: Failed to populate config file"
725
7262019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : c:\python27\lib\site-packages\setuptools-22.0.0-py2.7.egg\pkg_resources\__init__.py:1268: UserWarning: C:\Windows\system32\config\systemprofile\AppData\Roaming\Python-Eggs is writable by group/others and vulnerable to attack when used with get_resource_filename. Consider a more secure location (set with .set_extraction_path or the PYTHON_EGG_CACHE environment variable).
727
7282019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : -1
7292019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat'
7302019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : "common_env_on_boot.bat starting"
731"common_env_on_boot.bat calling C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py""
732
733c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py" || GOTO :SHOWERR
734setup_environment starting ...
735Detected platform:
736 Windows ......... : True
737 Strict Windows OS : True
738 Cygwin .......... : False
739 Posix ........... : False
740 Linux ........... : False
741Checking file: c:\asgard\services\CommonEnvironment\resources\windows\ag_env.bat ...
742All required files validated
743Setting up common environment ...
744Reading SDB value from zone property cache
745WARNING: Failed to lookup AG_STATEDB from subnet-info and ZoneProperties. Leaving any currently set value for AG_STATEDB intact
746Creating common directories ...
747c:\asgard already exists; skipping
748c:\asgard\logs already exists; skipping
749c:\asgard\services already exists; skipping
750c:\asgard\conf already exists; skipping
751Saving environment ({'AG_HOME': 'c:\\asgard', 'AG_LOGS': 'c:\\asgard\\logs'}) to: c:\asgard\conf\asgard_env.bat ...
752Wrote environment setup file: c:\asgard\conf\asgard_env.bat
753
754c:\asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
755---- common_env_on_boot.bat was successful
756
7572019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' :
7582019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : 0
7592019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat'
7602019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : "Setting up Disk and Network based on userdata"
761"Executing C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py"
762
763c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py || GOTO FAIL
764
765c:\asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
766---- Successfully configured Disk and Network on the host
767
7682019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 2019-10-24 20:03:16,905 - disk-network-init - ERROR - No disk or network related config found in userdata, skipping initialization
769Traceback (most recent call last):
770 File "c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py", line 126, in <module>
771 disk_network_info = get_subset_mime(USERDATA_FILE, "disk-network-info")
772 File "c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py", line 18, in get_subset_mime
773 msg = email.message_from_file(open(userdata))
774IOError: [Errno 2] No such file or directory: 'c:\\asgard\\conf\\userdata.txt'
775
7762019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 0
7772019-10-24 08:03:16+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat'
7782019-10-24 08:03:17+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
779c:\asgard\nvcloudinit\scripts\per-boot>REM
780
781c:\asgard\nvcloudinit\scripts\per-boot>REM Enables the Asgard OSL LogPruner
782
783c:\asgard\nvcloudinit\scripts\per-boot>REM
784
785c:\asgard\nvcloudinit\scripts\per-boot>SET PYTHON_PATH=C:\Python27\python.exe
786
787c:\asgard\nvcloudinit\scripts\per-boot>IF EXIST C:\Python27\python.exe GOTO FOUND_PYTHON
788
789c:\asgard\nvcloudinit\scripts\per-boot>IF NOT "c:\asgard" == "" GOTO SCHEDULE
790
791c:\asgard\nvcloudinit\scripts\per-boot>SET INSTALL_DIR=c:\asgard\services\LogPruner
792
793c:\asgard\nvcloudinit\scripts\per-boot>SET TASK_NAME="Asgard OSL LogPruner"
794
795c:\asgard\nvcloudinit\scripts\per-boot>REM Create the task
796
797c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Query /TN "Asgard OSL LogPruner" || GOTO CREATE_TASK
798
799Folder: \
800TaskName Next Run Time Status
801======================================== ====================== ===============
802Asgard OSL LogPruner 10/24/2019 10:00:00 PM Ready
803
804c:\asgard\nvcloudinit\scripts\per-boot>schtasks /End /TN "Asgard OSL LogPruner" || GOTO :EOF
805SUCCESS: The scheduled task "Asgard OSL LogPruner" has been terminated successfully.
806
807c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Delete /F /TN "Asgard OSL LogPruner" || GOTO :EOF
808SUCCESS: The scheduled task "Asgard OSL LogPruner" was successfully deleted.
809
810c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Create /RU SYSTEM /ST 00:00 /SC HOURLY /MO 2 /TN "Asgard OSL LogPruner" /TR "\"C:\Python27\python.exe\" c:\asgard\services\LogPruner\prunelogs_win.py 2>&1" || GOTO :EOF
811SUCCESS: The scheduled task "Asgard OSL LogPruner" has successfully been created.
812
8132019-10-24 08:03:17+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
8142019-10-24 08:03:17+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' : 0
8152019-10-24 08:03:17+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat'
8162019-10-24 08:03:18+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' :
817c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py
818****** Executing command : net stop nxlog
819****** Return code of the command: 0
820****** Stdout of the execution :
821
822
823The nxlog service was stopped successfully.
824
825
826
827
828c:\asgard
829Falling back to see if nvcloudinit based config can be read to get update server location
830
8312019-10-24 08:03:18+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : Traceback (most recent call last):
832 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 147, in <module>
833 log_address = getLogServer()
834 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 44, in getLogServer
835 log_server = getUserdataValue("LoggingServerAddresses")
836 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 112, in getUserdataValue
837 print str(traceback.print_exc())
838NameError: global name 'traceback' is not defined
839
8402019-10-24 08:03:18+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : 1
8412019-10-24 08:03:18+00:00 :: InitHelper:ProcessInitScripts() :: Completed processing/executing init scripts found in the system
8422019-10-24 08:03:18+00:00 :: RegistryWriter:WriteNVCRegistry() :: Setting regkey for nvcloudinit @Execution
8432019-10-24 08:03:18+00:00 :: nvcloudinit:OnStart() :: NVCloudInit stopping
8442019-10-24 08:03:18+00:00 :: nvcloudinit:OnStop() :: Service Stopping
8452019-10-30 06:55:29+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
8462019-10-30 06:55:34+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
847 at System.Net.HttpWebRequest.GetResponse()
848 at nvcloudinit.nvcloudinit.isAWSEnv()
8492019-10-30 06:55:34+00:00 :: nvcloudinit:isAVAEnv() :: Exception trying to query XenStore :System.Management.ManagementException: Not found
850 at System.Management.ManagementException.ThrowWithExtendedInfo(ManagementStatus errorCode)
851 at System.Management.ManagementObject.Initialize(Boolean getObject)
852 at System.Management.ManagementBaseObject.get_ClassName()
853 at System.Management.ManagementClass.GetInstances(EnumerationOptions options)
854 at System.Management.ManagementClass.GetInstances()
855 at nvcloudinit.XenStoreSession..ctor()
856 at nvcloudinit.nvcloudinit.isAVAEnv()
8572019-10-30 06:55:34+00:00 :: nvcloudinit:OnStart() :: No http or ISO endpoint found , so assuming physical host and not generating new metadata/userdata files
8582019-10-30 06:55:34+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
8592019-10-30 06:55:34+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [c:\asgard\conf\userdata.txt]
8602019-10-30 06:55:34+00:00 :: UserDataHelper:GetUserData() :: Failed getting userdata file.
8612019-10-30 06:55:34+00:00 :: UserDataHelper:IsMIMESection() :: Failed checking if [disk-network-info] is a MIME section in userdata.
8622019-10-30 06:55:34+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
8632019-10-30 06:55:34+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
8642019-10-30 06:55:34+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
8652019-10-30 06:55:39+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
866 at System.Net.HttpWebRequest.GetResponse()
867 at nvcloudinit.nvcloudinit.isAWSEnv()
8682019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Setting secondary IP based on interface name....
8692019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Name: Ethernet 3
8702019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Description: Intel(R) PRO/1000 MT Network Connection #2
8712019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: ==========================================
8722019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Interface type: Ethernet
8732019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Operational status: Up
8742019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: MAC: DED5D3932AEA
8752019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Gateways:
8762019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: 169.254.1.1
8772019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: IP Addresses:
8782019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: fe80::759b:678f:9375:508c%22 0.0.0.0
8792019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: 10.244.4.211 255.255.255.255
8802019-10-30 06:55:39+00:00 :: NetworkHelper:setSecondayNetworkIPsBasedInt() :: Adapter has DHCP Ip allocation config, so cannot process secondary Ips
8812019-10-30 06:55:39+00:00 :: ActivationHelper:getLicenseStatus() :: Determining Windows licensing details and status
8822019-10-30 06:55:40+00:00 :: ActivationHelper:getLicenseStatus() :: Windows(R), ServerStandardEval edition: Windows(R) Operating System, TIMEBASED_EVAL channel
8832019-10-30 06:55:40+00:00 :: ActivationHelper:getLicenseStatus() :: License status: Licensed
8842019-10-30 06:55:40+00:00 :: ActivationHelper:getLicenseStatus() :: Grace period remaining: 111d 2h
8852019-10-30 06:55:40+00:00 :: ActivationHelper:getLicenseStatus() :: Name:
8862019-10-30 06:55:40+00:00 :: ActivationHelper:getLicenseStatus() :: Port:0
8872019-10-30 06:55:40+00:00 :: nvcloudinit:OnStart() :: STATUS : Windows is already activated
8882019-10-30 06:55:40+00:00 :: nvcloudinit:OnStart() :: No domain specified in userdata, so no action for joining the domain
8892019-10-30 06:55:40+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputdiskList.txt]
8902019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
8912019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
8922019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
8932019-10-30 06:55:41+00:00 :: DiskInitializationHelper:initializeDisk() :: Checking Disk 0 ...
8942019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputpartitionList.txt]
8952019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
8962019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
8972019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
8982019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() :: -------------------Logging diskpart partion output -------------------
8992019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() ::
9002019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() :: Microsoft DiskPart version 6.3.9600
9012019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() ::
9022019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() :: Copyright (C) 1999-2013 Microsoft Corporation.
9032019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() :: On computer: BASEGAME-NGN-WK
9042019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() ::
9052019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() :: Disk 0 is now the selected disk.
9062019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() ::
9072019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() :: Partition ### Type Size Offset
9082019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() :: ------------- ---------------- ------- -------
9092019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() :: Partition 1 Primary 2000 MB 1024 KB
9102019-10-30 06:55:41+00:00 :: DiskInitializationHelper:checkPartition() :: --------------------------------------------------------------------
9112019-10-30 06:55:41+00:00 :: DiskInitializationHelper:initializeDisk() :: Disk 0 allready initialized, skipping disk
9122019-10-30 06:55:41+00:00 :: nvcloudinit:OnStart() :: Completed initializing disk
9132019-10-30 06:55:41+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Extending volume 'C' on unallocated space
9142019-10-30 06:55:41+00:00 :: DiskInitializationHelper:extendVolume() :: Extending volume 'C'
9152019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt]
9162019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT : Microsoft DiskPart version 6.3.9600
917
918Copyright (C) 1999-2013 Microsoft Corporation.
919On computer: BASEGAME-NGN-WK
920
921Volume 1 is the selected volume.
922
923There is not enough usable free space on specified disk(s) to extend the
924volume.
9252019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
9262019-10-30 06:55:41+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : -2147024809
9272019-10-30 06:55:41+00:00 :: nvcloudinit:OnStart() :: Completed extending volumes
9282019-10-30 06:55:41+00:00 :: InitHelper:GetInitScriptList() :: List of init scripts to execute in the order below :
9292019-10-30 06:55:41+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat
9302019-10-30 06:55:41+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat
9312019-10-30 06:55:41+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat
9322019-10-30 06:55:41+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat
9332019-10-30 06:55:41+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat
9342019-10-30 06:55:41+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat
9352019-10-30 06:55:41+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat'
9362019-10-30 06:55:43+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
937c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
938REM --- Registering instance and generating CD config file
939
940c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py || GOTO :ERROR
9412019-10-30 18:55:43,211 DEBUG __main__ - Attempting to acquire lock at: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
9422019-10-30 18:55:43,211 DEBUG __main__ - Lock acquired: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
9432019-10-30 18:55:43,211 INFO __main__ - Querying the CS for my CD props
9442019-10-30 18:55:43,211 DEBUG asgard_util_local.local - Reading file contents from: c:\asgard\conf\userdata.txt
9452019-10-30 18:55:43,211 DEBUG asgard_util_local.local - WARNING: Returning none for get_file_contents of c:\asgard\conf\userdata.txt, because the file doesn't exist
9462019-10-30 18:55:43,211 DEBUG asgard_util_local.local - Reading file contents from: c:\asgard\conf\userdata.txt
9472019-10-30 18:55:43,211 DEBUG asgard_util_local.local - WARNING: Returning none for get_file_contents of c:\asgard\conf\userdata.txt, because the file doesn't exist
9482019-10-30 18:55:43,211 INFO __main__ - Successfully deleted C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
9492019-10-30 18:55:43,211 ERROR __main__ - Unhandled top level exception
950Traceback (most recent call last):
951 File "c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 591, in <module>
952 rc = main(sub_name, new_cs_data_file_path)
953 File "c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 513, in main
954 cd_props = get_my_cd_props()
955 File "c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py", line 126, in get_my_cd_props
956 new_mappings = Local.get_codedeploy_mappings(user="code_deploy") or {}
957 File "c:\asgard\framework-python\asgard_util_local\local.py", line 284, in get_codedeploy_mappings
958 return Local.read_from_cs(user, "vm_mapping_aws_codedeploy", throw_on_missing_root_key=False, version=version)
959 File "c:\asgard\framework-python\asgard_util_local\local.py", line 112, in read_from_cs
960 return Local._read_from_cs(path_to_get, cs_creds=cs_creds, cs_users=[cs_user], version=version, throw_on_missing_root_key=throw_on_missing_root_key)
961 File "c:\asgard\framework-python\asgard_util_local\local.py", line 121, in _read_from_cs
962 raise Exception("Config service credentials could not be found")
963Exception: Config service credentials could not be found
9642019-10-30 18:55:43,226 INFO __main__ - ngn_codedeploy_register exiting with RC -1
965REM --- ERROR: Failed to configure or start the CodeDeploy-Agent. start.bat exiting with RC 1
966
9672019-10-30 06:55:43+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
9682019-10-30 06:55:43+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' : 1
9692019-10-30 06:55:43+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat'
9702019-10-30 06:55:44+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' :
971c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
972
973c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_DIR=C:\Asgard\conf\ZabbixAgent
974
975c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_TARGET_FILE=C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
976
977c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_TARGET_DIR=c:\asgard\services\ZabbixAgent
978
979c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SCRIPT=c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py
980
981c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SRC_FILE=c:\asgard\services\ZabbixAgent\zabbix_agentd.conf
982
983c:\asgard\nvcloudinit\scripts\per-boot>REM --- Generate the config file
984
985c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py --input-template c:\asgard\services\ZabbixAgent\zabbix_agentd.conf --output-template C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
986Creating logger...
9872019-10-30 18:55:43,523 INFO __main__ - Starting
9882019-10-30 18:55:44,618 INFO __main__ - Detected that this VM is hosted in NGN
9892019-10-30 18:55:44,634 ERROR __main__ - Unhandled top level exception
990Traceback (most recent call last):
991 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 493, in <module>
992 rc = main(options.input_template, options.output_template)
993 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 434, in main
994 config_vals['[[HOSTNAME]]'] = get_zabbix_hostname()
995 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 418, in get_zabbix_hostname
996 (machine_type, ip) = get_machine_type_and_primary_ip()
997 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 365, in get_machine_type_and_primary_ip
998 vm_type_info = read_vm_type_info()
999 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 257, in read_vm_type_info
1000 vm_type_from_userdata = read_userdata_section('vm-type-info')
1001 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 233, in read_userdata_section
1002 raise IOError("Missing required dependency {}".format(path_to_userdata))
1003IOError: Missing required dependency c:\asgard\conf\userdata.txt
10042019-10-30 18:55:44,634 INFO __main__ - Done, returning -1
1005
1006c:\asgard\nvcloudinit\scripts\per-boot>SET RC=-1
1007"ERROR: Failed to populate config file"
1008
10092019-10-30 06:55:44+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : c:\python27\lib\site-packages\setuptools-22.0.0-py2.7.egg\pkg_resources\__init__.py:1268: UserWarning: C:\Windows\system32\config\systemprofile\AppData\Roaming\Python-Eggs is writable by group/others and vulnerable to attack when used with get_resource_filename. Consider a more secure location (set with .set_extraction_path or the PYTHON_EGG_CACHE environment variable).
1010
10112019-10-30 06:55:44+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : -1
10122019-10-30 06:55:44+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat'
10132019-10-30 06:55:44+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : "common_env_on_boot.bat starting"
1014"common_env_on_boot.bat calling C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py""
1015
1016c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py" || GOTO :SHOWERR
1017setup_environment starting ...
1018Detected platform:
1019 Windows ......... : True
1020 Strict Windows OS : True
1021 Cygwin .......... : False
1022 Posix ........... : False
1023 Linux ........... : False
1024Checking file: c:\asgard\services\CommonEnvironment\resources\windows\ag_env.bat ...
1025All required files validated
1026Setting up common environment ...
1027Reading SDB value from zone property cache
1028WARNING: Failed to lookup AG_STATEDB from subnet-info and ZoneProperties. Leaving any currently set value for AG_STATEDB intact
1029Creating common directories ...
1030c:\asgard already exists; skipping
1031c:\asgard\logs already exists; skipping
1032c:\asgard\services already exists; skipping
1033c:\asgard\conf already exists; skipping
1034Saving environment ({'AG_HOME': 'c:\\asgard', 'AG_LOGS': 'c:\\asgard\\logs'}) to: c:\asgard\conf\asgard_env.bat ...
1035Wrote environment setup file: c:\asgard\conf\asgard_env.bat
1036
1037c:\asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
1038---- common_env_on_boot.bat was successful
1039
10402019-10-30 06:55:44+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' :
10412019-10-30 06:55:44+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : 0
10422019-10-30 06:55:44+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat'
10432019-10-30 06:55:45+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : "Setting up Disk and Network based on userdata"
1044"Executing C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py"
1045
1046c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py || GOTO FAIL
1047
1048c:\asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
1049---- Successfully configured Disk and Network on the host
1050
10512019-10-30 06:55:45+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 2019-10-30 18:55:45,039 - disk-network-init - ERROR - No disk or network related config found in userdata, skipping initialization
1052Traceback (most recent call last):
1053 File "c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py", line 126, in <module>
1054 disk_network_info = get_subset_mime(USERDATA_FILE, "disk-network-info")
1055 File "c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py", line 18, in get_subset_mime
1056 msg = email.message_from_file(open(userdata))
1057IOError: [Errno 2] No such file or directory: 'c:\\asgard\\conf\\userdata.txt'
1058
10592019-10-30 06:55:45+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 0
10602019-10-30 06:55:45+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat'
10612019-10-30 06:55:45+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
1062c:\asgard\nvcloudinit\scripts\per-boot>REM
1063
1064c:\asgard\nvcloudinit\scripts\per-boot>REM Enables the Asgard OSL LogPruner
1065
1066c:\asgard\nvcloudinit\scripts\per-boot>REM
1067
1068c:\asgard\nvcloudinit\scripts\per-boot>SET PYTHON_PATH=C:\Python27\python.exe
1069
1070c:\asgard\nvcloudinit\scripts\per-boot>IF EXIST C:\Python27\python.exe GOTO FOUND_PYTHON
1071
1072c:\asgard\nvcloudinit\scripts\per-boot>IF NOT "c:\asgard" == "" GOTO SCHEDULE
1073
1074c:\asgard\nvcloudinit\scripts\per-boot>SET INSTALL_DIR=c:\asgard\services\LogPruner
1075
1076c:\asgard\nvcloudinit\scripts\per-boot>SET TASK_NAME="Asgard OSL LogPruner"
1077
1078c:\asgard\nvcloudinit\scripts\per-boot>REM Create the task
1079
1080c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Query /TN "Asgard OSL LogPruner" || GOTO CREATE_TASK
1081
1082Folder: \
1083TaskName Next Run Time Status
1084======================================== ====================== ===============
1085Asgard OSL LogPruner 10/30/2019 8:00:00 PM Ready
1086
1087c:\asgard\nvcloudinit\scripts\per-boot>schtasks /End /TN "Asgard OSL LogPruner" || GOTO :EOF
1088SUCCESS: The scheduled task "Asgard OSL LogPruner" has been terminated successfully.
1089
1090c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Delete /F /TN "Asgard OSL LogPruner" || GOTO :EOF
1091SUCCESS: The scheduled task "Asgard OSL LogPruner" was successfully deleted.
1092
1093c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Create /RU SYSTEM /ST 00:00 /SC HOURLY /MO 2 /TN "Asgard OSL LogPruner" /TR "\"C:\Python27\python.exe\" c:\asgard\services\LogPruner\prunelogs_win.py 2>&1" || GOTO :EOF
1094SUCCESS: The scheduled task "Asgard OSL LogPruner" has successfully been created.
1095
10962019-10-30 06:55:45+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
10972019-10-30 06:55:45+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' : 0
10982019-10-30 06:55:45+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat'
10992019-10-30 06:55:46+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' :
1100c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py
1101****** Executing command : net stop nxlog
1102****** Return code of the command: 0
1103****** Stdout of the execution :
1104
1105
1106The nxlog service was stopped successfully.
1107
1108
1109
1110
1111c:\asgard
1112Falling back to see if nvcloudinit based config can be read to get update server location
1113
11142019-10-30 06:55:46+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : Traceback (most recent call last):
1115 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 147, in <module>
1116 log_address = getLogServer()
1117 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 44, in getLogServer
1118 log_server = getUserdataValue("LoggingServerAddresses")
1119 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 112, in getUserdataValue
1120 print str(traceback.print_exc())
1121NameError: global name 'traceback' is not defined
1122
11232019-10-30 06:55:46+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : 1
11242019-10-30 06:55:46+00:00 :: InitHelper:ProcessInitScripts() :: Completed processing/executing init scripts found in the system
11252019-10-30 06:55:46+00:00 :: RegistryWriter:WriteNVCRegistry() :: Setting regkey for nvcloudinit @Execution
11262019-10-30 06:55:46+00:00 :: nvcloudinit:OnStart() :: NVCloudInit stopping
11272019-10-30 06:55:46+00:00 :: nvcloudinit:OnStop() :: Service Stopping
11282019-10-31 04:33:02+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
11292019-10-31 04:33:02+00:00 :: ISOHelper:getCDROMDrive() :: Identified a CDROM ISO based drive as : E:\
11302019-10-31 04:33:02+00:00 :: nvcloudinit:OnStart() :: ISO driver with metadata found , so assuming NGN virtualized environment and fetching metadata/userdata files
11312019-10-31 04:33:02+00:00 :: nvcloudinit:processISOData() :: Not Running in AWS Environment, so looking for ISO based instance meta/user data attachment
11322019-10-31 04:33:02+00:00 :: nvcloudinit:processISOData() :: Writing user data to: c:\asgard\conf\userdata.txt
11332019-10-31 04:33:02+00:00 :: nvcloudinit:processISOData() :: Wrote userdata as: Content-Type: multipart/mixed; boundary="===============0637935640091705090=="
1134MIME-Version: 1.0
1135
1136--===============0637935640091705090==
1137Content-Type: text/plain; charset="us-ascii"
1138MIME-Version: 1.0
1139Content-Transfer-Encoding: 7bit
1140Content-Disposition: attachment; filename="zone-info"
1141
1142[ZONE-INFO]
1143CSRAPIKey = chejB3qKfZ2hMiYCracZghpk71SAT9wj
1144vpcId = ND-NIDR-01
1145CSREndPoint = https://prod.csr.geforcenow.com
1146Platform = NGN
1147Zone.Type = DEV
1148AvailabilityZone = ND-NIDR-01
1149
1150--===============0637935640091705090==
1151Content-Type: text/plain; charset="us-ascii"
1152MIME-Version: 1.0
1153Content-Transfer-Encoding: 7bit
1154Content-Disposition: attachment; filename="disk-network-info"
1155
1156{
1157 "nvcloudinit-disk-network-setup": false,
1158 "disk": {
1159 "init-disk": true
1160 },
1161 "network": {
1162 "ADMIN": {
1163 "mac": "{{{mac_4de59e68-1875-48c9-af03-1cd1cb813937}}}",
1164 "ip": "10.244.4.222"
1165 },
1166 "STORAGE": {
1167 "mac": "{{{mac_056ed905-a68c-40ed-9f4c-02cbb25dd38c}}}",
1168 "ip": "10.244.4.222",
1169 "secondary_ip": ""
1170 }
1171 }
1172}
1173
1174--===============0637935640091705090==
1175Content-Type: text/plain; charset="us-ascii"
1176MIME-Version: 1.0
1177Content-Transfer-Encoding: 7bit
1178Content-Disposition: attachment; filename="vm-type-info"
1179
1180{"primary_ip": "10.244.4.222", "vm_type_name": "Storage", "service_name": "BaseSTORAGE"}
1181
1182--===============0637935640091705090==--
1183
11842019-10-31 04:33:02+00:00 :: nvcloudinit:processISOData() :: Writing meta data to: c:\asgard\conf\metadata.txt
11852019-10-31 04:33:02+00:00 :: nvcloudinit:processISOData() :: Wrote metadata as: { "instance-id": "nd-nidr-01-storage-vmf0f4d5.default", "local-hostname": "nd-nidr-01-storage-vmf0f4d5" }
1186
11872019-10-31 04:33:02+00:00 :: nvcloudinit:processISOData() :: Writing networkdata to: c:\asgard\conf\networkdata.txt
11882019-10-31 04:33:02+00:00 :: nvcloudinit:processISOData() :: Wrote networkdata as: {"links":[{"id":"net1","type":"phy","mtu":1500,"ethernet_mac_address":"00:01:67:56:00:36"},{"id":"eth0","type":"phy","mtu":1440,"ethernet_mac_address":"ba:39:8c:1c:3a:ad"}],"networks":[{"id":"sriov-storage-net","type":"ipv4","link":"net1","ip_address":"10.1.32.31","netmask":"255.255.224.0"},{"id":"default","type":"ipv4","link":"eth0","ip_address":"10.244.4.222","netmask":"255.255.255.255","routes":[{"network":"10.244.0.0","netmask":"255.255.0.0","gateway":"169.254.1.1"}]}]}
11892019-10-31 04:33:02+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
11902019-10-31 04:33:02+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [c:\asgard\conf\userdata.txt]
11912019-10-31 04:33:02+00:00 :: UserDataHelper:IsMIMESection() :: MIME section [disk-network-info] exists
11922019-10-31 04:33:02+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
11932019-10-31 04:33:02+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
11942019-10-31 04:33:02+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
11952019-10-31 04:33:02+00:00 :: ActivationHelper:getLicenseStatus() :: Determining Windows licensing details and status
11962019-10-31 04:33:03+00:00 :: ActivationHelper:getLicenseStatus() :: Windows(R), ServerStandardEval edition: Windows(R) Operating System, TIMEBASED_EVAL channel
11972019-10-31 04:33:03+00:00 :: ActivationHelper:getLicenseStatus() :: License status: Licensed
11982019-10-31 04:33:03+00:00 :: ActivationHelper:getLicenseStatus() :: Grace period remaining: 110d 5h
11992019-10-31 04:33:03+00:00 :: ActivationHelper:getLicenseStatus() :: Name:
12002019-10-31 04:33:03+00:00 :: ActivationHelper:getLicenseStatus() :: Port:0
12012019-10-31 04:33:03+00:00 :: nvcloudinit:OnStart() :: STATUS : Windows is already activated
12022019-10-31 04:33:03+00:00 :: DomainHelper:getDomainFromUserdata() :: No DomainName found in the json userdata blob
12032019-10-31 04:33:03+00:00 :: DomainHelper:getUserFromUserdata() :: No DomainUser found in the json userdata blob
12042019-10-31 04:33:03+00:00 :: DomainHelper:getPasswordFromUserdata() :: No DomainPW found in the json userdata blob
12052019-10-31 04:33:03+00:00 :: nvcloudinit:OnStart() :: No domain specified in userdata, so no action for joining the domain
12062019-10-31 04:33:03+00:00 :: InitHelper:GetInitScriptList() :: List of init scripts to execute in the order below :
12072019-10-31 04:33:03+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat
12082019-10-31 04:33:03+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat
12092019-10-31 04:33:03+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat
12102019-10-31 04:33:03+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat
12112019-10-31 04:33:03+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat
12122019-10-31 04:33:03+00:00 :: InitHelper:GetInitScriptList() :: c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat
12132019-10-31 04:33:03+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat'
12142019-10-31 04:33:36+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
1215c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
1216REM --- Registering instance and generating CD config file
1217
1218c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py || GOTO :ERROR
12192019-10-31 16:33:04,341 DEBUG __main__ - Attempting to acquire lock at: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
12202019-10-31 16:33:04,341 DEBUG __main__ - Lock acquired: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
12212019-10-31 16:33:04,341 INFO __main__ - Querying the CS for my CD props
12222019-10-31 16:33:04,341 DEBUG asgard_util_local.local - Reading file contents from: c:\asgard\conf\userdata.txt
12232019-10-31 16:33:04,341 INFO __main__ - Trying to use vm_type_info from user-data for new CD mappings
12242019-10-31 16:33:04,341 INFO __main__ - Use the props from user-data
12252019-10-31 16:33:04,341 DEBUG asgard_util_local.local - Reading file contents from: c:\asgard\conf\userdata.txt
12262019-10-31 16:33:04,357 INFO __main__ - CDInstanceSettings:
1227{
1228 "instance_name": "BaseSTORAGE-ND-NIDR-01-10.244.4.222",
1229 "policy_doc": "{\"Version\": \"2012-10-17\",\"Statement\": [{\"Action\": [\"s3:GetObject\", \"s3:ListBucket\"],\"Effect\": \"Allow\",\"Resource\": \"arn:aws:s3:::*\"}]}",
1230 "my_ip": "10.244.4.222",
1231 "service_name": "BaseSTORAGE",
1232 "master_sak": "vNz7zhKuRoChOC/uvzdwQgQuhXY+wTq8CAdJQGuQ",
1233 "tags": {
1234 "deployment-group-id": "BaseSTORAGE-ND-NIDR-01-10.244.4.222",
1235 "deployment-group": "BaseSTORAGE-ND-NIDR-01"
1236 },
1237 "master_ak": "AKIAJTNPBB4F3KOMO3NQ",
1238 "test_bucket_name": "nvidia-codedeploy-us-west-1",
1239 "group_name": "BaseSTORAGE-ND-NIDR-01",
1240 "do_key_cleanup": true,
1241 "iam_user_name": "NGN-BaseSTORAGE-ND-NIDR-01-10.244.4.222",
1242 "vpc_id": "ND-NIDR-01",
1243 "iam_user_path": "/AWS/CodeDeploy/",
1244 "policy_name": "codedeploy-agent",
1245 "region": "us-west-1"
1246}
12472019-10-31 16:33:04,466 INFO __main__ - Querying for IAM user: NGN-BaseSTORAGE-ND-NIDR-01-10.244.4.222
12482019-10-31 16:33:06,154 INFO __main__ - User NGN-BaseSTORAGE-ND-NIDR-01-10.244.4.222 does not exist. Creating it
12492019-10-31 16:33:06,279 INFO __main__ - Created user NGN-BaseSTORAGE-ND-NIDR-01-10.244.4.222 with arn arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BaseSTORAGE-ND-NIDR-01-10.244.4.222
12502019-10-31 16:33:06,279 INFO __main__ - Adding/updating S3 Policy to IAM user NGN-BaseSTORAGE-ND-NIDR-01-10.244.4.222
12512019-10-31 16:33:06,418 INFO __main__ - No config file currently exists at C:\ProgramData\Amazon\CodeDeploy\conf.onpremises.yml
12522019-10-31 16:33:06,418 INFO __main__ - No CD config file, credentials will be recreated
12532019-10-31 16:33:06,529 INFO __main__ - Creating new keys for user NGN-BaseSTORAGE-ND-NIDR-01-10.244.4.222
12542019-10-31 16:33:06,668 INFO __main__ - Created new keys, verifying them
12552019-10-31 16:33:06,857 INFO __main__ - Still waiting for S3 AWS API to accept the new user keys. Sleeping for 10 seconds...
12562019-10-31 16:33:16,982 INFO __main__ - Still waiting for S3 AWS API to accept the new user keys. Sleeping for 10 seconds...
12572019-10-31 16:33:27,091 INFO __main__ - Verified AWS S3 API accepted the new keys
12582019-10-31 16:33:27,138 INFO __main__ - Registering on-prem instance BaseSTORAGE-ND-NIDR-01-10.244.4.222 with arn arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BaseSTORAGE-ND-NIDR-01-10.244.4.222
12592019-10-31 16:33:27,513 INFO __main__ - Calling wrapper_fn with tags [{'Value': 'BaseSTORAGE-ND-NIDR-01-10.244.4.222', 'Key': 'deployment-group-id'}, {'Value': 'BaseSTORAGE-ND-NIDR-01', 'Key': 'deployment-group'}]
12602019-10-31 16:33:27,559 INFO __main__ - Checking if AWS CD config file needs to be updated
12612019-10-31 16:33:27,559 INFO __main__ - No config file currently exists at C:\ProgramData\Amazon\CodeDeploy\conf.onpremises.yml
12622019-10-31 16:33:27,559 INFO __main__ - Updating existing config {} with new values {'region': 'us-west-1', 'aws_access_key_id': 'AKIAXVN5G5FB37MYNVNM', 'aws_secret_access_key': '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', 'iam_user_arn': 'arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BaseSTORAGE-ND-NIDR-01-10.244.4.222'}
12632019-10-31 16:33:27,559 INFO __main__ - Restarting codedeploy service, could take 60-90 seconds
12642019-10-31 16:33:27,559 INFO __main__ - Verified codedeployagent service isn't in a pending state
12652019-10-31 16:33:27,559 INFO __main__ - Restarting CDA
12662019-10-31 16:33:32,059 INFO __main__ - Successfully deleted C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
12672019-10-31 16:33:32,059 INFO __main__ - ngn_codedeploy_register exiting with RC 0
1268REM --- Starting AWS-CodeDeploy-Agent
1269
1270c:\asgard\nvcloudinit\scripts\per-boot>powershell.exe Start-Service codedeployagent || GOTO :ERROR
1271REM --- Successfully configured and started CodeDeploy-Agent. start.bat exiting with RC 0
1272
12732019-10-31 04:33:36+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
12742019-10-31 04:33:36+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' : 0
12752019-10-31 04:33:36+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat'
12762019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' :
1277c:\asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
1278
1279c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_DIR=C:\Asgard\conf\ZabbixAgent
1280
1281c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_TARGET_FILE=C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
1282
1283c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_TARGET_DIR=c:\asgard\services\ZabbixAgent
1284
1285c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SCRIPT=c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py
1286
1287c:\asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SRC_FILE=c:\asgard\services\ZabbixAgent\zabbix_agentd.conf
1288
1289c:\asgard\nvcloudinit\scripts\per-boot>REM --- Generate the config file
1290
1291c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py --input-template c:\asgard\services\ZabbixAgent\zabbix_agentd.conf --output-template C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
1292Creating logger...
12932019-10-31 16:33:37,154 INFO __main__ - Starting
12942019-10-31 16:33:38,247 INFO __main__ - Detected that this VM is hosted in NGN
12952019-10-31 16:33:38,263 ERROR __main__ - Unhandled top level exception
1296Traceback (most recent call last):
1297 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 493, in <module>
1298 rc = main(options.input_template, options.output_template)
1299 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 434, in main
1300 config_vals['[[HOSTNAME]]'] = get_zabbix_hostname()
1301 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 420, in get_zabbix_hostname
1302 zone_name = get_zone_name()
1303 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 410, in get_zone_name
1304 cs_creds_dict = read_cs_credentials()
1305 File "c:\asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 251, in read_cs_credentials
1306 raise Exception("No section 'cs-credentials' in userdata.txt")
1307Exception: No section 'cs-credentials' in userdata.txt
13082019-10-31 16:33:38,263 INFO __main__ - Done, returning -1
1309
1310c:\asgard\nvcloudinit\scripts\per-boot>SET RC=-1
1311"ERROR: Failed to populate config file"
1312
13132019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : c:\python27\lib\site-packages\pkg_resources\__init__.py:1243: UserWarning: C:\Windows\system32\config\systemprofile\AppData\Local\Python-Eggs\Python-Eggs\Cache is writable by group/others and vulnerable to attack when used with get_resource_filename. Consider a more secure location (set with .set_extraction_path or the PYTHON_EGG_CACHE environment variable).
1314 warnings.warn(msg, UserWarning)
1315
13162019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : -1
13172019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat'
13182019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : "common_env_on_boot.bat starting"
1319"common_env_on_boot.bat calling C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py""
1320
1321c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe "c:\asgard\services\CommonEnvironment\bin\setup_environment.py" || GOTO :SHOWERR
1322setup_environment starting ...
1323Detected platform:
1324 Windows ......... : True
1325 Strict Windows OS : True
1326 Cygwin .......... : False
1327 Posix ........... : False
1328 Linux ........... : False
1329Checking file: c:\asgard\services\CommonEnvironment\resources\windows\ag_env.bat ...
1330All required files validated
1331Setting up common environment ...
1332Reading SDB value from zone property cache
1333WARNING: Failed to lookup AG_STATEDB from subnet-info and ZoneProperties. Leaving any currently set value for AG_STATEDB intact
1334Creating common directories ...
1335c:\asgard already exists; skipping
1336c:\asgard\logs already exists; skipping
1337c:\asgard\services already exists; skipping
1338c:\asgard\conf already exists; skipping
1339Saving environment ({'AG_HOME': 'c:\\asgard', 'AG_LOGS': 'c:\\asgard\\logs'}) to: c:\asgard\conf\asgard_env.bat ...
1340Wrote environment setup file: c:\asgard\conf\asgard_env.bat
1341
1342c:\asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
1343---- common_env_on_boot.bat was successful
1344
13452019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' :
13462019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : 0
13472019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat'
13482019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : "Setting up Disk and Network based on userdata"
1349"Executing C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py"
1350
1351c:\asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\disk-network-init.py || GOTO FAIL
1352
1353c:\asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
1354---- Successfully configured Disk and Network on the host
1355
13562019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 2019-10-31 16:33:38,654 - disk-network-init - INFO - Skipping disk pooling and initialization
13572019-10-31 16:33:38,654 - disk-network-init - ERROR - Missing network configuration for 'ADMIN' interface, skipping
13582019-10-31 16:33:38,654 - disk-network-init - ERROR - Missing network configuration for 'STORAGE' interface, skipping
13592019-10-31 16:33:38,654 - disk-network-init - INFO - Successfully initialized disk and network interfaces
1360
13612019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\011-disk-network-init.bat' : 0
13622019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat'
13632019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
1364c:\asgard\nvcloudinit\scripts\per-boot>REM
1365
1366c:\asgard\nvcloudinit\scripts\per-boot>REM Enables the Asgard OSL LogPruner
1367
1368c:\asgard\nvcloudinit\scripts\per-boot>REM
1369
1370c:\asgard\nvcloudinit\scripts\per-boot>SET PYTHON_PATH=C:\Python27\python.exe
1371
1372c:\asgard\nvcloudinit\scripts\per-boot>IF EXIST C:\Python27\python.exe GOTO FOUND_PYTHON
1373
1374c:\asgard\nvcloudinit\scripts\per-boot>IF NOT "c:\asgard" == "" GOTO SCHEDULE
1375
1376c:\asgard\nvcloudinit\scripts\per-boot>SET INSTALL_DIR=c:\asgard\services\LogPruner
1377
1378c:\asgard\nvcloudinit\scripts\per-boot>SET TASK_NAME="Asgard OSL LogPruner"
1379
1380c:\asgard\nvcloudinit\scripts\per-boot>REM Create the task
1381
1382c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Query /TN "Asgard OSL LogPruner" || GOTO CREATE_TASK
1383
1384Folder: \
1385TaskName Next Run Time Status
1386======================================== ====================== ===============
1387Asgard OSL LogPruner 10/31/2019 6:00:00 PM Ready
1388
1389c:\asgard\nvcloudinit\scripts\per-boot>schtasks /End /TN "Asgard OSL LogPruner" || GOTO :EOF
1390SUCCESS: The scheduled task "Asgard OSL LogPruner" has been terminated successfully.
1391
1392c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Delete /F /TN "Asgard OSL LogPruner" || GOTO :EOF
1393SUCCESS: The scheduled task "Asgard OSL LogPruner" was successfully deleted.
1394
1395c:\asgard\nvcloudinit\scripts\per-boot>schtasks /Create /RU SYSTEM /ST 00:00 /SC HOURLY /MO 2 /TN "Asgard OSL LogPruner" /TR "\"C:\Python27\python.exe\" c:\asgard\services\LogPruner\prunelogs_win.py 2>&1" || GOTO :EOF
1396SUCCESS: The scheduled task "Asgard OSL LogPruner" has successfully been created.
1397
13982019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
13992019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' : 0
14002019-10-31 04:33:38+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'c:\asgard\nvcloudinit\scripts\per-boot\c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat'
14012019-10-31 04:33:39+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' :
1402c:\asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py
1403****** Executing command : net stop nxlog
1404****** Return code of the command: 0
1405****** Stdout of the execution :
1406
1407
1408The nxlog service was stopped successfully.
1409
1410
1411
1412
1413c:\asgard
1414{"primary_ip": "10.244.4.222", "vm_type_name": "Storage", "service_name": "BaseSTORAGE"}
1415
1416Falling back to see if nvcloudinit based config can be read to get update server location
1417Falling back to see if nvcloudinit based config is encoded in mime format
1418UserData value/empty for Key : LoggingServerAddresses not found
1419Falling back to see if nvcloudinit based config can be read to get update server location
1420Falling back to see if nvcloudinit based config is encoded in mime format
1421UserData value/empty for Key : LoggingServerAddresses not found
1422
14232019-10-31 04:33:39+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : Traceback (most recent call last):
1424 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 147, in <module>
1425 log_address = getLogServer()
1426 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 46, in getLogServer
1427 config_data = read_from_cs("/zone_properties")
1428 File "c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 32, in read_from_cs
1429 config_service = ConfigService(username=username)
1430 File "c:\asgard\framework-python\asgard_util_cs\config_service.py", line 79, in __init__
1431 credentials = get_credentials_from_cloudinit_userdata()
1432 File "c:\asgard\framework-python\asgard_util_cs\config_service.py", line 61, in get_credentials_from_cloudinit_userdata
1433 raise MissingCredentialsException("User-data can't be read or does not contain any Config Service credentials")
1434asgard_util_cs.config_service.MissingCredentialsException: User-data can't be read or does not contain any Config Service credentials
1435
14362019-10-31 04:33:39+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'c:\asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : 1
14372019-10-31 04:33:39+00:00 :: InitHelper:ProcessInitScripts() :: Completed processing/executing init scripts found in the system
14382019-10-31 04:33:39+00:00 :: RegistryWriter:WriteNVCRegistry() :: Setting regkey for nvcloudinit @Execution
14392019-10-31 04:33:39+00:00 :: nvcloudinit:OnStart() :: NVCloudInit stopping
14402019-10-31 04:33:39+00:00 :: nvcloudinit:OnStop() :: Service Stopping
14412019-10-31 04:39:09+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
14422019-10-31 04:39:09+00:00 :: ISOHelper:getCDROMDrive() :: Identified a CDROM ISO based drive as : E:\
14432019-10-31 04:39:14+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
1444 at System.Net.HttpWebRequest.GetResponse()
1445 at nvcloudinit.nvcloudinit.isAWSEnv()
14462019-10-31 04:39:15+00:00 :: nvcloudinit:isAVAEnv() :: Exception trying to query XenStore :System.Management.ManagementException: Not found
1447 at System.Management.ManagementException.ThrowWithExtendedInfo(ManagementStatus errorCode)
1448 at System.Management.ManagementObject.Initialize(Boolean getObject)
1449 at System.Management.ManagementBaseObject.get_ClassName()
1450 at System.Management.ManagementClass.GetInstances(EnumerationOptions options)
1451 at System.Management.ManagementClass.GetInstances()
1452 at nvcloudinit.XenStoreSession..ctor()
1453 at nvcloudinit.nvcloudinit.isAVAEnv()
14542019-10-31 04:39:15+00:00 :: nvcloudinit:OnStart() :: No http endpoint found but found ISO with no metadata file , so assuming physical host and not generating new metadata/userdata files
14552019-10-31 04:39:15+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
14562019-10-31 04:39:15+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [c:\asgard\conf\userdata.txt]
14572019-10-31 04:39:15+00:00 :: UserDataHelper:IsMIMESection() :: MIME section [disk-network-info] exists
14582019-10-31 04:39:15+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
14592019-10-31 04:39:15+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
14602019-10-31 04:39:15+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
14612019-10-31 04:39:15+00:00 :: ActivationHelper:getLicenseStatus() :: Determining Windows licensing details and status
14622019-10-31 04:39:15+00:00 :: ActivationHelper:getLicenseStatus() :: Windows(R), ServerStandardEval edition: Windows(R) Operating System, TIMEBASED_EVAL channel
14632019-10-31 04:39:15+00:00 :: ActivationHelper:getLicenseStatus() :: License status: Licensed
14642019-10-31 04:39:15+00:00 :: ActivationHelper:getLicenseStatus() :: Grace period remaining: 110d 5h
14652019-10-31 04:39:15+00:00 :: ActivationHelper:getLicenseStatus() :: Name:
14662019-10-31 04:39:15+00:00 :: ActivationHelper:getLicenseStatus() :: Port:0
14672019-10-31 04:39:15+00:00 :: nvcloudinit:OnStart() :: STATUS : Windows is already activated
14682019-10-31 04:39:15+00:00 :: nvcloudinit:OnStart() :: Skipping init scripts since --no-scripts was passed
14692019-10-31 04:39:15+00:00 :: nvcloudinit:OnStart() :: NVCloudInit stopping
14702019-10-31 04:39:15+00:00 :: nvcloudinit:OnStop() :: Service Stopping
14712019-10-31 04:39:41+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
14722019-10-31 04:39:41+00:00 :: ISOHelper:getCDROMDrive() :: Identified a CDROM ISO based drive as : E:\
14732019-10-31 04:39:46+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
1474 at System.Net.HttpWebRequest.GetResponse()
1475 at nvcloudinit.nvcloudinit.isAWSEnv()
14762019-10-31 04:39:46+00:00 :: nvcloudinit:isAVAEnv() :: Exception trying to query XenStore :System.Management.ManagementException: Not found
1477 at System.Management.ManagementException.ThrowWithExtendedInfo(ManagementStatus errorCode)
1478 at System.Management.ManagementObject.Initialize(Boolean getObject)
1479 at System.Management.ManagementBaseObject.get_ClassName()
1480 at System.Management.ManagementClass.GetInstances(EnumerationOptions options)
1481 at System.Management.ManagementClass.GetInstances()
1482 at nvcloudinit.XenStoreSession..ctor()
1483 at nvcloudinit.nvcloudinit.isAVAEnv()
14842019-10-31 04:39:46+00:00 :: nvcloudinit:OnStart() :: No http endpoint found but found ISO with no metadata file , so assuming physical host and not generating new metadata/userdata files
14852019-10-31 04:39:46+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
14862019-10-31 04:39:46+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [c:\asgard\conf\userdata.txt]
14872019-10-31 04:39:46+00:00 :: UserDataHelper:IsMIMESection() :: MIME section [disk-network-info] exists
14882019-10-31 04:39:46+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
14892019-10-31 04:39:46+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
14902019-10-31 04:39:46+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
14912019-10-31 04:39:46+00:00 :: ActivationHelper:getLicenseStatus() :: Determining Windows licensing details and status
14922019-10-31 04:39:47+00:00 :: ActivationHelper:getLicenseStatus() :: Windows(R), ServerStandardEval edition: Windows(R) Operating System, TIMEBASED_EVAL channel
14932019-10-31 04:39:47+00:00 :: ActivationHelper:getLicenseStatus() :: License status: Licensed
14942019-10-31 04:39:47+00:00 :: ActivationHelper:getLicenseStatus() :: Grace period remaining: 110d 5h
14952019-10-31 04:39:47+00:00 :: ActivationHelper:getLicenseStatus() :: Name:
14962019-10-31 04:39:47+00:00 :: ActivationHelper:getLicenseStatus() :: Port:0
14972019-10-31 04:39:47+00:00 :: nvcloudinit:OnStart() :: STATUS : Windows is already activated
14982019-10-31 04:39:47+00:00 :: nvcloudinit:OnStart() :: Skipping init scripts since --no-scripts was passed
14992019-10-31 04:39:47+00:00 :: nvcloudinit:OnStart() :: NVCloudInit stopping
15002019-10-31 04:39:47+00:00 :: nvcloudinit:OnStop() :: Service Stopping