· 6 years ago · Oct 18, 2019, 04:22 PM
12019-10-17 06:59:39+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
22019-10-17 06:59:39+00:00 :: ISOHelper:getCDROMDrive() :: Identified a CDROM ISO based drive as : D:\
32019-10-17 06:59:45+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-17 06:59:45+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-17 06:59:45+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-17 06:59:45+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
162019-10-17 06:59:45+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [C:\Asgard\conf\userdata.txt]
172019-10-17 06:59:45+00:00 :: UserDataHelper:GetUserData() :: Failed getting userdata file.
182019-10-17 06:59:45+00:00 :: UserDataHelper:IsMIMESection() :: Failed checking if [disk-network-info] is a MIME section in userdata.
192019-10-17 06:59:45+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
202019-10-17 06:59:45+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
212019-10-17 06:59:45+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
222019-10-17 06:59:45+00:00 :: nvcloudinit:OnStart() :: ERROR: Unhandled top level exception. Stopping: System.NullReferenceException: Object reference not set to an instance of an object.
23 at nvcloudinit.nvcloudinit.OnStart(String[] args)
242019-10-17 06:59:45+00:00 :: nvcloudinit:OnStop() :: Service Stopping
252019-10-18 06:05:19+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
262019-10-18 06:05:19+00:00 :: ISOHelper:getCDROMDrive() :: Identified a CDROM ISO based drive as : D:\
272019-10-18 06:05:19+00:00 :: nvcloudinit:OnStart() :: ISO driver with metadata found , so assuming NGN virtualized environment and fetching metadata/userdata files
282019-10-18 06:05:19+00:00 :: nvcloudinit:processISOData() :: Not Running in AWS Environment, so looking for ISO based instance meta/user data attachment
292019-10-18 06:05:19+00:00 :: nvcloudinit:processISOData() :: Writing user data to: C:\Asgard\conf\userdata.txt
302019-10-18 06:05:19+00:00 :: nvcloudinit:processISOData() :: Wrote userdata as: Content-Type: multipart/mixed; boundary="===============0637935640091705090=="
31MIME-Version: 1.0
32
33--===============0637935640091705090==
34Content-Type: text/plain; charset="us-ascii"
35MIME-Version: 1.0
36Content-Transfer-Encoding: 7bit
37Content-Disposition: attachment; filename="zone-info"
38
39[ZONE-INFO]
40CSRAPIKey = SRjPc30WgQLz5LTPHzdOte+Vzr59CbwQGZZ9uVjWxhg=
41vpcId = ND-NIDR-01
42CSREndPoint = https://prod.csr.geforcenow.com
43Platform = NGN
44Zone.Type = DEV
45AvailabilityZone = ND-NIDR-01
46NGN_DOMAIN_NAME = null
47NGN_USERNAME = null
48NGN_PASSWORD = null
49
50--===============0637935640091705090==
51Content-Type: text/plain; charset="us-ascii"
52MIME-Version: 1.0
53Content-Transfer-Encoding: 7bit
54Content-Disposition: attachment; filename="vm-type-info"
55
56{"primary_ip": "10.244.4.234", "vm_type_name": "PM", "service_name": "BasePM"}
57
58--===============0637935640091705090==--
59
602019-10-18 06:05:19+00:00 :: nvcloudinit:processISOData() :: Writing meta data to: C:\Asgard\conf\metadata.txt
612019-10-18 06:05:19+00:00 :: nvcloudinit:processISOData() :: Wrote metadata as: { "instance-id": "nd-nidr-01-pm-vm4515a5.default", "local-hostname": "nd-nidr-01-pm-vm4515a5" }
62
632019-10-18 06:05:19+00:00 :: ISOHelper:readNetworkdata() :: ERROR: No netowrkdata file found in ISO drive 'D:\' attached
642019-10-18 06:05:19+00:00 :: nvcloudinit:processISOData() :: ERROR: No networkdata found, so dumping null data to networkdata config
652019-10-18 06:05:19+00:00 :: nvcloudinit:processISOData() :: Writing networkdata to: C:\Asgard\conf\networkdata.txt
662019-10-18 06:05:19+00:00 :: nvcloudinit:processISOData() :: Wrote networkdata as:
672019-10-18 06:05:19+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
682019-10-18 06:05:19+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [C:\Asgard\conf\userdata.txt]
692019-10-18 06:05:19+00:00 :: UserDataHelper:IsMIMESection() :: MIME section [disk-network-info] does not exist
702019-10-18 06:05:19+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
712019-10-18 06:05:19+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
722019-10-18 06:05:19+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
732019-10-18 06:05:19+00:00 :: CloudBaseNetworkHelper:loadnwdata() :: Exception trying to read nwdata for network information System.NullReferenceException: Object reference not set to an instance of an object.
74 at nvcloudinit.CloudBaseNetworkHelper.loadnwdata()
752019-10-18 06:05:19+00:00 :: RegistryWriter:FlipDeadGWRegistry() :: Setting regkey for @DeadGWDetectDefault
762019-10-18 06:05:19+00:00 :: CloudBaseNetworkHelper:disableDeadGWDetection() :: Succesfully disabled dead gateway in the network
772019-10-18 06:05:19+00:00 :: CloudBaseNetworkHelper:disableNetBt() :: Ensuring TcpipNetbiosOptions is set to: 2
782019-10-18 06:05:19+00:00 :: CloudBaseNetworkHelper:disableNetBt() :: NetBios option for [00000015] Intel(R) X540 Virtual Function is currently set to 0. Changing to: 2
792019-10-18 06:05:19+00:00 :: CloudBaseNetworkHelper:printAndParseMgmtBaseObj() :: Successfully performed: SetTcpipNetbios for [00000015] Intel(R) X540 Virtual Function from 0 to 2
802019-10-18 06:05:19+00:00 :: CloudBaseNetworkHelper:disableNetBt() :: NetBios option for [00000016] Intel(R) X540 Virtual Function is currently set to 0. Changing to: 2
812019-10-18 06:05:19+00:00 :: CloudBaseNetworkHelper:printAndParseMgmtBaseObj() :: Successfully performed: SetTcpipNetbios for [00000016] Intel(R) X540 Virtual Function from 0 to 2
822019-10-18 06:05:19+00:00 :: CloudBaseNetworkHelper:setNetworkInterfaceName() :: No implementation of setNetworkInterfaceName for cloudbaseinit
832019-10-18 06:05:24+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
84 at System.Net.HttpWebRequest.GetResponse()
85 at nvcloudinit.nvcloudinit.isAWSEnv()
862019-10-18 06:05:24+00:00 :: CloudBaseNetworkHelper:setSecondayNetworkIPsBasedInt() :: No implementation of setSecondayNetworkIPsBasedInt for cloudbaseinit
872019-10-18 06:05:24+00:00 :: ActivationHelper:getLicenseStatus() :: Determining Windows licensing details and status
882019-10-18 06:05:25+00:00 :: ActivationHelper:getLicenseStatus() :: Windows(R), ServerStandardEval edition: Windows(R) Operating System, TIMEBASED_EVAL channel
892019-10-18 06:05:25+00:00 :: ActivationHelper:getLicenseStatus() :: License status: Licensed
902019-10-18 06:05:25+00:00 :: ActivationHelper:getLicenseStatus() :: Grace period remaining: 123d 15h
912019-10-18 06:05:25+00:00 :: ActivationHelper:getLicenseStatus() :: Name:
922019-10-18 06:05:25+00:00 :: ActivationHelper:getLicenseStatus() :: Port:0
932019-10-18 06:05:25+00:00 :: nvcloudinit:OnStart() :: STATUS : Windows is already activated
942019-10-18 06:05:25+00:00 :: DomainHelper:getDomainFromUserdata() :: No DomainName found in the json userdata blob
952019-10-18 06:05:25+00:00 :: DomainHelper:getUserFromUserdata() :: No DomainUser found in the json userdata blob
962019-10-18 06:05:25+00:00 :: DomainHelper:getPasswordFromUserdata() :: No DomainPW found in the json userdata blob
972019-10-18 06:05:25+00:00 :: nvcloudinit:OnStart() :: No domain specified in userdata, so no action for joining the domain
982019-10-18 06:05:25+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputdiskList.txt]
992019-10-18 06:05:25+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
1002019-10-18 06:05:25+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
1012019-10-18 06:05:25+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
1022019-10-18 06:05:25+00:00 :: DiskInitializationHelper:initializeDisk() :: Checking Disk 0 ...
1032019-10-18 06:05:25+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputpartitionList.txt]
1042019-10-18 06:05:25+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
1052019-10-18 06:05:25+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
1062019-10-18 06:05:25+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
1072019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() :: -------------------Logging diskpart partion output -------------------
1082019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() ::
1092019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() :: Microsoft DiskPart version 6.3.9600
1102019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() ::
1112019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() :: Copyright (C) 1999-2013 Microsoft Corporation.
1122019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() :: On computer: BASEGAME-NGN-WK
1132019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() ::
1142019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() :: Disk 0 is now the selected disk.
1152019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() ::
1162019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() :: Partition ### Type Size Offset
1172019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() :: ------------- ---------------- ------- -------
1182019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() :: Partition 1 Primary 2000 MB 1024 KB
1192019-10-18 06:05:25+00:00 :: DiskInitializationHelper:checkPartition() :: --------------------------------------------------------------------
1202019-10-18 06:05:25+00:00 :: DiskInitializationHelper:initializeDisk() :: Disk 0 allready initialized, skipping disk
1212019-10-18 06:05:25+00:00 :: nvcloudinit:OnStart() :: Completed initializing disk
1222019-10-18 06:05:25+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Extending volume 'C' on unallocated space
1232019-10-18 06:05:25+00:00 :: DiskInitializationHelper:extendVolume() :: Extending volume 'C'
1242019-10-18 06:05:25+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt]
1252019-10-18 06:05:26+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT : Microsoft DiskPart version 6.3.9600
126
127Copyright (C) 1999-2013 Microsoft Corporation.
128On computer: BASEGAME-NGN-WK
129
130Volume 2 is the selected volume.
131
132DiskPart successfully extended the volume.
1332019-10-18 06:05:26+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
1342019-10-18 06:05:26+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
1352019-10-18 06:05:26+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Skipping extend for volume 'D'
1362019-10-18 06:05:26+00:00 :: nvcloudinit:OnStart() :: Completed extending volumes
1372019-10-18 06:05:26+00:00 :: InitHelper:GetInitScriptList() :: List of init scripts to execute in the order below :
1382019-10-18 06:05:26+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat
1392019-10-18 06:05:26+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat
1402019-10-18 06:05:26+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat
1412019-10-18 06:05:26+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat
1422019-10-18 06:05:26+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat
1432019-10-18 06:05:26+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'C:\Asgard\nvcloudinit\scripts\per-boot\C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat'
1442019-10-18 06:05:48+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
145C:\Asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
146REM --- Registering instance and generating CD config file
147
148C:\Asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe C:\Asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py || GOTO :ERROR
1492019-10-18 06:05:27,303 DEBUG __main__ - Attempting to acquire lock at: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
1502019-10-18 06:05:27,319 DEBUG __main__ - Lock acquired: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
1512019-10-18 06:05:27,319 INFO __main__ - Querying the CS for my CD props
1522019-10-18 06:05:27,319 DEBUG asgard_util_local.local - Reading file contents from: C:\Asgard\conf\userdata.txt
1532019-10-18 06:05:27,319 INFO __main__ - Trying to use vm_type_info from user-data for new CD mappings
1542019-10-18 06:05:27,319 INFO __main__ - Use the props from user-data
1552019-10-18 06:05:27,319 DEBUG asgard_util_local.local - Reading file contents from: C:\Asgard\conf\userdata.txt
1562019-10-18 06:05:27,319 INFO __main__ - CDInstanceSettings:
157{
158 "instance_name": "BasePM-ND-NIDR-01-10.244.4.234",
159 "policy_doc": "{\"Version\": \"2012-10-17\",\"Statement\": [{\"Action\": [\"s3:GetObject\", \"s3:ListBucket\"],\"Effect\": \"Allow\",\"Resource\": \"arn:aws:s3:::*\"}]}",
160 "my_ip": "10.244.4.234",
161 "service_name": "BasePM",
162 "master_sak": "vNz7zhKuRoChOC/uvzdwQgQuhXY+wTq8CAdJQGuQ",
163 "tags": {
164 "deployment-group-id": "BasePM-ND-NIDR-01-10.244.4.234",
165 "deployment-group": "BasePM-ND-NIDR-01"
166 },
167 "master_ak": "AKIAJTNPBB4F3KOMO3NQ",
168 "test_bucket_name": "nvidia-codedeploy-us-west-1",
169 "group_name": "BasePM-ND-NIDR-01",
170 "do_key_cleanup": true,
171 "iam_user_name": "NGN-BasePM-ND-NIDR-01-10.244.4.234",
172 "vpc_id": "ND-NIDR-01",
173 "iam_user_path": "/AWS/CodeDeploy/",
174 "policy_name": "codedeploy-agent",
175 "region": "us-west-1"
176}
1772019-10-18 06:05:27,460 INFO __main__ - Querying for IAM user: NGN-BasePM-ND-NIDR-01-10.244.4.234
1782019-10-18 06:05:28,992 INFO __main__ - User NGN-BasePM-ND-NIDR-01-10.244.4.234 does not exist. Creating it
1792019-10-18 06:05:29,117 INFO __main__ - Created user NGN-BasePM-ND-NIDR-01-10.244.4.234 with arn arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BasePM-ND-NIDR-01-10.244.4.234
1802019-10-18 06:05:29,117 INFO __main__ - Adding/updating S3 Policy to IAM user NGN-BasePM-ND-NIDR-01-10.244.4.234
1812019-10-18 06:05:29,256 INFO __main__ - No config file currently exists at C:\ProgramData\Amazon\CodeDeploy\conf.onpremises.yml
1822019-10-18 06:05:29,256 INFO __main__ - No CD config file, credentials will be recreated
1832019-10-18 06:05:29,351 INFO __main__ - Creating new keys for user NGN-BasePM-ND-NIDR-01-10.244.4.234
1842019-10-18 06:05:29,476 INFO __main__ - Created new keys, verifying them
1852019-10-18 06:05:29,664 INFO __main__ - Still waiting for S3 AWS API to accept the new user keys. Sleeping for 10 seconds...
1862019-10-18 06:05:39,757 INFO __main__ - Verified AWS S3 API accepted the new keys
1872019-10-18 06:05:39,835 INFO __main__ - Registering on-prem instance BasePM-ND-NIDR-01-10.244.4.234 with arn arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BasePM-ND-NIDR-01-10.244.4.234
1882019-10-18 06:05:40,053 INFO __main__ - Calling wrapper_fn with tags [{'Value': 'BasePM-ND-NIDR-01-10.244.4.234', 'Key': 'deployment-group-id'}, {'Value': 'BasePM-ND-NIDR-01', 'Key': 'deployment-group'}]
1892019-10-18 06:05:40,117 INFO __main__ - Checking if AWS CD config file needs to be updated
1902019-10-18 06:05:40,117 INFO __main__ - No config file currently exists at C:\ProgramData\Amazon\CodeDeploy\conf.onpremises.yml
1912019-10-18 06:05:40,117 INFO __main__ - Updating existing config {} with new values {'region': 'us-west-1', 'aws_access_key_id': 'AKIAXVN5G5FB5YWNJML5', 'aws_secret_access_key': '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', 'iam_user_arn': 'arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BasePM-ND-NIDR-01-10.244.4.234'}
1922019-10-18 06:05:40,117 INFO __main__ - Restarting codedeploy service, could take 60-90 seconds
1932019-10-18 06:05:40,117 INFO __main__ - Verified codedeployagent service isn't in a pending state
1942019-10-18 06:05:40,117 INFO __main__ - Restarting CDA
1952019-10-18 06:05:43,851 INFO __main__ - Successfully deleted C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
1962019-10-18 06:05:43,851 INFO __main__ - ngn_codedeploy_register exiting with RC 0
197REM --- Starting AWS-CodeDeploy-Agent
198
199C:\Asgard\nvcloudinit\scripts\per-boot>powershell.exe Start-Service codedeployagent || GOTO :ERROR
200REM --- Successfully configured and started CodeDeploy-Agent. start.bat exiting with RC 0
201
2022019-10-18 06:05:48+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
2032019-10-18 06:05:48+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' : 0
2042019-10-18 06:05:48+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'C:\Asgard\nvcloudinit\scripts\per-boot\C:\Asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat'
2052019-10-18 06:05:50+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' :
206C:\Asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
207
208C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_DIR=C:\Asgard\conf\ZabbixAgent
209
210C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_TARGET_FILE=C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
211
212C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_TARGET_DIR=C:\Asgard\services\ZabbixAgent
213
214C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SCRIPT=C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py
215
216C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SRC_FILE=C:\Asgard\services\ZabbixAgent\zabbix_agentd.conf
217
218C:\Asgard\nvcloudinit\scripts\per-boot>REM --- Generate the config file
219
220C:\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
221Creating logger...
2222019-10-18 06:05:48,881 INFO __main__ - Starting
2232019-10-18 06:05:49,976 INFO __main__ - Detected that this VM is hosted in NGN
2242019-10-18 06:05:50,006 ERROR __main__ - Unhandled top level exception
225Traceback (most recent call last):
226 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 493, in <module>
227 rc = main(options.input_template, options.output_template)
228 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 434, in main
229 config_vals['[[HOSTNAME]]'] = get_zabbix_hostname()
230 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 420, in get_zabbix_hostname
231 zone_name = get_zone_name()
232 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 410, in get_zone_name
233 cs_creds_dict = read_cs_credentials()
234 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 251, in read_cs_credentials
235 raise Exception("No section 'cs-credentials' in userdata.txt")
236Exception: No section 'cs-credentials' in userdata.txt
2372019-10-18 06:05:50,006 INFO __main__ - Done, returning -1
238
239C:\Asgard\nvcloudinit\scripts\per-boot>SET RC=-1
240"ERROR: Failed to populate config file"
241
2422019-10-18 06:05:50+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).
243
2442019-10-18 06:05:50+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : -1
2452019-10-18 06:05:50+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'
2462019-10-18 06:05:50+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"
247"common_env_on_boot.bat calling C:\Python27\python.exe "C:\Asgard\services\CommonEnvironment\bin\setup_environment.py""
248
249C:\Asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe "C:\Asgard\services\CommonEnvironment\bin\setup_environment.py" || GOTO :SHOWERR
250setup_environment starting ...
251Detected platform:
252 Windows ......... : True
253 Strict Windows OS : True
254 Cygwin .......... : False
255 Posix ........... : False
256 Linux ........... : False
257Checking file: C:\Asgard\services\CommonEnvironment\resources\windows\ag_env.bat ...
258All required files validated
259Setting up common environment ...
260Reading SDB value from zone property cache
261WARNING: Failed to lookup AG_STATEDB from subnet-info and ZoneProperties. Leaving any currently set value for AG_STATEDB intact
262Creating common directories ...
263C:\Asgard already exists; skipping
264C:\Asgard\logs already exists; skipping
265C:\Asgard\services already exists; skipping
266C:\Asgard\conf already exists; skipping
267Saving environment ({'AG_HOME': 'C:\\Asgard', 'AG_LOGS': 'C:\\Asgard\\logs'}) to: C:\Asgard\conf\asgard_env.bat ...
268Wrote environment setup file: C:\Asgard\conf\asgard_env.bat
269
270C:\Asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
271---- common_env_on_boot.bat was successful
272
2732019-10-18 06:05:50+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' :
2742019-10-18 06:05:50+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : 0
2752019-10-18 06:05:50+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'C:\Asgard\nvcloudinit\scripts\per-boot\C:\Asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat'
2762019-10-18 06:05:50+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
277C:\Asgard\nvcloudinit\scripts\per-boot>REM
278
279C:\Asgard\nvcloudinit\scripts\per-boot>REM Enables the Asgard OSL LogPruner
280
281C:\Asgard\nvcloudinit\scripts\per-boot>REM
282
283C:\Asgard\nvcloudinit\scripts\per-boot>SET PYTHON_PATH=C:\Python27\python.exe
284
285C:\Asgard\nvcloudinit\scripts\per-boot>IF EXIST C:\Python27\python.exe GOTO FOUND_PYTHON
286
287C:\Asgard\nvcloudinit\scripts\per-boot>IF NOT "C:\Asgard" == "" GOTO SCHEDULE
288
289C:\Asgard\nvcloudinit\scripts\per-boot>SET INSTALL_DIR=C:\Asgard\services\LogPruner
290
291C:\Asgard\nvcloudinit\scripts\per-boot>SET TASK_NAME="Asgard OSL LogPruner"
292
293C:\Asgard\nvcloudinit\scripts\per-boot>REM Create the task
294
295C:\Asgard\nvcloudinit\scripts\per-boot>schtasks /Query /TN "Asgard OSL LogPruner" || GOTO CREATE_TASK
296
297C:\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
298SUCCESS: The scheduled task "Asgard OSL LogPruner" has successfully been created.
299
3002019-10-18 06:05:50+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.
3012019-10-18 06:05:50+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' : 0
3022019-10-18 06:05:50+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'C:\Asgard\nvcloudinit\scripts\per-boot\C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat'
3032019-10-18 06:05:51+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' :
304C:\Asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.py
305****** Executing command : net stop nxlog
306****** Return code of the command: 0
307****** Stdout of the execution :
308The nxlog service was stopped successfully.
309C:\Asgard
310{"primary_ip": "10.244.4.234", "vm_type_name": "PM", "service_name": "BasePM"}
311
312Falling back to see if nvcloudinit based config can be read to get update server location
313Falling back to see if nvcloudinit based config is encoded in mime format
314UserData value/empty for Key : LoggingServerAddresses not found
315Falling back to see if nvcloudinit based config can be read to get update server location
316Falling back to see if nvcloudinit based config is encoded in mime format
317UserData value/empty for Key : LoggingServerAddresses not found
318
3192019-10-18 06:05:51+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : Traceback (most recent call last):
320 File "C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 147, in <module>
321 log_address = getLogServer()
322 File "C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 46, in getLogServer
323 config_data = read_from_cs("/zone_properties")
324 File "C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 32, in read_from_cs
325 config_service = ConfigService(username=username)
326 File "c:\asgard\framework-python\asgard_util_cs\config_service.py", line 79, in __init__
327 credentials = get_credentials_from_cloudinit_userdata()
328 File "c:\asgard\framework-python\asgard_util_cs\config_service.py", line 61, in get_credentials_from_cloudinit_userdata
329 raise MissingCredentialsException("User-data can't be read or does not contain any Config Service credentials")
330asgard_util_cs.config_service.MissingCredentialsException: User-data can't be read or does not contain any Config Service credentials
331
3322019-10-18 06:05:51+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : 1
3332019-10-18 06:05:51+00:00 :: InitHelper:ProcessInitScripts() :: Completed processing/executing init scripts found in the system
3342019-10-18 06:05:51+00:00 :: RegistryWriter:WriteNVCRegistry() :: Setting regkey for nvcloudinit @Execution
3352019-10-18 06:05:51+00:00 :: nvcloudinit:OnStart() :: NVCloudInit stopping
3362019-10-18 06:05:51+00:00 :: nvcloudinit:OnStop() :: Service Stopping
3372019-10-18 04:11:20+00:00 :: nvcloudinit:OnStart() :: NVCloudInit starting
3382019-10-18 04:11:20+00:00 :: ISOHelper:getCDROMDrive() :: Identified a CDROM ISO based drive as : D:\
3392019-10-18 04:11:20+00:00 :: nvcloudinit:OnStart() :: ISO driver with metadata found , so assuming NGN virtualized environment and fetching metadata/userdata files
3402019-10-18 04:11:20+00:00 :: nvcloudinit:processISOData() :: Not Running in AWS Environment, so looking for ISO based instance meta/user data attachment
3412019-10-18 04:11:20+00:00 :: nvcloudinit:processISOData() :: Writing user data to: C:\Asgard\conf\userdata.txt
3422019-10-18 04:11:20+00:00 :: nvcloudinit:processISOData() :: Wrote userdata as: Content-Type: multipart/mixed; boundary="===============0637935640091705090=="
343MIME-Version: 1.0
344
345--===============0637935640091705090==
346Content-Type: text/plain; charset="us-ascii"
347MIME-Version: 1.0
348Content-Transfer-Encoding: 7bit
349Content-Disposition: attachment; filename="zone-info"
350
351[ZONE-INFO]
352CSRAPIKey = SRjPc30WgQLz5LTPHzdOte+Vzr59CbwQGZZ9uVjWxhg=
353vpcId = ND-NIDR-01
354CSREndPoint = https://prod.csr.geforcenow.com
355Platform = NGN
356Zone.Type = DEV
357AvailabilityZone = ND-NIDR-01
358NGN_DOMAIN_NAME = null
359NGN_USERNAME = null
360NGN_PASSWORD = null
361
362--===============0637935640091705090==
363Content-Type: text/plain; charset="us-ascii"
364MIME-Version: 1.0
365Content-Transfer-Encoding: 7bit
366Content-Disposition: attachment; filename="vm-type-info"
367
368{"primary_ip": "10.244.4.236", "vm_type_name": "PM", "service_name": "BasePM"}
369
370--===============0637935640091705090==--
371
3722019-10-18 04:11:20+00:00 :: nvcloudinit:processISOData() :: Writing meta data to: C:\Asgard\conf\metadata.txt
3732019-10-18 04:11:20+00:00 :: nvcloudinit:processISOData() :: Wrote metadata as: { "instance-id": "nd-nidr-01-pm-vm4515a5.default", "local-hostname": "nd-nidr-01-pm-vm4515a5" }
374
3752019-10-18 04:11:20+00:00 :: ISOHelper:readNetworkdata() :: ERROR: No netowrkdata file found in ISO drive 'D:\' attached
3762019-10-18 04:11:20+00:00 :: nvcloudinit:processISOData() :: ERROR: No networkdata found, so dumping null data to networkdata config
3772019-10-18 04:11:20+00:00 :: nvcloudinit:processISOData() :: Writing networkdata to: C:\Asgard\conf\networkdata.txt
3782019-10-18 04:11:20+00:00 :: nvcloudinit:processISOData() :: Wrote networkdata as:
3792019-10-18 04:11:20+00:00 :: UserDataHelper:IsMIMESection() :: Checking if [disk-network-info] is a MIME section in userdata.
3802019-10-18 04:11:20+00:00 :: UserDataHelper:GetUserData() :: Getting userdata [C:\Asgard\conf\userdata.txt]
3812019-10-18 04:11:20+00:00 :: UserDataHelper:IsMIMESection() :: MIME section [disk-network-info] does not exist
3822019-10-18 04:11:20+00:00 :: RegistryWriter:ReadNVCRegistry() :: Getting regkey value for the nvcloudinit @Reboot
3832019-10-18 04:11:20+00:00 :: RegistryWriter:ReadNVCRegistry() :: The registry key doesnt exist SOFTWARE\NvCloudInit @Reboot
3842019-10-18 04:11:20+00:00 :: RegistryWriter:ReadNVCRegistry() :: Object reference not set to an instance of an object.
3852019-10-18 04:11:20+00:00 :: CloudBaseNetworkHelper:loadnwdata() :: Exception trying to read nwdata for network information System.NullReferenceException: Object reference not set to an instance of an object.
386 at nvcloudinit.CloudBaseNetworkHelper.loadnwdata()
3872019-10-18 04:11:20+00:00 :: RegistryWriter:FlipDeadGWRegistry() :: Setting regkey for @DeadGWDetectDefault
3882019-10-18 04:11:20+00:00 :: CloudBaseNetworkHelper:disableDeadGWDetection() :: Succesfully disabled dead gateway in the network
3892019-10-18 04:11:21+00:00 :: CloudBaseNetworkHelper:disableNetBt() :: Ensuring TcpipNetbiosOptions is set to: 2
3902019-10-18 04:11:21+00:00 :: CloudBaseNetworkHelper:disableNetBt() :: NetBios option for [00000015] Intel(R) X540 Virtual Function is already set to 2
3912019-10-18 04:11:21+00:00 :: CloudBaseNetworkHelper:disableNetBt() :: NetBios option for [00000016] Intel(R) X540 Virtual Function is already set to 2
3922019-10-18 04:11:21+00:00 :: CloudBaseNetworkHelper:setNetworkInterfaceName() :: No implementation of setNetworkInterfaceName for cloudbaseinit
3932019-10-18 04:11:26+00:00 :: nvcloudinit:isAWSEnv() :: Exception trying to query AWS metadata :System.Net.WebException: The operation has timed out
394 at System.Net.HttpWebRequest.GetResponse()
395 at nvcloudinit.nvcloudinit.isAWSEnv()
3962019-10-18 04:11:26+00:00 :: CloudBaseNetworkHelper:setSecondayNetworkIPsBasedInt() :: No implementation of setSecondayNetworkIPsBasedInt for cloudbaseinit
3972019-10-18 04:11:26+00:00 :: ActivationHelper:getLicenseStatus() :: Determining Windows licensing details and status
3982019-10-18 04:11:26+00:00 :: ActivationHelper:getLicenseStatus() :: Windows(R), ServerStandardEval edition: Windows(R) Operating System, TIMEBASED_EVAL channel
3992019-10-18 04:11:26+00:00 :: ActivationHelper:getLicenseStatus() :: License status: Licensed
4002019-10-18 04:11:26+00:00 :: ActivationHelper:getLicenseStatus() :: Grace period remaining: 123d 5h
4012019-10-18 04:11:26+00:00 :: ActivationHelper:getLicenseStatus() :: Name:
4022019-10-18 04:11:26+00:00 :: ActivationHelper:getLicenseStatus() :: Port:0
4032019-10-18 04:11:26+00:00 :: nvcloudinit:OnStart() :: STATUS : Windows is already activated
4042019-10-18 04:11:26+00:00 :: DomainHelper:getDomainFromUserdata() :: No DomainName found in the json userdata blob
4052019-10-18 04:11:26+00:00 :: DomainHelper:getUserFromUserdata() :: No DomainUser found in the json userdata blob
4062019-10-18 04:11:26+00:00 :: DomainHelper:getPasswordFromUserdata() :: No DomainPW found in the json userdata blob
4072019-10-18 04:11:26+00:00 :: nvcloudinit:OnStart() :: No domain specified in userdata, so no action for joining the domain
4082019-10-18 04:11:26+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputdiskList.txt]
4092019-10-18 04:11:26+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
4102019-10-18 04:11:26+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
4112019-10-18 04:11:26+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
4122019-10-18 04:11:26+00:00 :: DiskInitializationHelper:initializeDisk() :: Checking Disk 0 ...
4132019-10-18 04:11:26+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt > outputpartitionList.txt]
4142019-10-18 04:11:27+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT :
4152019-10-18 04:11:27+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
4162019-10-18 04:11:27+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : 0
4172019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() :: -------------------Logging diskpart partion output -------------------
4182019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() ::
4192019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() :: Microsoft DiskPart version 6.3.9600
4202019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() ::
4212019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() :: Copyright (C) 1999-2013 Microsoft Corporation.
4222019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() :: On computer: BASEGAME-NGN-WK
4232019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() ::
4242019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() :: Disk 0 is now the selected disk.
4252019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() ::
4262019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() :: Partition ### Type Size Offset
4272019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() :: ------------- ---------------- ------- -------
4282019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() :: Partition 1 Primary 2000 MB 1024 KB
4292019-10-18 04:11:27+00:00 :: DiskInitializationHelper:checkPartition() :: --------------------------------------------------------------------
4302019-10-18 04:11:27+00:00 :: DiskInitializationHelper:initializeDisk() :: Disk 0 allready initialized, skipping disk
4312019-10-18 04:11:27+00:00 :: nvcloudinit:OnStart() :: Completed initializing disk
4322019-10-18 04:11:27+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Extending volume 'C' on unallocated space
4332019-10-18 04:11:27+00:00 :: DiskInitializationHelper:extendVolume() :: Extending volume 'C'
4342019-10-18 04:11:27+00:00 :: ActivationHelper:ExecuteCommandSync() :: Executing command : [diskpart /s state.txt]
4352019-10-18 04:11:27+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDOUT : Microsoft DiskPart version 6.3.9600
436
437Copyright (C) 1999-2013 Microsoft Corporation.
438On computer: BASEGAME-NGN-WK
439
440Volume 2 is the selected volume.
441
442There is not enough usable free space on specified disk(s) to extend the
443volume.
4442019-10-18 04:11:27+00:00 :: ActivationHelper:ExecuteCommandSync() :: STDERR :
4452019-10-18 04:11:27+00:00 :: ActivationHelper:ExecuteCommandSync() :: Return code : -2147024809
4462019-10-18 04:11:27+00:00 :: DiskInitializationHelper:extendAllVolumes() :: Skipping extend for volume 'D'
4472019-10-18 04:11:27+00:00 :: nvcloudinit:OnStart() :: Completed extending volumes
4482019-10-18 04:11:27+00:00 :: InitHelper:GetInitScriptList() :: List of init scripts to execute in the order below :
4492019-10-18 04:11:27+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat
4502019-10-18 04:11:27+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat
4512019-10-18 04:11:27+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat
4522019-10-18 04:11:27+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat
4532019-10-18 04:11:27+00:00 :: InitHelper:GetInitScriptList() :: C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat
4542019-10-18 04:11:27+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'C:\Asgard\nvcloudinit\scripts\per-boot\C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat'
4552019-10-18 04:11:50+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
456C:\Asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
457REM --- Registering instance and generating CD config file
458
459C:\Asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe C:\Asgard\services\AWS-CodeDeploy-Agent\deploy\ngn_codedeploy_register.py || GOTO :ERROR
4602019-10-18 16:11:28,312 DEBUG __main__ - Attempting to acquire lock at: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
4612019-10-18 16:11:28,312 DEBUG __main__ - Lock acquired: C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
4622019-10-18 16:11:28,312 INFO __main__ - Querying the CS for my CD props
4632019-10-18 16:11:28,312 DEBUG asgard_util_local.local - Reading file contents from: C:\Asgard\conf\userdata.txt
4642019-10-18 16:11:28,312 INFO __main__ - Trying to use vm_type_info from user-data for new CD mappings
4652019-10-18 16:11:28,312 INFO __main__ - Use the props from user-data
4662019-10-18 16:11:28,312 DEBUG asgard_util_local.local - Reading file contents from: C:\Asgard\conf\userdata.txt
4672019-10-18 16:11:28,312 INFO __main__ - CDInstanceSettings:
468{
469 "instance_name": "BasePM-ND-NIDR-01-10.244.4.236",
470 "policy_doc": "{\"Version\": \"2012-10-17\",\"Statement\": [{\"Action\": [\"s3:GetObject\", \"s3:ListBucket\"],\"Effect\": \"Allow\",\"Resource\": \"arn:aws:s3:::*\"}]}",
471 "my_ip": "10.244.4.236",
472 "service_name": "BasePM",
473 "master_sak": "vNz7zhKuRoChOC/uvzdwQgQuhXY+wTq8CAdJQGuQ",
474 "tags": {
475 "deployment-group-id": "BasePM-ND-NIDR-01-10.244.4.236",
476 "deployment-group": "BasePM-ND-NIDR-01"
477 },
478 "master_ak": "AKIAJTNPBB4F3KOMO3NQ",
479 "test_bucket_name": "nvidia-codedeploy-us-west-1",
480 "group_name": "BasePM-ND-NIDR-01",
481 "do_key_cleanup": true,
482 "iam_user_name": "NGN-BasePM-ND-NIDR-01-10.244.4.236",
483 "vpc_id": "ND-NIDR-01",
484 "iam_user_path": "/AWS/CodeDeploy/",
485 "policy_name": "codedeploy-agent",
486 "region": "us-west-1"
487}
4882019-10-18 16:11:28,467 INFO __main__ - Querying for IAM user: NGN-BasePM-ND-NIDR-01-10.244.4.236
4892019-10-18 16:11:30,078 INFO __main__ - User NGN-BasePM-ND-NIDR-01-10.244.4.236 does not exist. Creating it
4902019-10-18 16:11:30,233 INFO __main__ - Created user NGN-BasePM-ND-NIDR-01-10.244.4.236 with arn arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BasePM-ND-NIDR-01-10.244.4.236
4912019-10-18 16:11:30,233 INFO __main__ - Adding/updating S3 Policy to IAM user NGN-BasePM-ND-NIDR-01-10.244.4.236
4922019-10-18 16:11:30,342 INFO __main__ - Current CD config credentials are invalid, recreating them
4932019-10-18 16:11:30,437 INFO __main__ - Creating new keys for user NGN-BasePM-ND-NIDR-01-10.244.4.236
4942019-10-18 16:11:30,562 INFO __main__ - Created new keys, verifying them
4952019-10-18 16:11:30,812 INFO __main__ - Still waiting for S3 AWS API to accept the new user keys. Sleeping for 10 seconds...
4962019-10-18 16:11:40,890 INFO __main__ - Verified AWS S3 API accepted the new keys
4972019-10-18 16:11:40,953 INFO __main__ - Registering on-prem instance BasePM-ND-NIDR-01-10.244.4.236 with arn arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BasePM-ND-NIDR-01-10.244.4.236
4982019-10-18 16:11:41,171 INFO __main__ - Calling wrapper_fn with tags [{'Value': 'BasePM-ND-NIDR-01-10.244.4.236', 'Key': 'deployment-group-id'}, {'Value': 'BasePM-ND-NIDR-01', 'Key': 'deployment-group'}]
4992019-10-18 16:11:41,233 INFO __main__ - Checking if AWS CD config file needs to be updated
5002019-10-18 16:11:41,233 INFO __main__ - Updating existing config {'region': 'us-west-1', 'aws_access_key_id': 'AKIAXVN5G5FB5YWNJML5', 'aws_secret_access_key': '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', 'iam_user_arn': 'arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BasePM-ND-NIDR-01-10.244.4.234'} with new values {'region': 'us-west-1', 'aws_access_key_id': 'AKIAXVN5G5FBWISKHF4U', 'aws_secret_access_key': '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', 'iam_user_arn': 'arn:aws:iam::527067179331:user/AWS/CodeDeploy/NGN-BasePM-ND-NIDR-01-10.244.4.236'}
5012019-10-18 16:11:41,233 INFO __main__ - Restarting codedeploy service, could take 60-90 seconds
5022019-10-18 16:11:41,233 INFO __main__ - Verified codedeployagent service isn't in a pending state
5032019-10-18 16:11:41,233 INFO __main__ - Restarting CDA
5042019-10-18 16:11:45,390 INFO __main__ - Successfully deleted C:\asgard\conf\AWS-CodeDeploy-Agent\lock.txt
5052019-10-18 16:11:45,390 INFO __main__ - ngn_codedeploy_register exiting with RC 0
506REM --- Starting AWS-CodeDeploy-Agent
507
508C:\Asgard\nvcloudinit\scripts\per-boot>powershell.exe Start-Service codedeployagent || GOTO :ERROR
509REM --- Successfully configured and started CodeDeploy-Agent. start.bat exiting with RC 0
510
5112019-10-18 04:11:50+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' :
5122019-10-18 04:11:50+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-AWS-CodeDeploy-Agent.bat' : 0
5132019-10-18 04:11:50+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'C:\Asgard\nvcloudinit\scripts\per-boot\C:\Asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat'
5142019-10-18 04:11:51+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' :
515C:\Asgard\nvcloudinit\scripts\per-boot>call c:\Asgard\conf\asgard_env.bat
516
517C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_DIR=C:\Asgard\conf\ZabbixAgent
518
519C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_TARGET_FILE=C:\Asgard\conf\ZabbixAgent\zabbix_agentd.conf
520
521C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_TARGET_DIR=C:\Asgard\services\ZabbixAgent
522
523C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SCRIPT=C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py
524
525C:\Asgard\nvcloudinit\scripts\per-boot>SET ZAB_AGENT_CONF_SRC_FILE=C:\Asgard\services\ZabbixAgent\zabbix_agentd.conf
526
527C:\Asgard\nvcloudinit\scripts\per-boot>REM --- Generate the config file
528
529C:\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
530Creating logger...
5312019-10-18 16:11:50,499 INFO __main__ - Starting
5322019-10-18 16:11:51,608 INFO __main__ - Detected that this VM is hosted in NGN
5332019-10-18 16:11:51,640 ERROR __main__ - Unhandled top level exception
534Traceback (most recent call last):
535 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 493, in <module>
536 rc = main(options.input_template, options.output_template)
537 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 434, in main
538 config_vals['[[HOSTNAME]]'] = get_zabbix_hostname()
539 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 420, in get_zabbix_hostname
540 zone_name = get_zone_name()
541 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 410, in get_zone_name
542 cs_creds_dict = read_cs_credentials()
543 File "C:\Asgard\services\ZabbixAgent\generate_zabbix_conf.py", line 251, in read_cs_credentials
544 raise Exception("No section 'cs-credentials' in userdata.txt")
545Exception: No section 'cs-credentials' in userdata.txt
5462019-10-18 16:11:51,640 INFO __main__ - Done, returning -1
547
548C:\Asgard\nvcloudinit\scripts\per-boot>SET RC=-1
549"ERROR: Failed to populate config file"
550
5512019-10-18 04:11:51+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).
552
5532019-10-18 04:11:51+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\009-ZabbixAgent.bat' : -1
5542019-10-18 04:11:51+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'
5552019-10-18 04:11:52+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"
556"common_env_on_boot.bat calling C:\Python27\python.exe "C:\Asgard\services\CommonEnvironment\bin\setup_environment.py""
557
558C:\Asgard\nvcloudinit\scripts\per-boot>C:\Python27\python.exe "C:\Asgard\services\CommonEnvironment\bin\setup_environment.py" || GOTO :SHOWERR
559setup_environment starting ...
560Detected platform:
561 Windows ......... : True
562 Strict Windows OS : True
563 Cygwin .......... : False
564 Posix ........... : False
565 Linux ........... : False
566Checking file: C:\Asgard\services\CommonEnvironment\resources\windows\ag_env.bat ...
567All required files validated
568Setting up common environment ...
569Reading SDB value from zone property cache
570WARNING: Failed to lookup AG_STATEDB from subnet-info and ZoneProperties. Leaving any currently set value for AG_STATEDB intact
571Creating common directories ...
572C:\Asgard already exists; skipping
573C:\Asgard\logs already exists; skipping
574C:\Asgard\services already exists; skipping
575C:\Asgard\conf already exists; skipping
576Saving environment ({'AG_HOME': 'C:\\Asgard', 'AG_LOGS': 'C:\\Asgard\\logs'}) to: C:\Asgard\conf\asgard_env.bat ...
577Wrote environment setup file: C:\Asgard\conf\asgard_env.bat
578
579C:\Asgard\nvcloudinit\scripts\per-boot>GOTO :DONE
580---- common_env_on_boot.bat was successful
581
5822019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' :
5832019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\010-common_env_on_boot.bat' : 0
5842019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'C:\Asgard\nvcloudinit\scripts\per-boot\C:\Asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat'
5852019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
586C:\Asgard\nvcloudinit\scripts\per-boot>REM
587
588C:\Asgard\nvcloudinit\scripts\per-boot>REM Enables the Asgard OSL LogPruner
589
590C:\Asgard\nvcloudinit\scripts\per-boot>REM
591
592C:\Asgard\nvcloudinit\scripts\per-boot>SET PYTHON_PATH=C:\Python27\python.exe
593
594C:\Asgard\nvcloudinit\scripts\per-boot>IF EXIST C:\Python27\python.exe GOTO FOUND_PYTHON
595
596C:\Asgard\nvcloudinit\scripts\per-boot>IF NOT "C:\Asgard" == "" GOTO SCHEDULE
597
598C:\Asgard\nvcloudinit\scripts\per-boot>SET INSTALL_DIR=C:\Asgard\services\LogPruner
599
600C:\Asgard\nvcloudinit\scripts\per-boot>SET TASK_NAME="Asgard OSL LogPruner"
601
602C:\Asgard\nvcloudinit\scripts\per-boot>REM Create the task
603
604C:\Asgard\nvcloudinit\scripts\per-boot>schtasks /Query /TN "Asgard OSL LogPruner" || GOTO CREATE_TASK
605
606Folder: \
607TaskName Next Run Time Status
608======================================== ====================== ===============
609Asgard OSL LogPruner 10/18/2019 6:00:00 PM Ready
610
611C:\Asgard\nvcloudinit\scripts\per-boot>schtasks /End /TN "Asgard OSL LogPruner" || GOTO :EOF
612SUCCESS: The scheduled task "Asgard OSL LogPruner" has been terminated successfully.
613
614C:\Asgard\nvcloudinit\scripts\per-boot>schtasks /Delete /F /TN "Asgard OSL LogPruner" || GOTO :EOF
615SUCCESS: The scheduled task "Asgard OSL LogPruner" was successfully deleted.
616
617C:\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
618SUCCESS: The scheduled task "Asgard OSL LogPruner" has successfully been created.
619
6202019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' :
6212019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\161-LogPruner.bat' : 0
6222019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: Executing init script 'C:\Asgard\nvcloudinit\scripts\per-boot\C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat'
6232019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: STDOUT of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' :
624C:\Asgard\nvcloudinit\scripts\per-boot>c:\python27\python.exe C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.py
625****** Executing command : net stop nxlog
626****** Return code of the command: 0
627****** Stdout of the execution :
628The nxlog service was stopped successfully.
629C:\Asgard
630{"primary_ip": "10.244.4.236", "vm_type_name": "PM", "service_name": "BasePM"}
631
632Falling back to see if nvcloudinit based config can be read to get update server location
633Falling back to see if nvcloudinit based config is encoded in mime format
634UserData value/empty for Key : LoggingServerAddresses not found
635Falling back to see if nvcloudinit based config can be read to get update server location
636Falling back to see if nvcloudinit based config is encoded in mime format
637UserData value/empty for Key : LoggingServerAddresses not found
638
6392019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: STDERR of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : Traceback (most recent call last):
640 File "C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 147, in <module>
641 log_address = getLogServer()
642 File "C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 46, in getLogServer
643 config_data = read_from_cs("/zone_properties")
644 File "C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.py", line 32, in read_from_cs
645 config_service = ConfigService(username=username)
646 File "c:\asgard\framework-python\asgard_util_cs\config_service.py", line 79, in __init__
647 credentials = get_credentials_from_cloudinit_userdata()
648 File "c:\asgard\framework-python\asgard_util_cs\config_service.py", line 61, in get_credentials_from_cloudinit_userdata
649 raise MissingCredentialsException("User-data can't be read or does not contain any Config Service credentials")
650asgard_util_cs.config_service.MissingCredentialsException: User-data can't be read or does not contain any Config Service credentials
651
6522019-10-18 04:11:52+00:00 :: InitHelper:ExecuteScript() :: RETCODE of running script 'C:\Asgard\nvcloudinit\scripts\per-boot\config_nxlog.bat' : 1
6532019-10-18 04:11:52+00:00 :: InitHelper:ProcessInitScripts() :: Completed processing/executing init scripts found in the system
6542019-10-18 04:11:52+00:00 :: RegistryWriter:WriteNVCRegistry() :: Setting regkey for nvcloudinit @Execution
6552019-10-18 04:11:52+00:00 :: nvcloudinit:OnStart() :: NVCloudInit stopping
6562019-10-18 04:11:52+00:00 :: nvcloudinit:OnStop() :: Service Stopping