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
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",
}
],
}
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...