VMware Cloud Community
Pegasjus
Contributor
Contributor

VUM returns error 99 until debug is enabled then runs perfectly

Hi,

I have a small issue with VUM on esxi 4.1 (vsphere 4.1)

vsphere server: 4.1.0.491557

vum is 4.1U2 4.1.0.8977

When running the vum scan I get an error 99. I was looking into this and found the following kb:

http://kb.vmware.com/selfservice/search.do?cmd=displayKC&docType=kc&externalId=1030330

I am on update 2. but I checked the file and found also from one working system, which was base esxi 4.1u2.

On this system the scanner works ok and system was patched to a latest version this weekend.

I have compared the folder structure and the esxupdate.conf file and they are similar.

So I enabled debug mode on the troublesome system and suddenly I can scan successfully. If I remove the debug path and it does gives me error 99 again.

Anybody have a suggestion for this?

The idea is to scan and remediate this weekend and see if this does something but I would love to know if I am missing something here. I am attaching the esxupdate.conf file if anybody wants to see and finds something strange.

[platform]

product = embeddedEsx

version = 4.1.0

build = 582267

[visor]

bootbankdir = /bootbank

altbootbankdir = /altbootbank

lockerdir = /locker

packagingdir = /tmp/stage

stagedir = /tmp/stage/firmware

oemstgdir = /tmp/stage/oemstage

customstgdir = /tmp/stage/customstage

distribution = ESXi

helperscript = /sbin/bootbankstage-install.sh

ipkgconf = /tmp/ipkg.conf

rootdb = /usr/lib/ipkg/status

[log]

level = DEBUG

file = /var/tmp/esxupdate.debug

size = 1024000

[default]

# Float # of seconds to timeout for downloads, 0.0 = no timeout

timeout = 30.0

retry = 5

cachedir = /var/tmp/cache

defaultcachemb = 900

mincachemb = 100

Kind regards

Martin

Tags (2)
Reply
0 Kudos
2 Replies
Pegasjus
Contributor
Contributor

So... to add a little, same story, updated the server today again via commandline with update 3. After doing this and rebooting hoping it had fixed the issue I get the below output (same result). Anybody got an idea?

My guess is that ultimately it is looking for a file that it does not find, though, no idea what file:

[2012-09-23  11:46:12:909 'SingleHostScanTask.SingleHostScanTask{67}' 1324 ERROR]   [singleHostScanTask, 321] SingleHostScan caught exception: esxupdate  error, version: 1.30, operation:
99: (2, 'No such file or directory')

------------------------------------------------------
[2012-09-23 11:46:11:315 'Activation' 4656 INFO]  [activationValidator, 368] Leave Validate. Succeeded for integrity.UpdateManager.scan on target: Integrity.UpdateManager
[2012-09-23 11:46:11:534 'VciScanTask.ScanTask{62}' 4656 INFO]  [vciTaskBase, 512] Save the VC task info into the database: task-4359
[2012-09-23 11:46:11:534 'Activation.trace' 4656 DEBUG]  [activationValidator, 1094] Invoke done: integrity.UpdateManager.scan session: 30FAC3E5-9A9F-41E3-864E-33F9E2744AA0
Result:
'vim.Task:task-4359'
[2012-09-23 11:46:11:549 'InventoryMonitor' 4656 INFO]  [InventoryMonitor, 437] ProcessUpdate, Enter, Update version :=  96
[2012-09-23 11:46:11:549 'JobDispatcher' 4656 DEBUG]  [JobDispatcher, 1583] ProcessUpdate
[2012-09-23 11:46:11:643 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 1
[2012-09-23 11:46:11:643 'JobDispatcher' 260 INFO]  [JobDispatcher, 1815] Scheduling task ScanTask{62}
[2012-09-23 11:46:11:643 'JobDispatcher' 260 INFO]  [JobDispatcher, 472] Starting task ScanTask{62}
[2012-09-23 11:46:11:643 'VciScanTask.ScanTask{62}' 260 INFO]  [vciTaskBase, 1291] VciTask { id: ScanTask{62}, type: com.vmware.vcIntegrity.ScanTask }: Setting VC task state to: running
[2012-09-23 11:46:11:659 'VciScanTask.ScanTask{62}' 1324 INFO]  [vciTaskBase, 530] Task started...
[2012-09-23 11:46:11:659 'InventoryTree' 1324 DEBUG]  [InventoryTree, 1629] Locking host-272
[2012-09-23 11:46:11:659 'entityLocker' 1324 INFO]  [entityLocker, 77] Entities to be locked: 1, entities actually locked: 1
[2012-09-23 11:46:11:659 'VciScanTask.ScanTask{62}' 1324 INFO]  [vciTaskBase, 355] Subtask added: VciHostScanTask{63}
[2012-09-23 11:46:11:659 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 2
[2012-09-23 11:46:11:659 'JobDispatcher' 260 INFO]  [JobDispatcher, 1815] Scheduling task VciHostScanTask{63}
[2012-09-23 11:46:11:659 'JobDispatcher' 260 INFO]  [JobDispatcher, 472] Starting task VciHostScanTask{63}
[2012-09-23 11:46:11:690 'VciHostScanTask.VciHostScanTask{63}' 1324 INFO]  [vciTaskBase, 530] Task started...
[2012-09-23 11:46:11:768 'HostLocator' 1324 INFO]  [hostLocator, 607] Checking Power State for Host hostname
[2012-09-23 11:46:11:924 'VciHostScanTask.VciHostScanTask{63}' 1324 INFO]  [vciHostScanTask, 542] Add scan host: host-272
[2012-09-23 11:46:12:143 'SingleHostUpgradeScanTask.SingleHostUpgradeScanTask{66}' 1324 INFO]  [singleHostUpgradeScanTask, 99] Host upgrade scan task created: Host ID = host-272
[2012-09-23 11:46:12:143 'ChainedTaskContainer.ChainedTaskContainer{65}' 1324 INFO]  [vciTaskBase, 355] Subtask added: SingleHostUpgradeScanTask{66}
[2012-09-23 11:46:12:143 'SingleHostScanTask.SingleHostScanTask{67}' 1324 DEBUG]  [vciTaskBase, 1084] No authentication data for current VC user
[2012-09-23 11:46:12:143 'ChainedTaskContainer.ChainedTaskContainer{65}' 1324 INFO]  [vciTaskBase, 355] Subtask added: SingleHostScanTask{67}
[2012-09-23 11:46:12:143 'VciHostScanTask.VciHostScanTask{63}' 1324 INFO]  [vciTaskBase, 355] Subtask added: ChainedTaskContainer{65}
[2012-09-23 11:46:12:143 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 3
[2012-09-23 11:46:12:143 'InventoryTree' 260 DEBUG]  [InventoryTree, 1629] Locking host-272
[2012-09-23 11:46:12:143 'entityLocker' 260 INFO]  [entityLocker, 77] Entities to be locked: 1, entities actually locked: 1
[2012-09-23 11:46:12:143 'JobDispatcher' 260 INFO]  [JobDispatcher, 1815] Scheduling task ChainedTaskContainer{65}
[2012-09-23 11:46:12:143 'JobDispatcher' 260 INFO]  [JobDispatcher, 472] Starting task ChainedTaskContainer{65}
[2012-09-23 11:46:12:143 'ChainedTaskContainer.ChainedTaskContainer{65}' 1324 INFO]  [vciTaskBase, 530] Task started...
[2012-09-23 11:46:12:143 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 4
[2012-09-23 11:46:12:143 'JobDispatcher' 260 INFO]  [JobDispatcher, 1815] Scheduling task SingleHostUpgradeScanTask{66} that imposes 1 for ESX: host-272 new load: 1 that imposes 1 for VMware vCenter Update Manager new load: 1
[2012-09-23 11:46:12:143 'JobDispatcher' 260 INFO]  [JobDispatcher, 472] Starting task SingleHostUpgradeScanTask{66}
[2012-09-23 11:46:12:143 'SingleHostUpgradeScanTask.SingleHostUpgradeScanTask{66}' 1324 INFO]  [vciTaskBase, 530] Task started...
[2012-09-23 11:46:12:143 'SingleHostUpgradeScanTask.SingleHostUpgradeScanTask{66}' 1324 INFO]  [singleHostUpgradeScanTask, 173] Starting scan of vim.HostSystem:host-272
[2012-09-23 11:46:12:299 'SingleHostUpgradeScanTask.SingleHostUpgradeScanTask{66}' 1324 INFO]  [singleHostUpgradeScanTask, 307] Found 0 upgrade releases to scan for
[2012-09-23 11:46:12:330 'vcidbStoredProcEntry' 1324 INFO]  [vcidbStoredProcEntry, 108] Start calling vci_compBaselineComplianceLeaf(host-272, 1, 0, 1)
[2012-09-23 11:46:12:362 'vcidbStoredProcEntry' 1324 INFO]  [vcidbStoredProcEntry, 125] Elapsed time for compBaselineComplianceLeaf (host-272, 1, 0, 1) = 31 msec
[2012-09-23 11:46:12:362 'vcidbStoredProcEntry' 1324 INFO]  [vcidbStoredProcEntry, 149] Succeeded: calling vci_compBaselineComplianceLeaf(host-272, 1, 0, 1)
[2012-09-23 11:46:12:362 'SingleHostUpgradeScanTask' 1324 INFO]  [singleHostUpgradeScanTask, 219] Saved compliance
[2012-09-23 11:46:12:377 'ChainedTaskContainer.ChainedTaskContainer{65}' 1324 INFO]  [vciTaskBase, 579] A subTask finished: SingleHostUpgradeScanTask{66}
[2012-09-23 11:46:12:377 'SingleHostUpgradeScanTask.SingleHostUpgradeScanTask{66}' 1324 INFO]  [vciTaskBase, 891] Task finished, state: 2
[2012-09-23 11:46:12:393 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 5
[2012-09-23 11:46:12:393 'JobDispatcher' 260 INFO]  [JobDispatcher, 1815] Scheduling task SingleHostScanTask{67} that imposes 3 for ESX: host-272 new load: 4 that imposes 2 for VMware vCenter Update Manager new load: 3
[2012-09-23 11:46:12:393 'JobDispatcher' 260 INFO]  [JobDispatcher, 472] Starting task SingleHostScanTask{67}
[2012-09-23 11:46:12:393 'JobDispatcher' 1324 INFO]  [JobDispatcher, 1875]  Removing task SingleHostUpgradeScanTask{66} that imposed ESX load:1 for esx host-272, new load: 3 that imposed VMware vCenter Update Manager load:1, new load: 2
[2012-09-23 11:46:12:393 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 4
[2012-09-23 11:46:12:393 'SingleHostScanTask.SingleHostScanTask{67}' 1324 INFO]  [vciTaskBase, 530] Task started...
[2012-09-23 11:46:12:440 'HostLocator' 1324 INFO]  [hostLocator, 607] Checking Power State for Host hostname
[2012-09-23 11:46:12:627 'HostUpdateConfig' 1324 INFO]  [hostUpdateConfig, 252] Allowing direct access to webserver
[2012-09-23 11:46:12:627 'HostUpdateConfig' 1324 INFO]  [hostUpdateConfig, 269] using default for host updates url suffix
[2012-09-23 11:46:12:627 'HostUpdateConfig' 1324 INFO]  [hostUpdateConfig, 284] Patch depot url: http://10.176.1.15:9084/vci/hostupdates/hostupdate
[2012-09-23 11:46:12:643 'HostUpdateDepotManager' 1324 INFO]  [utilsImpl, 413] Unzipped file size of archive D:\vCenter_Patches\Data\hostupdate\csco\metadata\csco-VEM-4.1.0-metadata.zip is: 9453, which contains 7 files.
[2012-09-23 11:46:12:659 'HostUpdateDepotManager' 1324 INFO]  [utilsImpl, 413] Unzipped file size of archive D:\vCenter_Patches\Data\hostupdate\vmw\metadata\vmw-ESXi-4.1.0-metadata.zip is: 71908, which contains 7 files.
[2012-09-23 11:46:12:659 'scanHost20' 1324 INFO]  [scannerImpl, 144] Add metadata url: http://10.176.1.15:9084/vci/hostupdates/hostupdate/csco/metadata/csco-VEM-4.1.0-metadata.zip
[2012-09-23 11:46:12:659 'scanHost20' 1324 INFO]  [scannerImpl, 144] Add metadata url: http://10.176.1.15:9084/vci/hostupdates/hostupdate/vmw/metadata/vmw-ESXi-4.1.0-metadata.zip
[2012-09-23 11:46:12:659 'SetFirewallConfig' 1324 INFO]  [hostUpdateBase, 764] FirewallSystem not supported on host
[2012-09-23 11:46:12:659 'VcTaskMonitor' 1324 DEBUG]  [vcTaskMonitor, 87] VcTaskMonitor started for session[1D75F3E1-AD0A-4979-B62B-50B606A582EA]3042CE96-E896-489E-B513-A43D3C8A8B0C
[2012-09-23 11:46:12:659 'InventoryMonitor' 4656 INFO]  [InventoryMonitor, 437] ProcessUpdate, Enter, Update version :=  97
[2012-09-23 11:46:12:659 'VcTaskMonitor' 4656 DEBUG]  [vcTaskMonitor, 252] ProcessUpdate session[1D75F3E1-AD0A-4979-B62B-50B606A582EA]3042CE96-E896-489E-B513-A43D3C8A8B0C
[2012-09-23 11:46:12:659 'VcTaskMonitor' 1324 DEBUG]  [vcTaskMonitor, 142] VcTaskFilterWrapper started for session[1D75F3E1-AD0A-4979-B62B-50B606A582EA]3042CE96-E896-489E-B513-A43D3C8A8B0C
[2012-09-23 11:46:12:830 'InventoryMonitor' 4656 INFO]  [InventoryMonitor, 437] ProcessUpdate, Enter, Update version :=  98
[2012-09-23 11:46:12:830 'VcTaskMonitor' 4656 DEBUG]  [vcTaskMonitor, 252] ProcessUpdate session[1D75F3E1-AD0A-4979-B62B-50B606A582EA]3042CE96-E896-489E-B513-A43D3C8A8B0C
[2012-09-23 11:46:12:830 'InventoryMonitor' 4656 INFO]  [InventoryMonitor, 437] ProcessUpdate, Enter, Update version :=  99
[2012-09-23 11:46:12:830 'VcTaskMonitor' 4656 DEBUG]  [vcTaskMonitor, 252] ProcessUpdate session[1D75F3E1-AD0A-4979-B62B-50B606A582EA]3042CE96-E896-489E-B513-A43D3C8A8B0C
[2012-09-23 11:46:12:830 'VcTaskMonitor' 4656 DEBUG]  [vcTaskMonitor, 355] ProcessUpdate task finished session[1D75F3E1-AD0A-4979-B62B-50B606A582EA]3042CE96-E896-489E-B513-A43D3C8A8B0C
[2012-09-23 11:46:12:830 'VcTaskMonitor' 1324 DEBUG]  [vcTaskMonitor, 172] VcTaskFilterWrapper destroyed for session[1D75F3E1-AD0A-4979-B62B-50B606A582EA]3042CE96-E896-489E-B513-A43D3C8A8B0C
[2012-09-23 11:46:12:830 'VcTaskMonitor' 1324 DEBUG]  [vcTaskMonitor, 60] VcTaskMonitor destroyed for session[1D75F3E1-AD0A-4979-B62B-50B606A582EA]3042CE96-E896-489E-B513-A43D3C8A8B0C
[2012-09-23 11:46:12:830 'scanHost20' 1324 INFO]  [scannerImpl, 187] Scan Complete, disabling ruleset now...
[2012-09-23 11:46:12:830 'SetFirewallConfig' 1324 INFO]  [hostUpdateBase, 743] No changes to firewall configuration for: updateManager
[2012-09-23 11:46:12:830 'scanHost20' 1324 INFO]  [scannerImpl, 191] Ruleset disabled
[2012-09-23 11:46:12:830 'HostUpdateDepotManager' 1324 ERROR]  [scanHost20, 348] result for host: hostname (entity: host-272) shows error :
-----Result Begin (hostname)-----
<esxupdate-response>
  <version>1.30</version>
  <error errorClass="error">
    <errorCode>99</errorCode>
    <errorDesc>(2, 'No such file or directory')</errorDesc>
  </error>
</esxupdate-response>

-----Result End (hostname)-----
[2012-09-23 11:46:12:830 'HostUpdateDepotManager' 1324 ERROR]  [scanHost20, 392] esx update returned errors
[2012-09-23 11:46:12:909 'SingleHostScanTask.SingleHostScanTask{67}' 1324 ERROR]  [singleHostScanTask, 321] SingleHostScan caught exception: esxupdate error, version: 1.30, operation:
99: (2, 'No such file or directory')

with code: 129
[2012-09-23 11:46:12:971 'SingleHostScanTask.SingleHostScanTask{67}' 1324 ERROR]  [vciTaskBase, 548] Task execution has failed: integrity.fault.HostPatchESXUpdateFailure
[2012-09-23 11:46:12:987 'ChainedTaskContainer.ChainedTaskContainer{65}' 1324 INFO]  [vciTaskBase, 579] A subTask finished: SingleHostScanTask{67}
[2012-09-23 11:46:12:987 'VciHostScanTask.VciHostScanTask{63}' 1324 INFO]  [vciTaskBase, 579] A subTask finished: ChainedTaskContainer{65}
[2012-09-23 11:46:13:002 'VciScanTask.ScanTask{62}' 1324 INFO]  [vciTaskBase, 579] A subTask finished: VciHostScanTask{63}
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 INFO]  [statusCollector, 411] Cache size: 0
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 214] cache update is starting, updateRequestTimeStamp = 5159683961000
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 218] cache update succeeds, updateRequestTimeStamp = 5160072914000
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 INFO]  [statusCollector, 473] Group cache size: 0
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 214] cache update is starting, updateRequestTimeStamp = 5159683961000
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 218] cache update succeeds, updateRequestTimeStamp = 5160072914000
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 INFO]  [statusCollector, 411] Cache size: 1
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 214] cache update is starting, updateRequestTimeStamp = 5159683961000
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 218] cache update succeeds, updateRequestTimeStamp = 5160072914000
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 INFO]  [statusCollector, 473] Group cache size: 1
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 411] Cache size: 1
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 415] Updated cache size: 1
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 214] cache update is starting, updateRequestTimeStamp = 5159684023000
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 218] cache update succeeds, updateRequestTimeStamp = 5160072914000
[2012-09-23 11:46:13:002 'Activation.trace' 4608 DEBUG]  [activationValidator, 1028]
------------------------------------------------------
Invoking GetStatus on integrity.BaselineComplianceStatusCollector:session[30FAC3E5-9A9F-41E3-864E-33F9E2744AA0]DCC2FA25-C32B-4C80-BAA7-C15F379EF75E session 30FAC3E5-9A9F-41E3-864E-33F9E2744AA0
------------------------------------------------------
[2012-09-23 11:46:13:002 'Activation' 4608 INFO]  [activationValidator, 195] Leave Validate. Succeeded forintegrity.BaselineComplianceStatusCollector.GetStatus on session private target: session[30FAC3E5-9A9F-41E3-864E-33F9E2744AA0]DCC2FA25-C32B-4C80-BAA7-C15F379EF75E
[2012-09-23 11:46:13:002 'Activation.trace' 4608 DEBUG]  [activationValidator, 1094] Invoke done: integrity.BaselineComplianceStatusCollector.GetStatus session: 30FAC3E5-9A9F-41E3-864E-33F9E2744AA0
Result:
(integrity.BaselineComplianceStatus) [
   (integrity.BaselineComplianceStatus) {
      dynamicType = <unset>,
      key = 12,
      status = "NotCompliant",
   }
]
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 4656 DEBUG]  [statusCollector, 99] start to update status collector cache
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 4656 DEBUG]  [statusCollector, 103] status update request time in cache = 5160072914000 status update request time = 5160072914000
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 4656 DEBUG]  [statusCollector, 110] finish updating status collector cache
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 INFO]  [statusCollector, 411] Cache size: 1
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 214] cache update is starting, updateRequestTimeStamp = 5159684023000
[2012-09-23 11:46:13:002 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 218] cache update succeeds, updateRequestTimeStamp = 5160072914000
[2012-09-23 11:46:13:002 'Activation' 4656 INFO]  [activationValidator, 195] Leave Validate. Succeeded forvmodl.query.PropertyCollector.Filter.GetSpec on session private target: session[30FAC3E5-9A9F-41E3-864E-33F9E2744AA0]A76C7E64-D276-4B06-946A-8EE911714081
[2012-09-23 11:46:13:018 'ComplianceStatusMgr' 1324 INFO]  [statusCollector, 473] Group cache size: 1
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 411] Cache size: 1
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 415] Updated cache size: 1
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 214] cache update is starting, updateRequestTimeStamp = 5159684133000
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 218] cache update succeeds, updateRequestTimeStamp = 5160072945000
[2012-09-23 11:46:13:034 'Activation.trace' 4608 DEBUG]  [activationValidator, 1028]
------------------------------------------------------
Invoking GetStatus on integrity.BaselineComplianceStatusCollector:session[8CDEA85B-BC87-4781-9C38-7847576B4527]6313C78E-DC90-4458-B941-245710AC57C0 session 8CDEA85B-BC87-4781-9C38-7847576B4527
------------------------------------------------------
[2012-09-23 11:46:13:034 'Activation' 4608 INFO]  [activationValidator, 195] Leave Validate. Succeeded forintegrity.BaselineComplianceStatusCollector.GetStatus on session private target: session[8CDEA85B-BC87-4781-9C38-7847576B4527]6313C78E-DC90-4458-B941-245710AC57C0
[2012-09-23 11:46:13:034 'Activation.trace' 4608 DEBUG]  [activationValidator, 1094] Invoke done: integrity.BaselineComplianceStatusCollector.GetStatus session: 8CDEA85B-BC87-4781-9C38-7847576B4527
Result:
(integrity.BaselineComplianceStatus) [
   (integrity.BaselineComplianceStatus) {
      dynamicType = <unset>,
      key = 12,
      status = "NotCompliant",
   }
]
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 4656 DEBUG]  [statusCollector, 99] start to update status collector cache
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 4656 DEBUG]  [statusCollector, 103] status update request time in cache = 5160072914000 status update request time = 5160072945000
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 4656 DEBUG]  [statusCollector, 107] updating status cache with a more recently computed  compliance status results
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 4656 DEBUG]  [statusCollector, 110] finish updating status collector cache
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 1324 INFO]  [statusCollector, 411] Cache size: 1
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 214] cache update is starting, updateRequestTimeStamp = 5159684133000
[2012-09-23 11:46:13:034 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 218] cache update succeeds, updateRequestTimeStamp = 5160072945000
[2012-09-23 11:46:13:034 'Activation.trace' 3388 DEBUG]  [activationValidator, 1028]
------------------------------------------------------
Invoking queryBaselineStatus on integrity.ComplianceStatusManager:Integrity.ComplianceStatusMgr session 30FAC3E5-9A9F-41E3-864E-33F9E2744AA0
Arg entity:
'vim.HostSystem:host-272'
Arg selectedStatus:
(string) [
   "NotCompliant",
   "Incompatible",
   "Unknown",
   "Compliant"
]
Arg baseline:
(int) [
   12
]
------------------------------------------------------
[2012-09-23 11:46:13:049 'ComplianceStatusMgr' 4656 INFO]  [statusCollector, 473] Group cache size: 1
[2012-09-23 11:46:13:049 'ComplianceStatusMgr' 4656 INFO]  [statusCollector, 477] Updated group cache size: 1
[2012-09-23 11:46:13:049 'Activation.trace' 4656 DEBUG]  [activationValidator, 1028]
------------------------------------------------------
Invoking GetGroupStatus on integrity.BaselineComplianceStatusCollector:session[30FAC3E5-9A9F-41E3-864E-33F9E2744AA0]DCC2FA25-C32B-4C80-BAA7-C15F379EF75E session 30FAC3E5-9A9F-41E3-864E-33F9E2744AA0
------------------------------------------------------
[2012-09-23 11:46:13:049 'Activation' 4656 INFO]  [activationValidator, 195] Leave Validate. Succeeded forintegrity.BaselineComplianceStatusCollector.GetGroupStatus on session private target: session[30FAC3E5-9A9F-41E3-864E-33F9E2744AA0]DCC2FA25-C32B-4C80-BAA7-C15F379EF75E
[2012-09-23 11:46:13:049 'Activation.trace' 4656 DEBUG]  [activationValidator, 1094] Invoke done: integrity.BaselineComplianceStatusCollector.GetGroupStatus session: 30FAC3E5-9A9F-41E3-864E-33F9E2744AA0
Result:
(integrity.BaselineGroupComplianceStatus) [
   (integrity.BaselineGroupComplianceStatus) {
      dynamicType = <unset>,
      key = 1,
      status = "NotCompliant",
   }
]
[2012-09-23 11:46:13:049 'ComplianceStatusMgr' 2072 DEBUG]  [statusCollector, 99] start to update status collector cache
[2012-09-23 11:46:13:049 'ComplianceStatusMgr' 2072 DEBUG]  [statusCollector, 103] status update request time in cache = 5160072914000 status update request time = 5160072961000
[2012-09-23 11:46:13:049 'ComplianceStatusMgr' 2072 DEBUG]  [statusCollector, 107] updating status cache with a more recently computed  compliance status results
[2012-09-23 11:46:13:049 'ComplianceStatusMgr' 2072 DEBUG]  [statusCollector, 110] finish updating status collector cache
[2012-09-23 11:46:13:049 'ComplianceStatusMgr' 1324 INFO]  [statusCollector, 473] Group cache size: 1
[2012-09-23 11:46:13:049 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 214] cache update is starting, updateRequestTimeStamp = 5159684133000
[2012-09-23 11:46:13:049 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 218] cache update succeeds, updateRequestTimeStamp = 5160072961000
[2012-09-23 11:46:13:049 'VciScanTask.ScanTask{62}' 1324 INFO]  [vciTaskBase, 1278] SerializeToVimFault fault:
(integrity.fault.HostPatchESXUpdateFailure) {
   dynamicType = <unset>,
   faultCause = (vmodl.MethodFault) null,
   errorCodes = "99",
   msg = "",
}
Converted fault:
(vim.fault.ExtendedFault) {
   dynamicType = <unset>,
   faultCause = (vmodl.MethodFault) null,
   faultTypeId = "com.vmware.vcIntegrity.HostPatchESXUpdateFailure",
   data = (vim.KeyValue) [
      (vim.KeyValue) {
         dynamicType = <unset>,
         key = "dynamicType",
         value = "",
      },
      (vim.KeyValue) {
         dynamicType = <unset>,
         key = "dynamicProperty",
         value = "",
      },
      (vim.KeyValue) {
         dynamicType = <unset>,
         key = "faultCause",
         value = "",
      },
      (vim.KeyValue) {
         dynamicType = <unset>,
         key = "faultMessage",
         value = "",
      },
      (vim.KeyValue) {
         dynamicType = <unset>,
         key = "errorCodes",
         value = "99",
      }
   ],
   msg = "",
}
[2012-09-23 11:46:13:049 'VciScanTask.ScanTask{62}' 1324 INFO]  [vciTaskBase, 1540] Description updated
[2012-09-23 11:46:13:049 'Activation' 3388 INFO]  [activationValidator, 368] Leave Validate. Succeeded for integrity.ComplianceStatusManager.queryBaselineStatus on target: Integrity.ComplianceStatusMgr
[2012-09-23 11:46:13:049 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 3664] QueryBaselineStatus called by client on entity : host-272
[2012-09-23 11:46:13:049 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 3667]  baseline id: 12
[2012-09-23 11:46:13:065 'VciScanTask.ScanTask{62}' 1324 INFO]  [vciTaskBase, 1291] VciTask { id: ScanTask{62}, type: com.vmware.vcIntegrity.ScanTask }: Setting VC task state to: error
[2012-09-23 11:46:13:080 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 4600] get baselines for entity: host-272
[2012-09-23 11:46:13:080 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 4615] baselines for group id : 1
[2012-09-23 11:46:13:080 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 4618] baseline id: 12
[2012-09-23 11:46:13:080 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 4696] patch baseline list: 12,
[2012-09-23 11:46:13:080 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 4704] upgrade baseline list:
[2012-09-23 11:46:13:080 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 4712] extension baseline list:
[2012-09-23 11:46:13:080 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 4259] Entity group 0 has the following entities: host-272,
[2012-09-23 11:46:13:080 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 4270] Entity group 0 has the following patch baselines: 12,
[2012-09-23 11:46:13:080 'BaselineMgr' 3388 DEBUG]  [baselineMgr, 4323] only 1 baseline, get directly form db
[2012-09-23 11:46:13:080 'Activation' 4656 INFO]  [activationValidator, 195] Leave Validate. Succeeded forvmodl.query.PropertyCollector.Filter.GetSpec on session private target: session[30FAC3E5-9A9F-41E3-864E-33F9E2744AA0]A2348089-F0AE-4A76-8BA6-0EFF4B540F7B
[2012-09-23 11:46:13:127 'BaselineMgr' 3388 INFO]  [baselineMgr, 4367] Elapsed time for Vcidb::QueryBaselineScanDataByEntityList with 1 entities is 47 msec
[2012-09-23 11:46:13:127 'VciScanTask.ScanTask{62}' 1324 INFO]  [vciTaskBase, 187] Delete VC task from database: task-4359
[2012-09-23 11:46:13:127 'JobDispatcher' 4608 INFO]  [JobDispatcher, 1875]  Removing task ScanTask{62}
[2012-09-23 11:46:13:127 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 3
[2012-09-23 11:46:13:127 'VciScanTask.ScanTask{62}' 1324 INFO]  [vciTaskBase, 891] Task finished, state: 3
[2012-09-23 11:46:13:127 'JobDispatcher' 4608 INFO]  [JobDispatcher, 1875]  Removing task VciHostScanTask{63}
[2012-09-23 11:46:13:127 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 2
[2012-09-23 11:46:13:127 'BaselineMgr' 3388 INFO]  [baselineMgr, 4282] elapsed time for QueryBaselineStatusList(db call) = 47 msec
[2012-09-23 11:46:13:127 'BaselineMgr' 3388 INFO]  [baselineMgr, 4024] elapsed time for QueryPatchBaselineStatusList(Patch) = 47 msec
[2012-09-23 11:46:13:127 'BaselineMgr' 3388 INFO]  [baselineMgr, 3757] elapsed time for QueryBaselineStatusForEntities (HOST) = 47 msec
[2012-09-23 11:46:13:127 'VciHostScanTask.VciHostScanTask{63}' 1324 INFO]  [vciTaskBase, 891] Task finished, state: 3
[2012-09-23 11:46:13:127 'JobDispatcher' 4608 INFO]  [JobDispatcher, 1875]  Removing task ChainedTaskContainer{65} that imposed VMware vCenter Update Manager load:0, new load: 2
[2012-09-23 11:46:13:127 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 1
[2012-09-23 11:46:13:127 'ChainedTaskContainer.ChainedTaskContainer{65}' 1324 INFO]  [vciTaskBase, 891] Task finished, state: 3
[2012-09-23 11:46:13:127 'JobDispatcher' 4608 INFO]  [JobDispatcher, 1875]  Removing task SingleHostScanTask{67} that imposed ESX load:3 for esx host-272, new load: 0 that imposed VMware vCenter Update Manager load:2, new load: 0
[2012-09-23 11:46:13:127 'JobDispatcher' 260 DEBUG]  [JobDispatcher, 391] The number of tasks: 0
[2012-09-23 11:46:13:127 'SingleHostScanTask.SingleHostScanTask{67}' 1324 INFO]  [vciTaskBase, 891] Task finished, state: 3
[2012-09-23 11:46:13:127 'SetFirewallConfig' 1324 INFO]  [hostUpdateBase, 743] No changes to firewall configuration for: updateManager
[2012-09-23 11:46:13:127 'BaselineMgr' 3388 INFO]  [baselineMgr, 3858] performance summary for QueryBaselineStatus:  elapsed time = 78 msec entity count = 1 total updates = 28 total upgrades = 0 total extensions = 0
[2012-09-23 11:46:13:143 'Activation.trace' 3388 DEBUG]  [activationValidator, 1094] Invoke done: integrity.ComplianceStatusManager.queryBaselineStatus session: 30FAC3E5-9A9F-41E3-864E-33F9E2744AA0
Result:
(integrity.ComplianceStatusManager.EntityStatusSummary) {
   dynamicType = <unset>,
   entityStatus = (integrity.ComplianceStatusManager.EntityComplianceStatus) [
      (integrity.ComplianceStatusManager.EntityComplianceStatus) {
         dynamicType = <unset>,
         entity = 'vim.HostSystem:host-272',
         baseline = (int) [
            12
         ],
         lastScanTime = "2012-09-18T05:11:08.66Z",
         updateStatus = (integrity.ComplianceStatusManager.UpdateStatusSummary) [
            (integrity.ComplianceStatusManager.UpdateStatusSummary) {
               dynamicType = <unset>,
               status = "Compliant",
               patchCount = 22,
               upgradeCount = <unset>,
               stagedPatchCount = <unset>,
               stagedUpgradeCount = <unset>,
               extensionCount = <unset>,
               stagedExtensionCount = <unset>,
            },
            (integrity.ComplianceStatusManager.UpdateStatusSummary) {
               dynamicType = <unset>,
               status = "NotCompliant",
               patchCount = 6,
               upgradeCount = <unset>,
               stagedPatchCount = <unset>,
               stagedUpgradeCount = <unset>,
               extensionCount = <unset>,
               stagedExtensionCount = <unset>,
            },
            (integrity.ComplianceStatusManager.UpdateStatusSummary) {
               dynamicType = <unset>,
               status = "Unknown",
               patchCount = <unset>,
               upgradeCount = <unset>,
               stagedPatchCount = <unset>,
               stagedUpgradeCount = <unset>,
               extensionCount = <unset>,
               stagedExtensionCount = <unset>,
            },
            (integrity.ComplianceStatusManager.UpdateStatusSummary) {
               dynamicType = <unset>,
               status = "Incompatible",
               patchCount = <unset>,
               upgradeCount = <unset>,
               stagedPatchCount = <unset>,
               stagedUpgradeCount = <unset>,
               extensionCount = <unset>,
               stagedExtensionCount = <unset>,
            }
         ],
      }
   ],
   summary = (integrity.ComplianceStatusManager.UpdateStatusSummary) [
      (integrity.ComplianceStatusManager.UpdateStatusSummary) {
         dynamicType = <unset>,
         status = <unset>,
         patchCount = 28,
         upgradeCount = 0,
         stagedPatchCount = <unset>,
         stagedUpgradeCount = <unset>,
         extensionCount = <unset>,
         stagedExtensionCount = <unset>,
      }
   ],
}
[2012-09-23 11:46:13:159 'Activation' 4608 INFO]  [activationValidator, 195] Leave Validate. Succeeded forvmodl.query.PropertyCollector.Filter.GetSpec on session private target: session[8CDEA85B-BC87-4781-9C38-7847576B4527]DD352F41-D5F5-40EC-A49B-82E21C4F5141
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 411] Cache size: 1
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 415] Updated cache size: 1
[2012-09-23 11:46:13:159 'Activation.trace' 4608 DEBUG]  [activationValidator, 1028]
------------------------------------------------------
Invoking GetStatus on integrity.BaselineComplianceStatusCollector:session[8CDEA85B-BC87-4781-9C38-7847576B4527]9B2F936B-6468-472E-8EBE-1F290462366E session 8CDEA85B-BC87-4781-9C38-7847576B4527
------------------------------------------------------
[2012-09-23 11:46:13:159 'Activation' 4608 INFO]  [activationValidator, 195] Leave Validate. Succeeded forintegrity.BaselineComplianceStatusCollector.GetStatus on session private target: session[8CDEA85B-BC87-4781-9C38-7847576B4527]9B2F936B-6468-472E-8EBE-1F290462366E
[2012-09-23 11:46:13:159 'Activation.trace' 4608 DEBUG]  [activationValidator, 1094] Invoke done: integrity.BaselineComplianceStatusCollector.GetStatus session: 8CDEA85B-BC87-4781-9C38-7847576B4527
Result:
(integrity.BaselineComplianceStatus) [
   (integrity.BaselineComplianceStatus) {
      dynamicType = <unset>,
      key = 12,
      status = "NotCompliant",
   }
]
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 99] start to update status collector cache
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 103] status update request time in cache = 5160072945000 status update request time = 5160073070000
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 107] updating status cache with a more recently computed  compliance status results
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 110] finish updating status collector cache
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 473] Group cache size: 1
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 477] Updated group cache size: 1
[2012-09-23 11:46:13:159 'Activation.trace' 4608 DEBUG]  [activationValidator, 1028]
------------------------------------------------------
Invoking GetGroupStatus on integrity.BaselineComplianceStatusCollector:session[8CDEA85B-BC87-4781-9C38-7847576B4527]6313C78E-DC90-4458-B941-245710AC57C0 session 8CDEA85B-BC87-4781-9C38-7847576B4527
------------------------------------------------------
[2012-09-23 11:46:13:159 'Activation' 4608 INFO]  [activationValidator, 195] Leave Validate. Succeeded forintegrity.BaselineComplianceStatusCollector.GetGroupStatus on session private target: session[8CDEA85B-BC87-4781-9C38-7847576B4527]6313C78E-DC90-4458-B941-245710AC57C0
[2012-09-23 11:46:13:159 'Activation.trace' 4608 DEBUG]  [activationValidator, 1094] Invoke done: integrity.BaselineComplianceStatusCollector.GetGroupStatus session: 8CDEA85B-BC87-4781-9C38-7847576B4527
Result:
(integrity.BaselineGroupComplianceStatus) [
   (integrity.BaselineGroupComplianceStatus) {
      dynamicType = <unset>,
      key = 1,
      status = "NotCompliant",
   }
]
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 99] start to update status collector cache
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 103] status update request time in cache = 5160072945000 status update request time = 5160073070000
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 107] updating status cache with a more recently computed  compliance status results
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 1324 DEBUG]  [statusCollector, 110] finish updating status collector cache
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 473] Group cache size: 1
[2012-09-23 11:46:13:159 'ComplianceStatusMgr' 4608 INFO]  [statusCollector, 477] Updated group cache size: 1
[2012-09-23 11:46:13:174 'Activation.trace' 4608 DEBUG]  [activationValidator, 1028]
------------------------------------------------------
Invoking GetGroupStatus on integrity.BaselineComplianceStatusCollector:session[8CDEA85B-BC87-4781-9C38-7847576B4527]9B2F936B-6468-472E-8EBE-1F290462366E session 8CDEA85B-BC87-4781-9C38-7847576B4527
------------------------------------------------------
[2012-09-23 11:46:13:174 'Activation' 4608 INFO]  [activationValidator, 195] Leave Validate. Succeeded forintegrity.BaselineComplianceStatusCollector.GetGroupStatus on session private target: session[8CDEA85B-BC87-4781-9C38-7847576B4527]9B2F936B-6468-472E-8EBE-1F290462366E
[2012-09-23 11:46:13:174 'Activation.trace' 4608 DEBUG]  [activationValidator, 1094] Invoke done: integrity.BaselineComplianceStatusCollector.GetGroupStatus session: 8CDEA85B-BC87-4781-9C38-7847576B4527
Result:
(integrity.BaselineGroupComplianceStatus) [
   (integrity.BaselineGroupComplianceStatus) {
      dynamicType = <unset>,
      key = 1,
      status = "NotCompliant",
   }
]
[2012-09-23 11:46:13:174 'ComplianceStatusMgr' 4608 DEBUG]  [statusCollector, 99] start to update status collector cache
[2012-09-23 11:46:13:174 'ComplianceStatusMgr' 4608 DEBUG]  [statusCollector, 103] status update request time in cache = 5160072961000 status update request time = 5160073070000
[2012-09-23 11:46:13:174 'ComplianceStatusMgr' 4608 DEBUG]  [statusCollector, 107] updating status cache with a more recently computed  compliance status results
[2012-09-23 11:46:13:174 'ComplianceStatusMgr' 4608 DEBUG]  [statusCollector, 110] finish updating status collector cache
[2012-09-23 11:46:13:174 'Activation' 4608 INFO]  [activationValidator, 195] Leave Validate. Succeeded forvmodl.query.PropertyCollector.Filter.GetSpec on session private target: session[8CDEA85B-BC87-4781-9C38-7847576B4527]BBE7CAF6-6C75-464F-B882-E21A37C47366
[2012-09-23 11:46:13:174 'Activation' 4608 INFO]  [activationValidator, 195] Leave Validate. Succeeded forvmodl.query.PropertyCollector.Filter.GetSpec on session private target: session[8CDEA85B-BC87-4781-9C38-7847576B4527]CF599378-3AD2-4196-9413-ABE6EBF74F8B
[2012-09-23 11:46:13:190 'Activation' 4608 INFO]  [activationValidator, 368] Leave Validate. Succeeded for integrity.BaselineGroupManager.queryForEntity on target: Integrity.BaselineGroupManager
[2012-09-23 11:46:13:190 'Activation' 4612 INFO]  [activationValidator, 195] Leave Validate. Succeeded forvmodl.query.PropertyCollector.Filter.GetSpec on session private target: session[8CDEA85B-BC87-4781-9C38-7847576B4527]FB93583B-CBA3-4709-B7B6-CDB7C2FBC0B4
[2012-09-23 11:46:13:205 'Activation' 4612 INFO]  [activationValidator, 368] Leave Validate. Succeeded for integrity.BaselineManager.queryForEntity on target: Integrity.BaselineMgr
[2012-09-23 11:46:13:221 'Activation.trace' 3388 DEBUG]  [activationValidator, 1028]
------------------------------------------------------
Invoking queryEntityComplianceStatus on integrity.ComplianceStatusManager:Integrity.ComplianceStatusMgr session 8CDEA85B-BC87-4781-9C38-7847576B4527
Arg entity:
'vim.Datacenter:datacenter-22'
Arg baselineIds:
(int) [
   12
]
------------------------------------------------------
[2012-09-23 11:46:13:221 'Activation' 3388 INFO]  [activationValidator, 368] Leave Validate. Succeeded for integrity.ComplianceStatusManager.queryEntityComplianceStatus on target: Integrity.ComplianceStatusMgr
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [complianceStatusMgr, 924] QueryEntityComplianceStatus called by client on entity:datacenter-22
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [complianceStatusMgr, 927]  baseline id: 12
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [complianceStatusMgr, 932] getting baseline informaton from db
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 INFO]  [vciUtilities, 4298]  total elapsed time for BaselineGroup::FetchAllBaselineGroupsWithBaselines = 0 msec
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 INFO]  [vciUtilities, 4307] Acquired lock and get baselines for 6 entities
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4332] get baselines for entity: host-545
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4332] get baselines for entity: host-568
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4332] get baselines for entity: host-172
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4332] get baselines for entity: host-225
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4332] get baselines for entity: host-272
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4332] get baselines for entity: host-442
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4393] size of EntityBaselinesMap = 1
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4398] Patch baselines attach information.
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4402] entity: host-172 baselines: 12
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4402] entity: host-225 baselines: 12
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4402] entity: host-272 baselines: 12
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4402] entity: host-442 baselines: 12
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4402] entity: host-545 baselines: 12
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 DEBUG]  [vciUtilities, 4402] entity: host-568 baselines: 12
[2012-09-23 11:46:13:221 'ComplianceStatusMgr' 3388 INFO]  [vciUtilities, 4408]  total elapsed time for VciUtilities::GetAllApplicableBaselines = 0 msec
[2012-09-23 11:46:13:237 'vcidbStoredProcEntry' 3388 INFO] elapsed time for vci_queryEntityStatus  with 6 entities  = 15 msec
[2012-09-23 11:46:13:237 'vcidbStoredProcEntry' 3388 INFO]  total fetch time = 0 msec total e-to-e time = 15 msec
[2012-09-23 11:46:13:252 'ComplianceStatusMgr' 3388 INFO]  [complianceStatusMgr, 1214]  total elapsed time for vmodl (QueryEntityComplianceStatus) = 31 msec
[2012-09-23 11:46:13:252 'Activation.trace' 3388 DEBUG]  [activationValidator, 1094] Invoke done: integrity.ComplianceStatusManager.queryEntityComplianceStatus session: 8CDEA85B-BC87-4781-9C38-7847576B4527
Result:
(integrity.ComplianceStatusManager.EntityBaselinesComplianceSummary) {
   dynamicType = <unset>,
   summary = (integrity.ComplianceStatusManager.EntityComplianceCountsSummary) {
      dynamicType = <unset>,
      notCompliantEntities = 6,
      inCompatibleEntities = 0,
      unknownEntities = 0,
      compliantEntities = 0,
   },
   baselinesComplianceStatus = (integrity.ComplianceStatusManager.EntityBaselinesCompliance) [
      (integrity.ComplianceStatusManager.EntityBaselinesCompliance) {
         dynamicType = <unset>,
         entity = 'vim.HostSystem:host-172',
         status = "NotCompliant",
         baselineId = (int) [
            12
         ],
         baselineType = "Patch",
         lastScanTime = "2012-09-18T05:11:07.317Z",
      },
      (integrity.ComplianceStatusManager.EntityBaselinesCompliance) {
         dynamicType = <unset>,
         entity = 'vim.HostSystem:host-225',
         status = "NotCompliant",
         baselineId = (int) [
            12
         ],
         baselineType = "Patch",
         lastScanTime = "2012-09-18T05:11:08.112999Z",
      },
      (integrity.ComplianceStatusManager.EntityBaselinesCompliance) {
         dynamicType = <unset>,
         entity = 'vim.HostSystem:host-272',
         status = "NotCompliant",
         baselineId = (int) [
            12
         ],
         baselineType = "Patch",
         lastScanTime = "2012-09-18T05:11:08.66Z",
      },
      (integrity.ComplianceStatusManager.EntityBaselinesCompliance) {
         dynamicType = <unset>,
         entity = 'vim.HostSystem:host-442',
         status = "NotCompliant",
         baselineId = (int) [
            12
         ],
         baselineType = "Patch",
         lastScanTime = "2012-09-18T05:11:09.33Z",
      },
      (integrity.ComplianceStatusManager.EntityBaselinesCompliance) {
         dynamicType = <unset>,
         entity = 'vim.HostSystem:host-545',
         status = "NotCompliant",
         baselineId = (int) [
            12
         ],
         baselineType = "Patch",
         lastScanTime = "2012-09-18T05:11:10.377Z",
      },
      (integrity.ComplianceStatusManager.EntityBaselinesCompliance) {
         dynamicType = <unset>,
         entity = 'vim.HostSystem:host-568',
         status = "NotCompliant",
         baselineId = (int) [
            12
         ],
         baselineType = "Patch",
         lastScanTime = "2012-09-18T05:11:10.457Z",
      }
   ],
}

Reply
0 Kudos
Pegasjus
Contributor
Contributor

So, in the end, the missing file is for the syslog settings. For some reason it was set to a remote syslog server. When I changed this to a local log file the error went away...

Reply
0 Kudos