VMs awaiting a retry: a previous restart attempt f...

dmuligan · ‎02-12-2016

Hi all,

we are doing vSphere HA tests in our infrastructure, in a ESXi 5.5 host with 8 VMs running, we do a power reset from HP ilo, all the VM´s are migrated to other hosts succesfully after some minutes but there is a message displayed in vCenter Server, the message is:

"VMs awaiting a retry: a previous restart attempt failed, and vSphere HA is waiting for a timeout to expire before trying again"

Finallly all the vm´s are restarted but I want to know why the first attempt is failing.

Thank you.

kermic · ‎02-12-2016

Take a look at entries in fdm.log files on your surviving hosts. They should give you some more info than vCenter events.

LK3035 · ‎02-12-2016

We have these logs.. Any idea?

2016-02-12T11:40:50.364Z info fdm[FFCF1B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::HandleFailedPlacement(VmFailover)] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXVDASRV/XXXXVDASRV.vmx will be retried without backoff,after a failure at host host-18, delay = 120 secs

2016-02-12T11:14:10.412Z verbose fdm[FFAA9B70] [Originator@6876 sub=Execution opID=host-18:37:255d4dff-1] [FailoverAction::ErrorHandler] Got fault while failing over vm. /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx: [N3Vim5Fault12InvalidStateE:0x1f400240] (state = registering)

2016-02-12T11:40:50.385Z warning fdm[FFEB8B70] [Originator@6876 sub=Execution] [ExecutionCommandUpdate::Deserialize] vm /vmfs/volumes/566ed363-d066fb8c-9dca-0017a4779c1e/XXXXDCSXEN2/XXXXDCSXEN2.vmx failed to failover on host-10. FaultType: vim.fault.InvalidState

2016-02-12T11:40:50.364Z warning fdm[FFCF1B70] [Originator@6876 sub=Execution] [ExecutionCommandUpdate::Deserialize] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXVDASRV/XXXXVDASRV.vmx failed to failover on host-18. FaultType: vim.fault.InvalidState

2016-02-12T12:14:49.284Z warning fdm[FFA27780] [Originator@6876 sub=Execution] [ExecutionCommandUpdate::Deserialize] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx failed to failover on host-125. FaultType: vim.fault.InvalidState

kermic · ‎02-12-2016

This does not look descriptive enough, at least not intuitive.

Can you also check if there is anything interesting in the vmkernel.log and hostd.log logfiles on hosts, where restarts were attempted at and around the fault time (2016-02-12T12:14:49 and 2016-02-12T11:40:50)?

My first suspect would be that some file locks were still present at the time when failover was attempted however to tell for sure would need to find a log message that points to it.

LK3035 · ‎02-12-2016

Same issue at different time 9:41 FDML.log after that HOSTD.log

2016-02-12T09:40:55.955Z verbose fdm[314F0B70] [Originator@6876 sub=Placement] [RR::ResetVms] Reset 0 Vms. Records = 9

2016-02-12T09:40:55.955Z verbose fdm[314F0B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::IssuePlacementStartCompleteEventLocked] Failover operation in progress on 9 Vms: 9 VMs being restarted, 0 VMs waiting for a retry, 0 VMs waiting for resources, 0 inaccessible vSAN VMs

2016-02-12T09:40:55.955Z info fdm[314F0B70] [Originator@6876 sub=Placement] [RR::CreatePlacementRequest] 9 total VMs with some excluded: 0 VM disabled; 9 VMs being placed; 0 VMs waiting resources;0 VMs awaiting vSAN VM access change; 0 VMs in time delay;

2016-02-12T09:40:55.955Z verbose fdm[315F4B70] [Originator@6876 sub=FDM opID=SWI-b9d79d4] [FdmService] New event: EventEx=com.vmware.vc.HA.ClusterFailoverInProgressEvent vm= host= tag=host-18:-548212892:27

2016-02-12T09:40:55.955Z verbose fdm[315F4B70] [Originator@6876 sub=PropertyProvider opID=SWI-b9d79d4] RecordOp ADD: event[138], fdmService. Applied change to temp map.

2016-02-12T09:40:55.955Z verbose fdm[315F4B70] [Originator@6876 sub=PropertyProvider opID=SWI-b9d79d4] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:40:58.040Z warning fdm[314F0B70] [Originator@6876 sub=vpxLro] [VpxLRO] No taskinfo property updates for haTask-ha-folder-vm-vim.Folder.registerVm-124255216 in 10000 ms

2016-02-12T09:40:58.041Z warning fdm[314F0B70] [Originator@6876 sub=vpxLro] [VpxLRO] Taskinfo polling sees no change

2016-02-12T09:40:58.043Z warning fdm[315F4B70] [Originator@6876 sub=vpxLro] [VpxLRO] No taskinfo property updates for haTask-ha-folder-vm-vim.Folder.registerVm-124255217 in 10000 ms

2016-02-12T09:40:58.044Z warning fdm[315F4B70] [Originator@6876 sub=vpxLro] [VpxLRO] Taskinfo polling sees no change

2016-02-12T09:41:01.045Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:41:01.045Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:41:01.045Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:41:01.045Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:41:03.042Z verbose fdm[31531B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-51aab6c1] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-ha-folder-vm-vim.Folder.registerVm-124255216 updated to version 2(known last version = 1)

2016-02-12T09:41:03.044Z verbose fdm[31531B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-1ec462ca] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-ha-folder-vm-vim.Folder.registerVm-124255217 updated to version 2(known last version = 1)

2016-02-12T09:41:04.053Z info fdm[31531B70] [Originator@6876 sub=Invt] [HalVmMonitor::HandleVmAdd] vm config for 71 is null - trying to get cfgPath from summary

2016-02-12T09:41:04.056Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-2e2acf03] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-15

2016-02-12T09:41:04.056Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-2e2acf03] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:41:04.056Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-7c7ce5a2] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:04.056Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-7c7ce5a2] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx

2016-02-12T09:41:04.056Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-7c7ce5a2] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx from stats

2016-02-12T09:41:04.056Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-7c7ce5a2] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:04.056Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-7c7ce5a2] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx

2016-02-12T09:41:04.056Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-7c7ce5a2] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:41:04.060Z verbose fdm[31531B70] [Originator@6876 sub=Invt] [HalVmMonitor::Notify] Adding new vm: vmPath=/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx, moId=71

2016-02-12T09:41:04.060Z info fdm[31739B70] [Originator@6876 sub=Invt opID=SWI-6bc82e87] [VmStateChange::SavePowerChange] Treating power off as clean for vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:41:04.060Z info fdm[31739B70] [Originator@6876 sub=Invt opID=SWI-6bc82e87] [VmStateChange::SavePowerChange] Treating power off as unclean for inaccessible vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:41:04.060Z info fdm[31739B70] [Originator@6876 sub=Invt opID=SWI-6bc82e87] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=__localhost__

2016-02-12T09:41:04.060Z info fdm[31739B70] [Originator@6876 sub=Invt opID=SWI-6bc82e87] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx to host __localhost__ (moId on host=71)

2016-02-12T09:41:04.060Z verbose fdm[31739B70] [Originator@6876 sub=Invt opID=SWI-6bc82e87] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx local-host: local power state=powered off; global power state=powered off

2016-02-12T09:41:04.060Z verbose fdm[31739B70] [Originator@6876 sub=Invt opID=SWI-6bc82e87] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx from __localhost__ changed inventory cnxState=inaccessible localHostdMoId=71

2016-02-12T09:41:04.060Z verbose fdm[31739B70] [Originator@6876 sub=Invt opID=SWI-6bc82e87] [VmStateChange::SaveToInventory] Will send notifications for inaccessible vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:41:04.060Z verbose fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-2366d859] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:04.060Z info fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-2366d859] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:41:04.060Z verbose fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-2366d859] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx from stats

2016-02-12T09:41:04.060Z verbose fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-2366d859] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:04.060Z info fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-2366d859] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:41:04.061Z verbose fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-2366d859] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:41:04.063Z verbose fdm[31572B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-fe3f113] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-ha-folder-vm-vim.Folder.registerVm-124255216 updated to version 3(known last version = 2)

2016-02-12T09:41:04.064Z verbose fdm[31572B70] [Originator@6876 sub=vpxLro opID=SWI-fe3f113] [VpxLRO] Dispatching Callback Functor for haTask-ha-folder-vm-vim.Folder.registerVm-124255216 which completed successfully from task-internal-301

2016-02-12T09:41:04.064Z verbose fdm[31572B70] [Originator@6876 sub=vpxLro opID=SWI-fe3f113] [VpxLRO] Task task-internal-301 has been rescheduled

2016-02-12T09:41:04.064Z verbose fdm[31739B70] [Originator@6876 sub=vpxLro opID=host-18:29:f22131e-0] [VpxLRO] Task task-internal-301 has been resumed

2016-02-12T09:41:04.064Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-0] [FailoverAction::RegisterCompletionCallback] Registering vm done (vmid=/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx, hostdVmId=71)

2016-02-12T09:41:04.066Z verbose fdm[31739B70] [Originator@6876 sub=Invt opID=host-18:29:f22131e-0] [InventoryManagerImpl::IsVmCleanlyPoweredOff] VM is not connected: inaccessible

2016-02-12T09:41:04.066Z error fdm[31739B70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-0] [FailoverAction::RegisterCompletionCallback] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx is invalid - failing failover.

2016-02-12T09:41:04.066Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-0] [FailoverAction::ErrorHandler] Got fault while failing over vm. /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: [N3Vim5Fault12InvalidStateE:0x1f462050] (state = registering)

2016-02-12T09:41:04.066Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-0] [ActionScheduler::RemoveAction] Action is removed: opId = host-18:29:f22131e-0

2016-02-12T09:41:04.066Z verbose fdm[31739B70] [Originator@6876 sub=Placement opID=host-18:29:f22131e-0] [PlacementManagerImpl::HandleFailedPlacement(VmFailover)] Reset state for 1 vms (opID=[host-18:29:f22131e-0])

2016-02-12T09:41:04.066Z info fdm[31739B70] [Originator@6876 sub=Placement opID=host-18:29:f22131e-0] [PlacementManagerImpl::HandleFailedPlacement(VmFailover)] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx will be retried without backoff,after a failure at host __localhost__, delay = 120 secs

2016-02-12T09:41:04.066Z info fdm[31739B70] [Originator@6876 sub=vpxLro opID=host-18:29:f22131e-0] [VpxLRO] -- FINISH task-internal-301

2016-02-12T09:41:04.072Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-522d576d] [VmStateChange::SavePowerChange] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-10

2016-02-12T09:41:04.072Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-522d576d] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:41:04.072Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-1ec6df41] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:04.072Z info fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-1ec6df41] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx

2016-02-12T09:41:04.072Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-1ec6df41] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx from stats

2016-02-12T09:41:04.072Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-1ec6df41] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:04.072Z info fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-1ec6df41] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx

2016-02-12T09:41:04.072Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-1ec6df41] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:41:04.087Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-126b3430] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-10

2016-02-12T09:41:04.087Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-126b3430] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:41:04.087Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-3c2f44c2] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:04.087Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-3c2f44c2] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx

2016-02-12T09:41:04.087Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-3c2f44c2] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx from stats

2016-02-12T09:41:04.087Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-3c2f44c2] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:04.087Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-3c2f44c2] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx

2016-02-12T09:41:04.088Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-3c2f44c2] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:41:04.187Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-434b3b6c] [VmStateChange::SavePowerChange] vm /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-15

2016-02-12T09:41:04.187Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-434b3b6c] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:41:04.187Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-434b3b6c] [VmStateChange::SaveToInventory] vm /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx from host-15 changed inventory datastoresInUse changed numCpu=2

2016-02-12T09:41:04.187Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-2a51f8] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:04.187Z info fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-2a51f8] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx

2016-02-12T09:41:04.187Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-2a51f8] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx from stats

2016-02-12T09:41:04.187Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-2a51f8] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:04.187Z info fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-2a51f8] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx

2016-02-12T09:41:04.187Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-2a51f8] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:41:04.221Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-5a6b70dd] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-10

2016-02-12T09:41:04.221Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-5a6b70dd] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:41:04.221Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-5a6b70dd] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx from host-10 changed inventory datastoresInUse changed

2016-02-12T09:41:04.221Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-43beeb8b] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:04.221Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-43beeb8b] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx

2016-02-12T09:41:04.221Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-43beeb8b] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx from stats

2016-02-12T09:41:04.221Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-43beeb8b] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:04.221Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-43beeb8b] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx

2016-02-12T09:41:04.221Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-43beeb8b] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:41:04.255Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-2b5a156c] [VmStateChange::SavePowerChange] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-104

2016-02-12T09:41:04.255Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-2b5a156c] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:41:04.255Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-2b5a156c] [VmStateChange::SaveToInventory] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx from host-104 changed inventory datastoresInUse changed numCpu=2

2016-02-12T09:41:04.255Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-27347bb0] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:04.255Z info fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-27347bb0] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx

2016-02-12T09:41:04.255Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-27347bb0] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx from stats

2016-02-12T09:41:04.255Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-27347bb0] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:04.255Z info fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-27347bb0] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx

2016-02-12T09:41:04.255Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-27347bb0] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:41:04.283Z warning fdm[31635B70] [Originator@6876 sub=Invt] [HalVmMonitor::GetIsCptFtFromFtInfo] Missing ftInfo. ftInfo is null for /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx

2016-02-12T09:41:04.287Z info fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-66a473eb] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-104

2016-02-12T09:41:04.287Z verbose fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-66a473eb] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:41:04.287Z verbose fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-66a473eb] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx from host-104 changed inventory datastoresInUse changed numCpu=2

2016-02-12T09:41:04.287Z verbose fdm[31572B70] [Originator@6876 sub=Policy opID=SWI-36b67df2] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:04.287Z info fdm[31572B70] [Originator@6876 sub=Policy opID=SWI-36b67df2] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx

2016-02-12T09:41:04.287Z verbose fdm[31572B70] [Originator@6876 sub=Policy opID=SWI-36b67df2] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx from stats

2016-02-12T09:41:04.287Z verbose fdm[31572B70] [Originator@6876 sub=Policy opID=SWI-36b67df2] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:04.287Z info fdm[31572B70] [Originator@6876 sub=Policy opID=SWI-36b67df2] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx

2016-02-12T09:41:04.287Z verbose fdm[31572B70] [Originator@6876 sub=Policy opID=SWI-36b67df2] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:41:04.288Z verbose fdm[31635B70] [Originator@6876 sub=Invt] [HalVmMonitor::Notify] Adding new vm: vmPath=/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx, moId=72

2016-02-12T09:41:04.288Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-706b6ee] [VmStateChange::SavePowerChange] vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=__localhost__

2016-02-12T09:41:04.288Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-706b6ee] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx to host __localhost__ (moId on host=72)

2016-02-12T09:41:04.288Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-706b6ee] [VmStateChange::SavePowerChange] vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx local-host: local power state=powered off; global power state=powered off

2016-02-12T09:41:04.288Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-706b6ee] [VmStateChange::SaveToInventory] vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx from __localhost__ changed inventory cnxState=connected localHostdMoId=72 datastoresInUse changed numCpu=2

2016-02-12T09:41:04.288Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-49d3c6dc] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:04.288Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-49d3c6dc] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx

2016-02-12T09:41:04.288Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-49d3c6dc] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx from stats

2016-02-12T09:41:04.288Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-49d3c6dc] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:04.288Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-49d3c6dc] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx

2016-02-12T09:41:04.289Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-49d3c6dc] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:41:04.291Z verbose fdm[31531B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-10a1607] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-ha-folder-vm-vim.Folder.registerVm-124255217 updated to version 3(known last version = 2)

2016-02-12T09:41:04.292Z verbose fdm[31531B70] [Originator@6876 sub=vpxLro opID=SWI-10a1607] [VpxLRO] Dispatching Callback Functor for haTask-ha-folder-vm-vim.Folder.registerVm-124255217 which completed successfully from task-internal-302

2016-02-12T09:41:04.292Z verbose fdm[31531B70] [Originator@6876 sub=vpxLro opID=SWI-10a1607] [VpxLRO] Task task-internal-302 has been rescheduled

2016-02-12T09:41:04.292Z verbose fdm[31739B70] [Originator@6876 sub=vpxLro opID=host-18:29:f22131e-1] [VpxLRO] Task task-internal-302 has been resumed

2016-02-12T09:41:04.292Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-1] [FailoverAction::RegisterCompletionCallback] Registering vm done (vmid=/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx, hostdVmId=72)

2016-02-12T09:41:04.294Z verbose fdm[31739B70] [Originator@6876 sub=Invt opID=host-18:29:f22131e-1] [InventoryManagerImpl::FlattenShares] Changing cpu shares from 2000 to 2095

2016-02-12T09:41:04.294Z verbose fdm[31739B70] [Originator@6876 sub=Invt opID=host-18:29:f22131e-1] [InventoryManagerImpl::FlattenShares] Changing memory shares from 40960 to 45161

2016-02-12T09:41:04.294Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-1] [FailoverAction::InitiateReconfigure] Reconfiguring vm

2016-02-12T09:41:04.297Z verbose fdm[31739B70] [Originator@6876 sub=vpxLro opID=host-18:29:f22131e-1] [VpxLRO] Task task-internal-302 has been descheduled

2016-02-12T09:41:04.297Z verbose fdm[31739B70] [Originator@6876 sub=vpxLro] [VpxLRO] Task task-internal-302 has been requeued

2016-02-12T09:41:04.298Z verbose fdm[31531B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-1d7952af] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.reconfigure-124255222 updated to version 1(known last version = 0)

2016-02-12T09:41:04.303Z verbose fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-8236e47] [VmStateChange::SaveToInventory] vm /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx from host-15 changed inventory overhead=182910976

2016-02-12T09:41:04.336Z verbose fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-50c2f39b] [VmStateChange::SaveToInventory] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx from host-104 changed inventory overhead=146862080

2016-02-12T09:41:04.353Z verbose fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-10e9fc7e] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx from host-10 changed inventory overhead=98258944

2016-02-12T09:41:04.378Z verbose fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-6308cd29] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx from host-104 changed inventory overhead=183046144

2016-02-12T09:41:04.396Z verbose fdm[315F4B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-5711478f] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.reconfigure-124255222 updated to version 2(known last version = 1)

2016-02-12T09:41:04.426Z verbose fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-2f7129f1] [VmStateChange::SaveToInventory] vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx from __localhost__ changed inventory overhead=182910976

2016-02-12T09:41:04.428Z verbose fdm[31531B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-72bca229] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.reconfigure-124255222 updated to version 3(known last version = 2)

2016-02-12T09:41:04.428Z verbose fdm[31531B70] [Originator@6876 sub=vpxLro opID=SWI-72bca229] [VpxLRO] Dispatching Callback Functor for haTask-72-vim.VirtualMachine.reconfigure-124255222 which completed successfully from task-internal-302

2016-02-12T09:41:04.428Z verbose fdm[31531B70] [Originator@6876 sub=vpxLro opID=SWI-72bca229] [VpxLRO] Task task-internal-302 has been rescheduled

2016-02-12T09:41:04.428Z verbose fdm[314AFB70] [Originator@6876 sub=vpxLro opID=host-18:29:f22131e-1] [VpxLRO] Task task-internal-302 has been resumed

2016-02-12T09:41:04.428Z verbose fdm[314AFB70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-1] [FailoverAction::ReconfigureCompletionCallback] Reconfiguring vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx is done

2016-02-12T09:41:04.429Z verbose fdm[314AFB70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-1] [FailoverAction::ReconfigureCompletionCallback] Powering on vm

2016-02-12T09:41:04.431Z verbose fdm[314AFB70] [Originator@6876 sub=vpxLro opID=host-18:29:f22131e-1] [VpxLRO] Task task-internal-302 has been descheduled

2016-02-12T09:41:04.431Z verbose fdm[314AFB70] [Originator@6876 sub=vpxLro] [VpxLRO] Task task-internal-302 has been requeued

2016-02-12T09:41:04.433Z verbose fdm[31531B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-6daf8f12] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 1(known last version = 0)

2016-02-12T09:41:04.435Z verbose fdm[31531B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-20da1f00] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 2(known last version = 1)

2016-02-12T09:41:04.743Z verbose fdm[31531B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-502949d9] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 3(known last version = 2)

2016-02-12T09:41:04.784Z info fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-46af6eac] [VmStateChange::SavePowerChange] vm /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered on clnPwrOff=false hostReporting=host-15

2016-02-12T09:41:04.784Z info fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-46af6eac] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx to host host-15 (moId on host=78)

2016-02-12T09:41:04.784Z verbose fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-46af6eac] [VmStateChange::SaveToInventory] Initialize VmStateChange from inventory for poweredOn vm /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx

2016-02-12T09:41:04.785Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-278b0275] [VmOperationsManager::RequestCancelPlacement] VM ID /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx: Transitioned to 'Cancel' state for 'VmFailover'

2016-02-12T09:41:04.785Z verbose fdm[31635B70] [Originator@6876 sub=Placement opID=SWI-278b0275] [PlacementManagerImpl::CancelVmPlacement] removed 1 of 2 vms

2016-02-12T09:41:04.785Z verbose fdm[31531B70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-15

2016-02-12T09:41:04.785Z verbose fdm[31531B70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.ha.VmRestartedByHAEvent vm=/vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx host=host-15 tag=host-15:1023502345:17

2016-02-12T09:41:04.785Z verbose fdm[31531B70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[139], fdmService. Applied change to temp map.

2016-02-12T09:41:04.785Z verbose fdm[31531B70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:41:04.816Z info fdm[314AFB70] [Originator@6876 sub=Invt opID=SWI-1b6d6111] [VmStateChange::SavePowerChange] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered on clnPwrOff=false hostReporting=host-104

2016-02-12T09:41:04.816Z info fdm[314AFB70] [Originator@6876 sub=Invt opID=SWI-1b6d6111] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx to host host-104 (moId on host=30)

2016-02-12T09:41:04.816Z verbose fdm[314AFB70] [Originator@6876 sub=Invt opID=SWI-1b6d6111] [VmStateChange::SaveToInventory] Initialize VmStateChange from inventory for poweredOn vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx

2016-02-12T09:41:04.816Z verbose fdm[31635B70] [Originator@6876 sub=Policy opID=SWI-29ffb128] [VmOperationsManager::RequestCancelPlacement] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx: Transitioned to 'Cancel' state for 'VmFailover'

2016-02-12T09:41:04.816Z verbose fdm[31635B70] [Originator@6876 sub=Placement opID=SWI-29ffb128] [PlacementManagerImpl::CancelVmPlacement] removed 1 of 2 vms

2016-02-12T09:41:04.817Z verbose fdm[31635B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-51c7cea2] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 4(known last version = 3)

2016-02-12T09:41:04.818Z verbose fdm[315F4B70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-104

2016-02-12T09:41:04.818Z verbose fdm[315F4B70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.ha.VmRestartedByHAEvent vm=/vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx host=host-104 tag=host-104:1999577011:25

2016-02-12T09:41:04.818Z verbose fdm[315F4B70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[140], fdmService. Applied change to temp map.

2016-02-12T09:41:04.818Z verbose fdm[315F4B70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:41:04.881Z verbose fdm[31635B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-14cf17a0] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 5(known last version = 4)

2016-02-12T09:41:04.893Z verbose fdm[314AFB70] [Originator@6876 sub=vpxTaskInfo opID=SWI-2818140c] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 6(known last version = 5)

2016-02-12T09:41:04.896Z verbose fdm[31531B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-3a83b485] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 7(known last version = 6)

2016-02-12T09:41:04.897Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=SWI-1cc0a5c6] [ExecutionManagerImpl::HandleVmQuestion] Question while failing over vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx, Label = msg.uuid.altered

2016-02-12T09:41:04.900Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-2bc378e9] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered on clnPwrOff=false hostReporting=host-104

2016-02-12T09:41:04.900Z info fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-2bc378e9] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx to host host-104 (moId on host=31)

2016-02-12T09:41:04.900Z verbose fdm[31572B70] [Originator@6876 sub=Invt opID=SWI-2bc378e9] [VmStateChange::SaveToInventory] Initialize VmStateChange from inventory for poweredOn vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx

2016-02-12T09:41:04.900Z verbose fdm[31572B70] [Originator@6876 sub=Placement opID=SWI-2bc378e9] [RR::ResetAwaitingChangeVms] 0 Vms have been updated

2016-02-12T09:41:04.900Z verbose fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-2e371f71] [VmOperationsManager::RequestCancelPlacement] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx: Transitioned to 'Cancel' state for 'VmFailover'

2016-02-12T09:41:04.901Z verbose fdm[31531B70] [Originator@6876 sub=Placement opID=SWI-2e371f71] [PlacementManagerImpl::CancelVmPlacement] removed 1 of 2 vms

2016-02-12T09:41:04.902Z verbose fdm[31531B70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-104

2016-02-12T09:41:04.902Z verbose fdm[31531B70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.ha.VmRestartedByHAEvent vm=/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx host=host-104 tag=host-104:1999577011:26

2016-02-12T09:41:04.902Z verbose fdm[31531B70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[141], fdmService. Applied change to temp map.

2016-02-12T09:41:04.902Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=SWI-1cc0a5c6] [ExecutionManagerImpl::HandleVmQuestion] Answered question msg.uuid.altered with choice button.uuid.movedTheVM

2016-02-12T09:41:04.902Z verbose fdm[31531B70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:41:04.908Z verbose fdm[314AFB70] [Originator@6876 sub=vpxTaskInfo opID=SWI-328c3b95] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 8(known last version = 7)

2016-02-12T09:41:04.912Z verbose fdm[31635B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-149bcd20] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 9(known last version = 😎

2016-02-12T09:41:04.914Z verbose fdm[315F4B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-b7fa72] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 10(known last version = 9)

2016-02-12T09:41:04.918Z verbose fdm[314AFB70] [Originator@6876 sub=vpxTaskInfo opID=SWI-2e9b52bc] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 11(known last version = 10)

2016-02-12T09:41:04.920Z verbose fdm[31739B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-5754cc8b] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 12(known last version = 11)

2016-02-12T09:41:04.923Z verbose fdm[314AFB70] [Originator@6876 sub=vpxTaskInfo opID=SWI-40e678] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 13(known last version = 12)

2016-02-12T09:41:04.928Z verbose fdm[314AFB70] [Originator@6876 sub=vpxTaskInfo opID=SWI-24dad069] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 14(known last version = 13)

2016-02-12T09:41:04.976Z verbose fdm[31572B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-19a8b066] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 15(known last version = 14)

2016-02-12T09:41:05.013Z verbose fdm[31572B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-11d505b3] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 16(known last version = 15)

2016-02-12T09:41:05.048Z verbose fdm[31739B70] [Originator@6876 sub=Cluster opID=SWI-2cf6495] [ClusterDatastore::WriteProtect] VM /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/XXXXHPEMS/XXXXHPEMS.vmx protected; index 1 mem=0 cpu=0 overhead=146862080 vFlashCache=0

2016-02-12T09:41:05.048Z verbose fdm[31635B70] [Originator@6876 sub=Cluster opID=SWI-386933ea] [ClusterDatastore::WriteProtect] VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx protected; index 2 mem=0 cpu=0 overhead=182910976 vFlashCache=0

2016-02-12T09:41:05.049Z verbose fdm[314F0B70] [Originator@6876 sub=Cluster opID=SWI-6f412df] [ClusterDatastore::WriteProtect] VM /vmfs/volumes/569e380c-769fb6c4-bd59-0017a4779c1e/VMware vRealize Orchestrator Appliance/VMware vRealize Orchestrator Appliance.vmx protected; index 0 mem=0 cpu=0 overhead=182910976 vFlashCache=0

2016-02-12T09:41:05.049Z verbose fdm[316B7B70] [Originator@6876 sub=Cluster opID=SWI-202f20a] [ClusterDatastore::WriteProtect] VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXDIRTRXEN/XXXXDIRTRXEN.vmx protected; index 5 mem=0 cpu=0 overhead=183046144 vFlashCache=0

2016-02-12T09:41:05.049Z verbose fdm[315F4B70] [Originator@6876 sub=Cluster opID=SWI-7dd473a3] [ClusterDatastore::WriteProtect] VM /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx protected; index 4 mem=0 cpu=0 overhead=98258944 vFlashCache=0

2016-02-12T09:41:05.065Z info fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-f606f8e] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered on clnPwrOff=false hostReporting=host-10

2016-02-12T09:41:05.065Z info fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-f606f8e] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx to host host-10 (moId on host=87)

2016-02-12T09:41:05.065Z verbose fdm[315F4B70] [Originator@6876 sub=Invt opID=SWI-f606f8e] [VmStateChange::SaveToInventory] Initialize VmStateChange from inventory for poweredOn vm /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx

2016-02-12T09:41:05.066Z verbose fdm[314F0B70] [Originator@6876 sub=Policy opID=SWI-43bdb85] [VmOperationsManager::RequestCancelPlacement] VM ID /vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx: Transitioned to 'Cancel' state for 'VmFailover'

2016-02-12T09:41:05.066Z verbose fdm[3177AB70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-10

2016-02-12T09:41:05.066Z verbose fdm[314F0B70] [Originator@6876 sub=Placement opID=SWI-43bdb85] [PlacementManagerImpl::CancelVmPlacement] removed 1 of 2 vms

2016-02-12T09:41:05.066Z verbose fdm[3177AB70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.ha.VmRestartedByHAEvent vm=/vmfs/volumes/566b336c-01421234-18e8-0017a4771c1c/vMA6/vMA6.vmx host=host-10 tag=host-10:-605756034:45

2016-02-12T09:41:05.066Z verbose fdm[3177AB70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[142], fdmService. Applied change to temp map.

2016-02-12T09:41:05.066Z verbose fdm[3177AB70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:41:05.067Z verbose fdm[3177AB70] [Originator@6876 sub=vpxTaskInfo opID=SWI-75c8faee] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 17(known last version = 16)

2016-02-12T09:41:05.080Z verbose fdm[3177AB70] [Originator@6876 sub=vpxTaskInfo opID=SWI-1d8067ac] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 18(known last version = 17)

2016-02-12T09:41:05.087Z verbose fdm[316B7B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-59165c55] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 19(known last version = 18)

2016-02-12T09:41:05.155Z verbose fdm[3177AB70] [Originator@6876 sub=vpxTaskInfo opID=SWI-4b07fe24] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 20(known last version = 19)

2016-02-12T09:41:05.174Z verbose fdm[316B7B70] [Originator@6876 sub=vmMonitor] [HalVmMonitor::CalcVmStaticMemOverhead] for vm id 72

2016-02-12T09:41:05.175Z info fdm[3177AB70] [Originator@6876 sub=Invt opID=SWI-79be13f7] [VmStateChange::SavePowerChange] vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx curPwrState=powered off curPowerOnCount=0 newPwrState=powered on clnPwrOff=false hostReporting=__localhost__

2016-02-12T09:41:05.175Z verbose fdm[3177AB70] [Originator@6876 sub=Invt opID=SWI-79be13f7] [VmStateChange::SavePowerChange] vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx local-host: local power state=powered on; global power state=powered on

2016-02-12T09:41:05.175Z verbose fdm[3177AB70] [Originator@6876 sub=Invt opID=SWI-79be13f7] [VmStateChange::SaveToInventory] vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx from __localhost__ changed inventory cleanPwrOff=(null)

2016-02-12T09:41:05.175Z verbose fdm[3177AB70] [Originator@6876 sub=Invt opID=SWI-79be13f7] [VmStateChange::SaveToInventory] Initialize VmStateChange from inventory for poweredOn vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx

2016-02-12T09:41:05.175Z verbose fdm[316B7B70] [Originator@6876 sub=Policy opID=SWI-5cf445de] [LocalVmMonitoringPolicy::ProcessElements(VmHeartbeatStateChange)] VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: guest heartbeat transitioned to gray

2016-02-12T09:41:05.175Z verbose fdm[316B7B70] [Originator@6876 sub=Policy opID=SWI-5cf445de] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx from stats

2016-02-12T09:41:05.175Z verbose fdm[316B7B70] [Originator@6876 sub=Policy opID=SWI-5cf445de] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: Transitioned to a heartbeat monitoring state. type=1, value=1

2016-02-12T09:41:05.175Z verbose fdm[316B7B70] [Originator@6876 sub=Policy opID=SWI-5cf445de] [LocalVmMonitoringPolicy::ProcessElements(VmHeartbeatStateChange)] VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: guest OS heartbeat transitioned to gray

2016-02-12T09:41:05.175Z verbose fdm[316B7B70] [Originator@6876 sub=Policy opID=SWI-5cf445de] [VmOperationsManager::RequestCancelPlacement] VM ID /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: Transitioned to 'Cancel' state for 'VmFailover'

2016-02-12T09:41:05.175Z verbose fdm[316B7B70] [Originator@6876 sub=Placement opID=SWI-5cf445de] [PlacementManagerImpl::CancelVmPlacement] removed 1 of 2 vms

2016-02-12T09:41:05.177Z verbose fdm[314F0B70] [Originator@6876 sub=vpxTaskInfo opID=SWI-2d982dec] [TaskInfoChannel::GetTaskInfoHandleSuccess] task: haTask-72-vim.VirtualMachine.powerOn-124255224 updated to version 21(known last version = 20)

2016-02-12T09:41:05.177Z verbose fdm[314F0B70] [Originator@6876 sub=vpxLro opID=SWI-2d982dec] [VpxLRO] Dispatching Callback Functor for haTask-72-vim.VirtualMachine.powerOn-124255224 which completed successfully from task-internal-302

2016-02-12T09:41:05.177Z verbose fdm[314F0B70] [Originator@6876 sub=vpxLro opID=SWI-2d982dec] [VpxLRO] Task task-internal-302 has been rescheduled

2016-02-12T09:41:05.177Z verbose fdm[316B7B70] [Originator@6876 sub=vpxLro opID=host-18:29:f22131e-1] [VpxLRO] Task task-internal-302 has been resumed

2016-02-12T09:41:05.177Z verbose fdm[316B7B70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-1] [FailoverAction::PowerOnCompletionCallback] Power on vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx done

2016-02-12T09:41:05.177Z verbose fdm[316B7B70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-1] [ActionScheduler::RemoveAction] Action is removed: opId = host-18:29:f22131e-1

2016-02-12T09:41:05.178Z info fdm[316B7B70] [Originator@6876 sub=Execution opID=host-18:29:f22131e-1] [ExecutionManagerImpl::OnFailoverActionCompletion] Placement completed for /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx

2016-02-12T09:41:05.178Z info fdm[316B7B70] [Originator@6876 sub=vpxLro opID=host-18:29:f22131e-1] [VpxLRO] -- FINISH task-internal-302

2016-02-12T09:41:05.178Z verbose fdm[31572B70] [Originator@6876 sub=FDM opID=host-18:29:f22131e-1-SWI-5b010ca0] [FdmService] New event: EventEx=com.vmware.vc.ha.VmRestartedByHAEvent vm=/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx host=host-18 tag=host-18:-548212892:28

2016-02-12T09:41:05.178Z verbose fdm[31572B70] [Originator@6876 sub=PropertyProvider opID=host-18:29:f22131e-1-SWI-5b010ca0] RecordOp ADD: event[143], fdmService. Applied change to temp map.

2016-02-12T09:41:05.178Z verbose fdm[31572B70] [Originator@6876 sub=PropertyProvider opID=host-18:29:f22131e-1-SWI-5b010ca0] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:41:06.051Z verbose fdm[31572B70] [Originator@6876 sub=Cluster opID=SWI-21e40b0d] [HBDatastore::WritePowerOnList] Writing power-on-list @ /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e/.vSphere-HA/FDM-350fccf3-943d-4566-959a-344c58029951-7-c3e6b0f-XXXXVCT/host-18-poweron with 12 vms isolated=false

2016-02-12T09:41:11.058Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:41:11.058Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:41:11.058Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:41:11.058Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:41:12.058Z verbose fdm[315F4B70] [Originator@6876 sub=Cluster opID=SWI-2f12e244] [HBDatastore::WritePowerOnList] Writing power-on-list @ /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e/.vSphere-HA/FDM-350fccf3-943d-4566-959a-344c58029951-7-c3e6b0f-XXXXVCT/host-18-poweron with 12 vms isolated=false

2016-02-12T09:41:12.058Z verbose fdm[314F0B70] [Originator@6876 sub=Cluster opID=SWI-7c4947f3] [HBDatastore::WritePowerOnList] Writing power-on-list @ /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e/.vSphere-HA/FDM-350fccf3-943d-4566-959a-344c58029951-7-c3e6b0f-XXXXVCT/host-18-poweron with 12 vms isolated=false

2016-02-12T09:41:12.897Z verbose fdm[314F0B70] [Originator@6876 sub=Placement opID=SWI-c4f681] [RR::ResetAwaitingChangeVms] 0 Vms have been updated

2016-02-12T09:41:14.061Z warning fdm[316B7B70] [Originator@6876 sub=Execution] [ExecutionCommandUpdate::Deserialize] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx failed to failover on host-15. FaultType: vim.fault.InvalidState

2016-02-12T09:41:14.061Z verbose fdm[316B7B70] [Originator@6876 sub=Execution] [ExecutionManagerImpl::HandleExecutionUpdateMessage] Placement completed for 1 vms

2016-02-12T09:41:14.061Z verbose fdm[316B7B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::HandleFailedPlacement(VmFailover)] Reset state for 1 vms (opID=[host-18:28:721631d6-0])

2016-02-12T09:41:14.061Z info fdm[316B7B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::HandleFailedPlacement(VmFailover)] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx will be retried without backoff,after a failure at host host-15, delay = 120 secs

2016-02-12T09:41:14.091Z warning fdm[316B7B70] [Originator@6876 sub=Execution] [ExecutionCommandUpdate::Deserialize] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx failed to failover on host-10. FaultType: vim.fault.InvalidState

2016-02-12T09:41:14.091Z warning fdm[316B7B70] [Originator@6876 sub=Execution] [ExecutionCommandUpdate::Deserialize] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx failed to failover on host-10. FaultType: vim.fault.InvalidState

2016-02-12T09:41:14.091Z verbose fdm[316B7B70] [Originator@6876 sub=Execution] [ExecutionManagerImpl::HandleExecutionUpdateMessage] Placement completed for 1 vms

2016-02-12T09:41:14.091Z verbose fdm[316B7B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::HandleFailedPlacement(VmFailover)] Reset state for 2 vms (opID=[host-18:27:1d51fe6b-2])

2016-02-12T09:41:14.091Z info fdm[316B7B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::HandleFailedPlacement(VmFailover)] Vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx will be retried without backoff,after a failure at host host-10, delay = 120 secs

2016-02-12T09:41:14.091Z info fdm[316B7B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::HandleFailedPlacement(VmFailover)] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx will be retried without backoff,after a failure at host host-10, delay = 120 secs

2016-02-12T09:41:14.624Z verbose fdm[316B7B70] [Originator@6876 sub=Invt] [HalVmMonitor::HandleVmRemoval] Removing vm 71

2016-02-12T09:41:14.624Z info fdm[314F0B70] [Originator@6876 sub=Invt opID=SWI-3138f8c] [InventoryManagerImpl::RemoveVmLocked] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx (protected) removed from local host; on 0 hosts

2016-02-12T09:41:14.624Z verbose fdm[314F0B70] [Originator@6876 sub=Invt opID=SWI-3138f8c] [InventoryManagerImpl::RemoveVmLocked] marking protected vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx as in unknown power state

2016-02-12T09:41:14.624Z verbose fdm[314F0B70] [Originator@6876 sub=Invt opID=SWI-3138f8c] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx removed from __localhost__

2016-02-12T09:41:14.625Z verbose fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-793531e7] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:41:14.625Z info fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-793531e7] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:41:14.625Z verbose fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-793531e7] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx from stats

2016-02-12T09:41:14.625Z verbose fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-793531e7] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:41:14.625Z info fdm[31531B70] [Originator@6876 sub=Policy opID=SWI-793531e7] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:41:14.635Z verbose fdm[314F0B70] [Originator@6876 sub=Invt opID=SWI-135f72dc] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx removed from host-15

2016-02-12T09:41:14.821Z verbose fdm[316B7B70] [Originator@6876 sub=Execution] [ExecutionManagerImpl::HandleExecutionUpdateMessage] Placement completed for 2 vms

2016-02-12T09:41:15.063Z verbose fdm[316B7B70] [Originator@6876 sub=Cluster opID=SWI-801d30d] [HBDatastore::WritePowerOnList] Writing power-on-list @ /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e/.vSphere-HA/FDM-350fccf3-943d-4566-959a-344c58029951-7-c3e6b0f-XXXXVCT/host-18-poweron with 12 vms isolated=false

2016-02-12T09:41:21.069Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:41:21.069Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:41:21.069Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:41:21.069Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:41:22.846Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-7995245e] [VmStateChange::SaveToInventory] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx removed from host-10

2016-02-12T09:41:22.853Z verbose fdm[314F0B70] [Originator@6876 sub=Invt opID=SWI-5eeb4e5d] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx removed from host-10

2016-02-12T09:41:31.088Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:41:31.088Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:41:31.088Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:41:31.088Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:41:34.072Z verbose fdm[3177AB70] [Originator@6876 sub=Placement] [PlacementManagerImpl::CancelVmPlacement] removed 0 of 4 vms

2016-02-12T09:41:34.073Z info fdm[3177AB70] [Originator@6876 sub=Policy] [VmOperationsManager::PerformPlacements] Sending a list of 2 VMs to the placement manager for placement.

2016-02-12T09:41:34.073Z info fdm[3177AB70] [Originator@6876 sub=Placement] [PlacementManagerImpl::AddVmToPlace] 2 Vms added, 0 VmRecord created

2016-02-12T09:41:34.073Z verbose fdm[3177AB70] [Originator@6876 sub=Placement] [PlacementManagerImpl::ReevaluateAllVms] Reevaluate all to-be-placed Vms.

2016-02-12T09:41:34.073Z verbose fdm[3177AB70] [Originator@6876 sub=Placement] [PlacementManagerImpl::IssuePlacementStartCompleteEventLocked] Failover operation in progress on 4 Vms: 0 VMs being restarted, 4 VMs waiting for a retry, 0 VMs waiting for resources, 0 inaccessible vSAN VMs

2016-02-12T09:41:34.073Z info fdm[3177AB70] [Originator@6876 sub=Placement] [RR::CreatePlacementRequest] 4 total VMs with some excluded: 0 VM disabled; 0 VMs being placed; 0 VMs waiting resources;0 VMs awaiting vSAN VM access change; 4 VMs in time delay;

2016-02-12T09:41:34.073Z verbose fdm[3177AB70] [Originator@6876 sub=Placement] [PlacementManagerImpl::ReevaluateAllVms] No place request is outstanding.

2016-02-12T09:41:34.073Z verbose fdm[31531B70] [Originator@6876 sub=FDM opID=SWI-1e48d125] [FdmService] New event: EventEx=com.vmware.vc.HA.ClusterFailoverInProgressEvent vm= host= tag=host-18:-548212892:29

2016-02-12T09:41:34.073Z verbose fdm[31531B70] [Originator@6876 sub=PropertyProvider opID=SWI-1e48d125] RecordOp ADD: event[144], fdmService. Applied change to temp map.

2016-02-12T09:41:34.073Z verbose fdm[31531B70] [Originator@6876 sub=PropertyProvider opID=SWI-1e48d125] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:41:37.075Z verbose fdm[315F4B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::CancelVmPlacement] removed 0 of 4 vms

2016-02-12T09:41:37.075Z info fdm[315F4B70] [Originator@6876 sub=Policy] [VmOperationsManager::PerformPlacements] Sending a list of 2 VMs to the placement manager for placement.

2016-02-12T09:41:37.075Z info fdm[315F4B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::AddVmToPlace] 2 Vms added, 0 VmRecord created

2016-02-12T09:41:37.075Z verbose fdm[315F4B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::ReevaluateAllVms] Reevaluate all to-be-placed Vms.

2016-02-12T09:41:37.075Z info fdm[315F4B70] [Originator@6876 sub=Placement] [RR::CreatePlacementRequest] 4 total VMs with some excluded: 0 VM disabled; 0 VMs being placed; 0 VMs waiting resources;0 VMs awaiting vSAN VM access change; 4 VMs in time delay;

2016-02-12T09:41:37.075Z verbose fdm[315F4B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::ReevaluateAllVms] No place request is outstanding.

2016-02-12T09:41:41.105Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:41:41.105Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:41:41.105Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:41:41.106Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:41:47.268Z verbose fdm[31739B70] [Originator@6876 sub=Invt opID=SWI-477dabe6] [VmHeartbeatStateChange::SaveToInventory] vm /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx changed toolsStatus=toolsOk

2016-02-12T09:41:51.125Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:41:51.125Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:41:51.125Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:41:51.125Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:41:55.958Z verbose fdm[31739B70] [Originator@6876 sub=Placement] [RR::ResetVms] Reset 0 Vms. Records = 4

2016-02-12T09:41:55.958Z verbose fdm[31739B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::ReevaluateAllVms] There is failed execution since last evaluation, reset Vms awaiting resources

2016-02-12T09:41:55.958Z verbose fdm[31739B70] [Originator@6876 sub=Placement] [RR::ResetAwaitingChangeVms] 0 Vms have been updated

2016-02-12T09:41:55.958Z info fdm[31739B70] [Originator@6876 sub=Placement] [RR::CreatePlacementRequest] 4 total VMs with some excluded: 0 VM disabled; 0 VMs being placed; 0 VMs waiting resources;0 VMs awaiting vSAN VM access change; 4 VMs in time delay;

2016-02-12T09:42:01.139Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:42:01.139Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:42:01.139Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:42:01.139Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:42:11.150Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:42:11.150Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:42:11.150Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:42:11.150Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:42:17.255Z verbose fdm[314F0B70] [Originator@6876 sub=Policy opID=SWI-5d17c771] [LocalVmMonitoringPolicy::ProcessElements(VmHeartbeatStateChange)] VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: guest heartbeat transitioned to yellow

2016-02-12T09:42:17.255Z verbose fdm[314F0B70] [Originator@6876 sub=Policy opID=SWI-5d17c771] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx from stats

2016-02-12T09:42:17.255Z verbose fdm[314F0B70] [Originator@6876 sub=Policy opID=SWI-5d17c771] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: Transitioned to a heartbeat monitoring state. type=1, value=1

2016-02-12T09:42:17.255Z verbose fdm[314F0B70] [Originator@6876 sub=Policy opID=SWI-5d17c771] [LocalVmMonitoringPolicy::ProcessElements(VmHeartbeatStateChange)] VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: guest OS heartbeat transitioned to yellow

2016-02-12T09:42:21.165Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:42:21.165Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:42:21.165Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:42:21.165Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:42:24.704Z verbose fdm[316B7B70] [Originator@6876 sub=Placement opID=SWI-1ac2601] [RR::ResetAwaitingChangeVms] 0 Vms have been updated

2016-02-12T09:42:31.175Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:42:31.175Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:42:31.175Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:42:31.176Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:42:41.192Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:42:41.192Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:42:41.192Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:42:41.192Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:42:47.254Z verbose fdm[316B7B70] [Originator@6876 sub=Policy opID=SWI-25793598] [LocalVmMonitoringPolicy::ProcessElements(VmHeartbeatStateChange)] VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: guest heartbeat transitioned to green

2016-02-12T09:42:47.254Z verbose fdm[316B7B70] [Originator@6876 sub=Policy opID=SWI-25793598] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx from stats

2016-02-12T09:42:47.254Z verbose fdm[316B7B70] [Originator@6876 sub=Policy opID=SWI-25793598] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: Transitioned to a heartbeat monitoring state. type=1, value=1

2016-02-12T09:42:47.254Z verbose fdm[316B7B70] [Originator@6876 sub=Policy opID=SWI-25793598] [LocalVmMonitoringPolicy::ProcessElements(VmHeartbeatStateChange)] VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx: guest OS heartbeat transitioned to green

2016-02-12T09:42:51.208Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:42:51.208Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:42:51.208Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:42:51.209Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:42:55.959Z verbose fdm[31531B70] [Originator@6876 sub=Placement] [RR::ResetVms] Reset 0 Vms. Records = 4

2016-02-12T09:42:55.960Z info fdm[31531B70] [Originator@6876 sub=Placement] [RR::CreatePlacementRequest] 4 total VMs with some excluded: 0 VM disabled; 0 VMs being placed; 0 VMs waiting resources;0 VMs awaiting vSAN VM access change; 4 VMs in time delay;

2016-02-12T09:43:01.226Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:43:01.226Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:43:01.226Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:43:01.226Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:43:11.246Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:43:11.246Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:43:11.246Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:43:11.246Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:43:21.260Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:43:21.260Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:43:21.260Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:43:21.261Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:43:31.276Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:43:31.276Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:43:31.276Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:43:31.276Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:43:41.291Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:43:41.291Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:43:41.291Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:43:41.291Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:43:51.308Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:43:51.308Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:43:51.308Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:43:51.308Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:43:55.960Z verbose fdm[31739B70] [Originator@6876 sub=Placement] [RR::ResetVms] Reset 0 Vms. Records = 4

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ProcessRequestsLoop] Process a batch of 1 requests

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [LocalDrmWrapperPlacementEngine::SetDrsOptions] Required number of AgentVm = 0

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructClusterSnapshot] Start snapshotting.

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructClusterSnapshot] Retrieve hosts.

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::SetHostNetworkInfo] host __localhost__ has 0 proxy switches backed by 0 pnics, and 0 pnics used by 0 vnics

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructOneDrmHost] Successfully retrieve host,hostId = __localhost__, drmId = 0, UnreservedMem = 66653MB, UnreservedCpu = 8352MHz, vFlashFreeCapacity = 0MB

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::SetHostNetworkInfo] host host-15 has 0 proxy switches backed by 0 pnics, and 0 pnics used by 0 vnics

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructOneDrmHost] Successfully retrieve host,hostId = host-15, drmId = 1, UnreservedMem = 90043MB, UnreservedCpu = 27496MHz, vFlashFreeCapacity = 0MB

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::SetHostNetworkInfo] host host-10 has 0 proxy switches backed by 0 pnics, and 0 pnics used by 0 vnics

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructOneDrmHost] Successfully retrieve host,hostId = host-10, drmId = 2, UnreservedMem = 90320MB, UnreservedCpu = 27496MHz, vFlashFreeCapacity = 0MB

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::SetHostNetworkInfo] host host-104 has 0 proxy switches backed by 0 pnics, and 0 pnics used by 0 vnics

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructOneDrmHost] Successfully retrieve host,hostId = host-104, drmId = 3, UnreservedMem = 124087MB, UnreservedCpu = 30239MHz, vFlashFreeCapacity = 0MB

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructClusterSnapshot] Retrieve RPs.

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::RetrieveDrmResourcePools] Pool for root: UnreservedCpu = 93583Mhz, UnreservedMem = 371103Mb

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::RetrieveDrmVmOnHost] Retrieved 6 powered-on Vms for Host host-104

2016-02-12T09:43:55.961Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::RetrieveDrmVmOnHost] Retrieved 13 powered-on Vms for Host host-10

2016-02-12T09:43:55.962Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::RetrieveDrmVmOnHost] Retrieved 11 powered-on Vms for Host host-15

2016-02-12T09:43:55.962Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::RetrieveDrmVmOnHost] Retrieved 12 powered-on Vms for Host __localhost__

2016-02-12T09:43:55.962Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [PlacementManagerImpl::GetPendingPlacementExecution] 0 Vms are being placed

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::GenerateRecommendation] Snapshot is valid

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ProcessRestartPriority] 4 Vms have priority 32

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::GenerateRecommendation] 4 Vms are to be powered on

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-686069f5] [InventoryManagerImpl::ComputeCompatMap] Compat list for /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx: __localhost__, host-15, host-104, host-10, host-125.

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-686069f5] [InventoryManagerImpl::ComputeCompatMap] Compat list for /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx: __localhost__, host-15, host-104, host-10, host-125.

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-686069f5] [InventoryManagerImpl::ComputeCompatMap] Compat list for /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: __localhost__, host-15, host-104, host-10, host-125.

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-686069f5] [InventoryManagerImpl::ComputeCompatMap] Compat list for /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx: __localhost__, host-15, host-104, host-10, host-125.

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructOneDrmVm] drmVm vmId=/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx drmId=2000042 numCompatHost=4 onHost=1 vmState=5 type=3 cpuRes=0 memRes=0 overhead=84 vFlashRes=0

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructOneDrmVm] drmVm vmId=/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx drmId=2000043 numCompatHost=4 onHost=0 vmState=5 type=3 cpuRes=0 memRes=0 overhead=84 vFlashRes=0

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructOneDrmVm] drmVm vmId=/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx drmId=2000044 numCompatHost=4 onHost=3 vmState=5 type=3 cpuRes=0 memRes=0 overhead=174 vFlashRes=0

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::ConstructOneDrmVm] drmVm vmId=/vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx drmId=2000045 numCompatHost=4 onHost=2 vmState=5 type=3 cpuRes=0 memRes=0 overhead=84 vFlashRes=0

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::GenerateRecommendation] 4 vms added to domain config

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::InvokeDrsMultiplePasses] Pass2: respect host preference but not failover hosts

2016-02-12T09:43:55.964Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::InvokeDrsAlgorithmForPlacement] Calling mapVm to place 4 Vms

2016-02-12T09:43:55.965Z info fdm[312E9B70] [Originator@6876 sub=drmLogger opID=SWI-686069f5] Drmdump written: /var/log/vmware/fdm/drmDump/cluster/4386609008-mapVm.dump

2016-02-12T09:43:55.970Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [DrmPE::InvokeDrsMultiplePasses] All Vms are placed (4) in pass 2

2016-02-12T09:43:55.970Z info fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-686069f5] [PlacementManagerImpl::ProcessRecommendation] 4 Vms have been updated

2016-02-12T09:43:55.970Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=SWI-11cc895c] [ExecutionManagerImpl::ConstructAndDispatchCommands] Place /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx on host-104 (cmd ID host-18:31:b4e0025)

2016-02-12T09:43:55.970Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=SWI-11cc895c] [ExecutionManagerImpl::ConstructAndDispatchCommands] Place /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx on host-104 (cmd ID host-18:31:b4e0025)

2016-02-12T09:43:55.970Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=SWI-11cc895c] [ExecutionManagerImpl::ConstructAndDispatchCommands] Place /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx on host-104 (cmd ID host-18:31:b4e0025)

2016-02-12T09:43:55.970Z verbose fdm[31739B70] [Originator@6876 sub=Execution opID=SWI-11cc895c] [ExecutionManagerImpl::ConstructAndDispatchCommands] Place /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx on host-104 (cmd ID host-18:31:b4e0025)

2016-02-12T09:43:56.116Z info fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-2deb82ef] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-104

2016-02-12T09:43:56.116Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-2deb82ef] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:43:56.116Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-2deb82ef] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx from host-104 changed inventory datastoresInUse changed

2016-02-12T09:43:56.116Z verbose fdm[312E9B70] [Originator@6876 sub=Policy opID=SWI-40e13076] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:43:56.116Z info fdm[312E9B70] [Originator@6876 sub=Policy opID=SWI-40e13076] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:43:56.116Z verbose fdm[312E9B70] [Originator@6876 sub=Policy opID=SWI-40e13076] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx from stats

2016-02-12T09:43:56.116Z verbose fdm[312E9B70] [Originator@6876 sub=Policy opID=SWI-40e13076] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:43:56.116Z info fdm[312E9B70] [Originator@6876 sub=Policy opID=SWI-40e13076] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:43:56.116Z verbose fdm[312E9B70] [Originator@6876 sub=Policy opID=SWI-40e13076] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:43:56.129Z info fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-34b441] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-104

2016-02-12T09:43:56.129Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-34b441] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:43:56.129Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-34b441] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx from host-104 changed inventory datastoresInUse changed numCpu=2

2016-02-12T09:43:56.129Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-4261782] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:43:56.129Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-4261782] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx

2016-02-12T09:43:56.129Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-4261782] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx from stats

2016-02-12T09:43:56.129Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-4261782] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:43:56.129Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-4261782] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx

2016-02-12T09:43:56.129Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-4261782] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:43:56.225Z info fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-60d159ee] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-104

2016-02-12T09:43:56.225Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-60d159ee] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:43:56.225Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-60d159ee] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx from host-104 changed inventory datastoresInUse changed

2016-02-12T09:43:56.225Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-506749ea] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:43:56.226Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-506749ea] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx

2016-02-12T09:43:56.226Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-506749ea] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx from stats

2016-02-12T09:43:56.226Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-506749ea] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:43:56.226Z info fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-506749ea] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx

2016-02-12T09:43:56.226Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-506749ea] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:43:56.247Z info fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-2d25cae3] [VmStateChange::SavePowerChange] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered off clnPwrOff=false hostReporting=host-104

2016-02-12T09:43:56.247Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-2d25cae3] [VmStateChange::SavePowerChange] Vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx uncleanly powered off; alreadyCreated=true

2016-02-12T09:43:56.247Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-2d25cae3] [VmStateChange::SaveToInventory] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx from host-104 changed inventory datastoresInUse changed

2016-02-12T09:43:56.247Z verbose fdm[315B3B70] [Originator@6876 sub=Policy opID=SWI-c2b143d] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx: Transitioned to a heartbeat monitoring state. type=0, value=0

2016-02-12T09:43:56.247Z info fdm[315B3B70] [Originator@6876 sub=Policy opID=SWI-c2b143d] [LocalVmMonitoringPolicy::DisableVmAppMonitoring(vmId)] Stopped monitoring APP heartbeat for VM /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx

2016-02-12T09:43:56.247Z verbose fdm[315B3B70] [Originator@6876 sub=Policy opID=SWI-c2b143d] [LocalVmMonitoringPolicy::UnsubscribeFromQuickStats] Unsubscribed /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx from stats

2016-02-12T09:43:56.247Z verbose fdm[315B3B70] [Originator@6876 sub=Policy opID=SWI-c2b143d] [VmOperationsManager::RequestHeartbeatMonitoring] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx: Transitioned to a heartbeat monitoring state. type=1, value=0

2016-02-12T09:43:56.247Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-603db708] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx from host-104 changed inventory overhead=123633664

2016-02-12T09:43:56.247Z info fdm[315B3B70] [Originator@6876 sub=Policy opID=SWI-c2b143d] [LocalVmMonitoringPolicy::DisableVmGuestMonitoring(vmId)] Stopped monitoring GUEST heartbeat for VM /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx

2016-02-12T09:43:56.247Z verbose fdm[315B3B70] [Originator@6876 sub=Policy opID=SWI-c2b143d] [VmOperationsManager::RequestMonitoring] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx: Transitioned to 'MonitorPower' state

2016-02-12T09:43:56.248Z verbose fdm[31635B70] [Originator@6876 sub=Invt opID=SWI-73bb9f94] [VmStateChange::SaveToInventory] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx from host-104 changed inventory overhead=146780160

2016-02-12T09:43:56.577Z verbose fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-2ab9e940] [VmStateChange::SaveToInventory] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx from host-104 changed inventory overhead=123985920

2016-02-12T09:43:57.048Z info fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-3747f124] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered on clnPwrOff=false hostReporting=host-104

2016-02-12T09:43:57.048Z info fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-3747f124] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx to host host-104 (moId on host=34)

2016-02-12T09:43:57.048Z verbose fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-3747f124] [VmStateChange::SaveToInventory] Initialize VmStateChange from inventory for poweredOn vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:43:57.049Z info fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-3747f124] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered on clnPwrOff=false hostReporting=host-104

2016-02-12T09:43:57.049Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-54288ed6] [VmOperationsManager::RequestCancelPlacement] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx: Transitioned to 'Cancel' state for 'VmFailover'

2016-02-12T09:43:57.049Z info fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-3747f124] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx to host host-104 (moId on host=35)

2016-02-12T09:43:57.049Z verbose fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-3747f124] [VmStateChange::SaveToInventory] Initialize VmStateChange from inventory for poweredOn vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx

2016-02-12T09:43:57.049Z verbose fdm[31739B70] [Originator@6876 sub=Placement opID=SWI-54288ed6] [PlacementManagerImpl::CancelVmPlacement] removed 1 of 2 vms

2016-02-12T09:43:57.049Z verbose fdm[31739B70] [Originator@6876 sub=Policy opID=SWI-54288ed6] [VmOperationsManager::RequestCancelPlacement] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx: Transitioned to 'Cancel' state for 'VmFailover'

2016-02-12T09:43:57.049Z verbose fdm[31739B70] [Originator@6876 sub=Placement opID=SWI-54288ed6] [PlacementManagerImpl::CancelVmPlacement] removed 1 of 2 vms

2016-02-12T09:43:57.060Z info fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-32b9aec4] [VmStateChange::SavePowerChange] vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered on clnPwrOff=false hostReporting=host-104

2016-02-12T09:43:57.060Z verbose fdm[31739B70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-104

2016-02-12T09:43:57.060Z info fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-32b9aec4] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx to host host-104 (moId on host=32)

2016-02-12T09:43:57.060Z verbose fdm[312E9B70] [Originator@6876 sub=Invt opID=SWI-32b9aec4] [VmStateChange::SaveToInventory] Initialize VmStateChange from inventory for poweredOn vm /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx

2016-02-12T09:43:57.060Z verbose fdm[3177AB70] [Originator@6876 sub=Policy opID=SWI-3c80dfe0] [VmOperationsManager::RequestCancelPlacement] VM ID /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx: Transitioned to 'Cancel' state for 'VmFailover'

2016-02-12T09:43:57.060Z verbose fdm[3177AB70] [Originator@6876 sub=Placement opID=SWI-3c80dfe0] [PlacementManagerImpl::CancelVmPlacement] removed 1 of 2 vms

2016-02-12T09:43:57.061Z verbose fdm[31739B70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.ha.VmRestartedByHAEvent vm=/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx host=host-104 tag=host-104:1999577011:27

2016-02-12T09:43:57.061Z verbose fdm[31739B70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[145], fdmService. Applied change to temp map.

2016-02-12T09:43:57.061Z verbose fdm[31739B70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:43:57.061Z verbose fdm[315B3B70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-104

2016-02-12T09:43:57.061Z verbose fdm[315B3B70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.ha.VmRestartedByHAEvent vm=/vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx host=host-104 tag=host-104:1999577011:28

2016-02-12T09:43:57.061Z verbose fdm[315B3B70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[146], fdmService. Applied change to temp map.

2016-02-12T09:43:57.061Z verbose fdm[315B3B70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:43:57.061Z verbose fdm[3177AB70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-104

2016-02-12T09:43:57.062Z verbose fdm[3177AB70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.ha.VmRestartedByHAEvent vm=/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx host=host-104 tag=host-104:1999577011:29

2016-02-12T09:43:57.062Z verbose fdm[3177AB70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[147], fdmService. Applied change to temp map.

2016-02-12T09:43:57.062Z verbose fdm[3177AB70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:43:57.159Z info fdm[315B3B70] [Originator@6876 sub=Invt opID=SWI-30ea0a28] [VmStateChange::SavePowerChange] vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx curPwrState=unknown curPowerOnCount=0 newPwrState=powered on clnPwrOff=false hostReporting=host-104

2016-02-12T09:43:57.159Z info fdm[315B3B70] [Originator@6876 sub=Invt opID=SWI-30ea0a28] [InventoryManagerImpl::AddVmLocked] Adding vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx to host host-104 (moId on host=33)

2016-02-12T09:43:57.159Z verbose fdm[315B3B70] [Originator@6876 sub=Invt opID=SWI-30ea0a28] [VmStateChange::SaveToInventory] Initialize VmStateChange from inventory for poweredOn vm /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx

2016-02-12T09:43:57.159Z verbose fdm[315B3B70] [Originator@6876 sub=Placement opID=SWI-30ea0a28] [RR::ResetAwaitingChangeVms] 0 Vms have been updated

2016-02-12T09:43:57.159Z verbose fdm[312E9B70] [Originator@6876 sub=Policy opID=SWI-5540e12e] [VmOperationsManager::RequestCancelPlacement] VM ID /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx: Transitioned to 'Cancel' state for 'VmFailover'

2016-02-12T09:43:57.159Z verbose fdm[312E9B70] [Originator@6876 sub=Placement opID=SWI-5540e12e] [PlacementManagerImpl::CancelVmPlacement] removed 1 of 2 vms

2016-02-12T09:43:57.162Z verbose fdm[3177AB70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-104

2016-02-12T09:43:57.162Z verbose fdm[3177AB70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.ha.VmRestartedByHAEvent vm=/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/XXXXLICXEN/XXXXLICXEN.vmx host=host-104 tag=host-104:1999577011:30

2016-02-12T09:43:57.162Z verbose fdm[3177AB70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[148], fdmService. Applied change to temp map.

2016-02-12T09:43:57.162Z verbose fdm[3177AB70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:44:00.326Z verbose fdm[3177AB70] [Originator@6876 sub=Cluster opID=SWI-5396c6c2] [ClusterDatastore::WriteProtect] VM /vmfs/volumes/56bd9328-cd8407bc-4e11-0017a4779c1e/cludlk/cludlk.vmx protected; index 2 mem=0 cpu=0 overhead=123985920 vFlashCache=0

2016-02-12T09:44:00.326Z verbose fdm[315B3B70] [Originator@6876 sub=Cluster opID=SWI-4fd3156d] [ClusterDatastore::WriteProtect] VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_1/Netscaler_1.vmx protected; index 3 mem=0 cpu=0 overhead=146780160 vFlashCache=0

2016-02-12T09:44:00.326Z verbose fdm[315B3B70] [Originator@6876 sub=Cluster opID=SWI-4fd3156d] [ClusterDatastore::WriteProtect] VM /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx protected; index 4 mem=0 cpu=0 overhead=123633664 vFlashCache=0

2016-02-12T09:44:01.328Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:44:01.328Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:44:01.328Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:44:01.329Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:44:07.063Z verbose fdm[312E9B70] [Originator@6876 sub=Execution] [ExecutionManagerImpl::HandleExecutionUpdateMessage] Placement completed for 4 vms

2016-02-12T09:44:11.341Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:44:11.342Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:44:11.342Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:44:11.342Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:44:21.356Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:44:21.356Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:44:21.356Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:44:21.356Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:44:31.371Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:44:31.371Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:44:31.371Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:44:31.371Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:44:34.706Z verbose fdm[31739B70] [Originator@6876 sub=Placement opID=SWI-6331e5e1] [RR::ResetAwaitingChangeVms] 0 Vms have been updated

2016-02-12T09:44:41.385Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:44:41.385Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:44:41.385Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:44:41.385Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:44:48.937Z verbose fdm[31635B70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-15

2016-02-12T09:44:48.937Z verbose fdm[31635B70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.HA.NotAllHostAddrsPingable vm= host=host-15 tag=host-15:1023502345:18

2016-02-12T09:44:48.937Z verbose fdm[31635B70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[149], fdmService. Applied change to temp map.

2016-02-12T09:44:48.937Z verbose fdm[31635B70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:44:51.399Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566810d1-90c784c0-eb6d-0017a4779c1e on localhost

2016-02-12T09:44:51.399Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/566a90b6-67076b66-a87e-0017a4779c1e on localhost

2016-02-12T09:44:51.399Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/567811e2-8a36a4a4-bb27-0017a4779c1e on localhost

2016-02-12T09:44:51.399Z verbose fdm[FFFE6B70] [Originator@6876 sub=Cluster opID=SWI-3ab50c2a] [ClusterDatastore::CheckSlaveHeartbeats] Checking heartbeat datastore /vmfs/volumes/56781317-b8b46a48-94c3-0017a4779c1e on localhost

2016-02-12T09:44:53.088Z verbose fdm[316B7B70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-10

2016-02-12T09:44:53.088Z verbose fdm[316B7B70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.HA.NotAllHostAddrsPingable vm= host=host-10 tag=host-10:-605756034:46

2016-02-12T09:44:53.088Z verbose fdm[316B7B70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[150], fdmService. Applied change to temp map.

2016-02-12T09:44:53.088Z verbose fdm[316B7B70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:44:54.153Z verbose fdm[314F0B70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-104

2016-02-12T09:44:54.153Z verbose fdm[314F0B70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.HA.NotAllHostAddrsPingable vm= host=host-104 tag=host-104:1999577011:31

2016-02-12T09:44:54.154Z verbose fdm[314F0B70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[151], fdmService. Applied change to temp map.

2016-02-12T09:44:54.154Z verbose fdm[314F0B70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:44:55.939Z verbose fdm[3177AB70] [Originator@6876 sub=FDM] [EventManagerImpl] Received event from host-15

2016-02-12T09:44:55.939Z verbose fdm[3177AB70] [Originator@6876 sub=FDM] [FdmService] New event: EventEx=com.vmware.vc.HA.AllHostAddrsPingable vm= host=host-15 tag=host-15:1023502345:19

2016-02-12T09:44:55.939Z verbose fdm[3177AB70] [Originator@6876 sub=PropertyProvider] RecordOp ADD: event[152], fdmService. Applied change to temp map.

2016-02-12T09:44:55.939Z verbose fdm[3177AB70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: serverTime, fdmService. Applied change to temp map.

2016-02-12T09:44:55.962Z verbose fdm[316B7B70] [Originator@6876 sub=Placement] [PlacementManagerImpl::IssuePlacementStartCompleteEventLocked] Issue failover complete event

2016-02-12T09:44:55.963Z verbose fdm[31739B70] [Originator@6876 sub=FDM opID=SWI-37a63c87] [FdmService] New event: EventEx=com.vmware.vc.HA.ClusterFailoverActionCompletedEvent vm= host= tag=host-18:-548212892:30

2016-02-12T09:44:55.963Z verbose fdm[31739B70] [Originator@6876 sub=PropertyProvider opID=SWI-37a63c87] RecordOp ADD: event[153], fdmService. Applied change to temp map.

HOSTD.LOG

2016-02-12T09:40:44.599Z [36D40B70 verbose 'Locale' opID=HB-host-18@32435-636ad6de-30 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:40:44.626Z [36D40B70 verbose 'Default' opID=HB-host-18@32435-636ad6de-30 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:40:44.629Z [FFC81B70 verbose 'Default' opID=HB-host-18@32435-636ad6de-30 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:40:44.646Z [36D40B70 verbose 'Default' opID=HB-host-18@32435-636ad6de-30 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:40:44.646Z [36D40B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32435-636ad6de-30 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:40:44.669Z [36D40B70 verbose 'Default' opID=HB-host-18@32435-636ad6de-30 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:40:48.029Z [38081B70 verbose 'Default' opID=SWI-19206338 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetChildConfiguration'

2016-02-12T09:40:48.035Z [380C2B70 verbose 'Default' opID=host-18:29:f22131e-0 user=vpxuser] AdapterServer: target='vim.ComputeResource:ha-compute-res', method='GetResourcePool'

2016-02-12T09:40:48.035Z [38081B70 verbose 'Default' opID=host-18:29:f22131e-1 user=vpxuser] AdapterServer: target='vim.ComputeResource:ha-compute-res', method='GetResourcePool'

2016-02-12T09:40:48.036Z [369A1B70 verbose 'Default' opID=host-18:29:f22131e-0 user=vpxuser] AdapterServer: target='vim.Folder:ha-folder-vm', method='registerVm'

2016-02-12T09:40:48.037Z [369A1B70 info 'Vimsvc.TaskManager' opID=host-18:29:f22131e-0 user=vpxuser] Task Created : haTask-ha-folder-vm-vim.Folder.registerVm-124255216

2016-02-12T09:40:48.037Z [36D40B70 info 'Solo.HaVMFolder' opID=host-18:29:f22131e-0 user=vpxuser] Register called: []/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:40:48.037Z [38740B70 verbose 'Default' opID=host-18:29:f22131e-1 user=vpxuser] AdapterServer: target='vim.Folder:ha-folder-vm', method='registerVm'

2016-02-12T09:40:48.038Z [38740B70 info 'Vimsvc.TaskManager' opID=host-18:29:f22131e-1 user=vpxuser] Task Created : haTask-ha-folder-vm-vim.Folder.registerVm-124255217

2016-02-12T09:40:48.038Z [38081B70 info 'Solo.HaVMFolder' opID=host-18:29:f22131e-1 user=vpxuser] Register called: []/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx

2016-02-12T09:40:48.039Z [36D40B70 verbose 'Vmsvc' opID=host-18:29:f22131e-0 user=vpxuser] Queued registering virtual machine /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

2016-02-12T09:40:48.039Z [38281B70 verbose 'Vmsvc'] Initializing registering virtual machine [71]: /vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx

-->

2016-02-12T09:40:48.040Z [38081B70 verbose 'Vmsvc' opID=host-18:29:f22131e-1 user=vpxuser] Queued registering virtual machine /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx

2016-02-12T09:40:48.040Z [377C2B70 verbose 'Vmsvc'] Initializing registering virtual machine [72]: /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx

-->

2016-02-12T09:40:55.100Z [38081B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:40:58.211Z [38081B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:41:00.228Z [38740B70 verbose 'Cimsvc'] Ticket issued for CIMOM version 1.0, user root

2016-02-12T09:41:01.648Z [38740B70 info 'Hostsvc' opID=hostd-687a] VsanSystemVmkProvider : GetRuntimeInfo: Start

2016-02-12T09:41:01.649Z [38740B70 info 'Hostsvc' opID=hostd-687a] VsanSystemVmkProvider : GetRuntimeInfo: Complete, runtime info: (vim.vsan.host.VsanRuntimeInfo) {

--> dynamicType = <unset>,

--> accessGenNo = 0,

--> }

2016-02-12T09:41:04.044Z [38281B70 info 'vm:VigorOfflineClient_Init: Failed to initialize VIGOR offline: Unable to load configuration file '/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx'] .

--> Cannot open file "/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx": Device or resource busy.

-->

2016-02-12T09:41:04.044Z [38281B70 verbose 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx'] LoadFromConfig translated error to vim.fault.FileNotFound

2016-02-12T09:41:04.044Z [38281B70 info 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx'] LoadFromConfig failed: vim.fault.FileNotFound

2016-02-12T09:41:04.044Z [38281B70 verbose 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx'] LoadFromConfig message: Unable to load configuration file '/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx'.

--> Cannot open file "/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx": Device or resource busy.

-->

2016-02-12T09:41:04.044Z [38281B70 info 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx'] Failed to load virtual machine: vim.fault.FileNotFound.

2016-02-12T09:41:04.044Z [38281B70 warning 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx'] Failed to load VM from foundry during register vim.fault.FileNotFound

2016-02-12T09:41:04.044Z [38281B70 info 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx'] Marking VirtualMachine invalid

2016-02-12T09:41:04.045Z [38281B70 info 'Vimsvc.ha-eventmgr'] Event 600 : Configuration file for Unknown 13 on LK2BL2SRVVMW.domain.local in ha-datacenter cannot be found

2016-02-12T09:41:04.045Z [38281B70 info 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx'] State Transition (VM_STATE_INITIALIZING -> VM_STATE_INVALID_LOAD)

2016-02-12T09:41:04.045Z [38281B70 verbose 'Hostsvc.HostConfigSyncManagerImpl'] Sync called

2016-02-12T09:41:04.046Z [38281B70 verbose 'Hostsvc.HostConfigSyncManagerImpl'] Scheduling '/sbin/backup.sh' in 5 seconds (backup counter = 0)

2016-02-12T09:41:04.046Z [38281B70 verbose 'Hostsvc.ResourcePool ha-root-pool'] Added child 71 to pool

2016-02-12T09:41:04.047Z [38281B70 info 'Vimsvc.ha-eventmgr'] Event 601 : Registered Unknown 13 on LK2BL2SRVVMW.domain.local in ha-datacenter

2016-02-12T09:41:04.047Z [38281B70 info 'Vimsvc.TaskManager'] Task Completed : haTask-ha-folder-vm-vim.Folder.registerVm-124255216 Status success

2016-02-12T09:41:04.058Z [38740B70 verbose 'Default' opID=SWI-5f084f30 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:41:04.060Z [369A1B70 verbose 'Default' opID=SWI-5f084f30 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:41:04.065Z [FFC81B70 verbose 'Default' opID=host-18:29:f22131e-0 user=vpxuser] AdapterServer: target='vim.VirtualMachine:71', method='GetRuntime'

2016-02-12T09:41:04.080Z [377C2B70 info 'vm:Vix: [35322 foundryPollWorker.c:624]: FoundryPollWorker_AssignVMToWorker: Assign VM:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] to worker 374D2B70.

2016-02-12T09:41:04.094Z [374D2B70 info 'vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Reloading config state.

2016-02-12T09:41:04.122Z [374D2B70 info 'Libs'] VMHS: Transitioned vmx/execState/val to poweredOff

2016-02-12T09:41:04.172Z [377C2B70 verbose 'Default'] SetVmHandle /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx 10485916

2016-02-12T09:41:04.172Z [377C2B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Upgrade is required for virtual machine, version: 7

2016-02-12T09:41:04.178Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Time to gather Snapshot information ( read from disk, build tree): 5 msecs. needConsolidate is false.

2016-02-12T09:41:04.178Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Time to gather snapshot file layout: 0 (msecs)

2016-02-12T09:41:04.178Z [377C2B70 warning 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] CannotRetrieveCorefiles: VM is in an invalid state

2016-02-12T09:41:04.199Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Time to gather config: 21 (msecs)

2016-02-12T09:41:04.200Z [FFC81B70 verbose 'Hbrsvc'] Replicator: ReconfigListener triggered for config VM 72

2016-02-12T09:41:04.200Z [FFC81B70 info 'Hbrsvc'] Replicator: ReconfigListener failed to look up VM (id=72)

2016-02-12T09:41:04.219Z [377C2B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Error retrieving MemoryClientStats for file layout : vmodl.fault.ManagedObjectNotFound

2016-02-12T09:41:04.229Z [377C2B70 verbose 'Hbrsvc'] Replicator: VmFileProviderCallback VM (id=72)

2016-02-12T09:41:04.229Z [377C2B70 info 'Hbrsvc'] Replicator: VmFileProviderCallback failed to look up VM (id=72)

2016-02-12T09:41:04.230Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Fault Tolerance state callback received

2016-02-12T09:41:04.230Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Record/replay state callback received

2016-02-12T09:41:04.235Z [377C2B70 info 'Libs'] Failed to find manifest content in extended config xml.

2016-02-12T09:41:04.235Z [377C2B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Initial tools version: 8:guestToolsUnmanaged

2016-02-12T09:41:04.236Z [377C2B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] State Transition (VM_STATE_INITIALIZING -> VM_STATE_OFF)

2016-02-12T09:41:04.237Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Predicted VM overhead: 182910976 bytes

2016-02-12T09:41:04.237Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Max connection count changed from 0 to 40

2016-02-12T09:41:04.237Z [377C2B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Initialized virtual machine.

2016-02-12T09:41:04.250Z [377C2B70 verbose 'Hostsvc.HostConfigSyncManagerImpl'] Sync called

2016-02-12T09:41:04.251Z [377C2B70 verbose 'Hostsvc.ResourcePool ha-root-pool'] Added child 72 to pool

2016-02-12T09:41:04.251Z [377C2B70 verbose 'Default'] InitializeFileObjectIfAccessible: Datastore Accessibility could not be inferred from relative filepath: Liquidware_HUB-438dcdcf.hlog

2016-02-12T09:41:04.262Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Time to gather config: 8 (msecs)

2016-02-12T09:41:04.263Z [377C2B70 info 'Hostsvc'] Lookupvm: World ID not set for VM 72

2016-02-12T09:41:04.263Z [38740B70 verbose 'Hbrsvc'] Replicator: ReconfigListener triggered for config VM 72

2016-02-12T09:41:04.265Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] hostlog state success -> success

2016-02-12T09:41:04.265Z [377C2B70 info 'Vimsvc.ha-eventmgr'] Event 602 : Registered Liquidware_HUB on LK2BL2SRVVMW.domain.local in ha-datacenter

2016-02-12T09:41:04.266Z [377C2B70 info 'Vimsvc.TaskManager'] Task Completed : haTask-ha-folder-vm-vim.Folder.registerVm-124255217 Status success

2016-02-12T09:41:04.266Z [38740B70 verbose 'Hbrsvc'] Replicator: VmReconfig ignoring VM 72 not configured for replication

2016-02-12T09:41:04.291Z [FFC81B70 verbose 'Default' opID=SWI-573b3a7a user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:41:04.293Z [38040B70 verbose 'Default' opID=host-18:29:f22131e-1 user=vpxuser] AdapterServer: target='vim.VirtualMachine:72', method='GetRuntime'

2016-02-12T09:41:04.293Z [380C2B70 verbose 'Default' opID=SWI-573b3a7a user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:41:04.295Z [38040B70 verbose 'Default' opID=host-18:29:f22131e-1 user=vpxuser] AdapterServer: target='vim.VirtualMachine:72', method='reconfigure'

2016-02-12T09:41:04.296Z [38040B70 info 'Vimsvc.TaskManager' opID=host-18:29:f22131e-1 user=vpxuser] Task Created : haTask-72-vim.VirtualMachine.reconfigure-124255222

2016-02-12T09:41:04.296Z [FFC81B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] State Transition (VM_STATE_OFF -> VM_STATE_RECONFIGURING)

2016-02-12T09:41:04.296Z [FFC81B70 verbose 'Hostsvc.HaHost' opID=host-18:29:f22131e-1 user=vpxuser] ModeMgr::Begin: op = normal, current = normal, count = 11

2016-02-12T09:41:04.297Z [FFC81B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Invoking interceptor:1-hbr

2016-02-12T09:41:04.297Z [FFC81B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Done invoking interceptor:1-hbr

2016-02-12T09:41:04.297Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Enter VmConfig::Reconfigure

2016-02-12T09:41:04.298Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Current VM Tracking state: disabled

2016-02-12T09:41:04.300Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] VmConfig::Reconfigure : creating taskProgress

2016-02-12T09:41:04.300Z [38740B70 info 'Libs' opID=host-18:29:f22131e-1 user=vpxuser] Vix: [35329 foundryHandles.c:1526]: Error VIX_E_FAIL in Vix_AddRefHandleImpl(): Add ref a destroyed/recycled handle=-1, file=(null), line=0.

2016-02-12T09:41:04.300Z [38740B70 info 'Libs' opID=host-18:29:f22131e-1 user=vpxuser] Vix: [35329 foundryHandles.c:947]: Error VIX_E_FAIL in Vix_ReleaseHandleImpl(): Releasing a recycled or destroyed handle=-1, file=(null), line=0.

2016-02-12T09:41:04.300Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Setting VM's tracking state to disabled.

2016-02-12T09:41:04.332Z [38740B70 info 'vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Reloading config state.

2016-02-12T09:41:04.364Z [38740B70 info 'Libs' opID=host-18:29:f22131e-1 user=vpxuser] VMHS: Transitioned vmx/execState/val to poweredOff

2016-02-12T09:41:04.394Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Commit Vigor batch operation successful

2016-02-12T09:41:04.394Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Signalling Commit Vigor batch operation

2016-02-12T09:41:04.394Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Waiting on Commit Vigor batch operation

2016-02-12T09:41:04.394Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Completed Commit Vigor batch operation

2016-02-12T09:41:04.409Z [38040B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Upgrade is required for virtual machine, version: 7

2016-02-12T09:41:04.410Z [374D2B70 info 'vm:Vix: [34961 foundryVMPowerOps.c:980]: FoundryVMPowerStateChangeCallback: /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] vmx/execState/val = poweredOff.

2016-02-12T09:41:04.410Z [38740B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] State Transition (VM_STATE_RECONFIGURING -> VM_STATE_OFF)

2016-02-12T09:41:04.410Z [38740B70 verbose 'Hostsvc.HaHost' opID=host-18:29:f22131e-1 user=vpxuser] ModeMgr::End: op = normal, current = normal, count = 12

2016-02-12T09:41:04.410Z [38740B70 info 'Vimsvc.ha-eventmgr' opID=host-18:29:f22131e-1 user=vpxuser] Event 603 : Reconfigured Liquidware_HUB on LK2BL2SRVVMW.domain.local in ha-datacenter

2016-02-12T09:41:04.420Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Time to gather config: 9 (msecs)

2016-02-12T09:41:04.421Z [FFC81B70 verbose 'Hbrsvc'] Replicator: ReconfigListener triggered for config VM 72

2016-02-12T09:41:04.421Z [38740B70 info 'Hostsvc' opID=host-18:29:f22131e-1 user=vpxuser] Lookupvm: World ID not set for VM 72

2016-02-12T09:41:04.422Z [38740B70 info 'Vimsvc.TaskManager' opID=host-18:29:f22131e-1 user=vpxuser] Task Completed : haTask-72-vim.VirtualMachine.reconfigure-124255222 Status success

2016-02-12T09:41:04.422Z [38740B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Reconfigure worker thread completed succesfully

2016-02-12T09:41:04.423Z [FFC81B70 verbose 'Hbrsvc'] Replicator: VmReconfig ignoring VM 72 not configured for replication

2016-02-12T09:41:04.430Z [FFC81B70 verbose 'Default' opID=host-18:29:f22131e-1 user=vpxuser] AdapterServer: target='vim.VirtualMachine:72', method='powerOn'

2016-02-12T09:41:04.430Z [FFC81B70 info 'Vimsvc.TaskManager' opID=host-18:29:f22131e-1 user=vpxuser] Task Created : haTask-72-vim.VirtualMachine.powerOn-124255224

2016-02-12T09:41:04.430Z [38081B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Power on request received

2016-02-12T09:41:04.430Z [38081B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Reconfigure ethernet backing if required

2016-02-12T09:41:04.431Z [38081B70 info 'Vimsvc.ha-eventmgr' opID=host-18:29:f22131e-1 user=vpxuser] Event 604 : Liquidware_HUB on host LK2BL2SRVVMW.domain.local in ha-datacenter is starting

2016-02-12T09:41:04.431Z [38081B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] State Transition (VM_STATE_OFF -> VM_STATE_POWERING_ON)

2016-02-12T09:41:04.431Z [38081B70 verbose 'Hostsvc.HaHost' opID=host-18:29:f22131e-1 user=vpxuser] ModeMgr::Begin: op = normal, current = normal, count = 11

2016-02-12T09:41:04.431Z [38081B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Invoking interceptor:3-ha-vmsvc

SIOC: SIOC is notified not to start injector

2016-02-12T09:41:04.432Z [38081B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Done invoking interceptor:3-ha-vmsvc

2016-02-12T09:41:04.433Z [38081B70 verbose 'Hostsvc' opID=host-18:29:f22131e-1 user=vpxuser] Security domain hostd72 not found

2016-02-12T09:41:04.438Z [38081B70 info 'Libs' opID=host-18:29:f22131e-1 user=vpxuser] Starting vmx as /bin/vmx

2016-02-12T09:41:04.438Z [38081B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] Vigor client state callback 1 success

2016-02-12T09:41:04.439Z [38081B70 info 'vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] VMHS: VM /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx in directory /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB

2016-02-12T09:41:04.439Z [38081B70 info 'vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] VMHS: Exec()'ing /bin/vmx.

2016-02-12T09:41:04.448Z [38081B70 info 'Libs' opID=host-18:29:f22131e-1 user=vpxuser] VMHS: VMKernel_ForkExec(/bin/vmx, detached=1): status=0 pid=11753545

2016-02-12T09:41:04.448Z [38081B70 info 'Libs' opID=host-18:29:f22131e-1 user=vpxuser] VmdbAddConnection: cnxPath=/db/connection/#5c/, cnxIx=32

2016-02-12T09:41:04.448Z [38081B70 info 'Libs' opID=host-18:29:f22131e-1 user=vpxuser] Vmdb_AddConnectionAsync_Secure: registered VERSION completion callback, cnx = /db/connection/#5c/

2016-02-12T09:41:04.448Z [38081B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=host-18:29:f22131e-1 user=vpxuser] PowerOn request queued

2016-02-12T09:41:04.548Z [374D2B70 info 'vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Mounting VM begun.

2016-02-12T09:41:04.549Z [374D2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Vigor client state callback 2 success

2016-02-12T09:41:04.549Z [374D2B70 info 'vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Mounting virtual machine paths on connection: /db/connection/#5c/.

2016-02-12T09:41:04.550Z [374D2B70 info 'Libs'] VmdbCnxControlCb: registered SUBSCRIBE completion callback, cnx = /db/connection/#5c/

2016-02-12T09:41:04.587Z [38081B70 verbose 'Default' opID=HB-host-18@32455-44303b3d-3a user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:41:04.599Z [38081B70 verbose 'Locale' opID=HB-host-18@32455-44303b3d-3a user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:41:04.626Z [FFC81B70 verbose 'Default' opID=HB-host-18@32455-44303b3d-3a user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:41:04.629Z [38040B70 verbose 'Default' opID=HB-host-18@32455-44303b3d-3a user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:41:04.647Z [FFC81B70 verbose 'Default' opID=HB-host-18@32455-44303b3d-3a user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:41:04.647Z [FFC81B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32455-44303b3d-3a user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:41:04.673Z [FFC81B70 verbose 'Default' opID=HB-host-18@32455-44303b3d-3a user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:41:04.673Z [FFC81B70 verbose 'Vmsvc' opID=HB-host-18@32455-44303b3d-3a user=vpxuser] Vmsvc: Filtering Vms: ignored vm not ready to query power state vim.VirtualMachine:71

2016-02-12T09:41:04.697Z [380C2B70 verbose 'Default' opID=HB-host-18@32455-44303b3d-3a user=vpxuser] AdapterServer: target='vim.VirtualMachine:71', method='GetSummary'

2016-02-12T09:41:04.739Z [380C2B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Upgrade is required for virtual machine, version: 7

2016-02-12T09:41:04.740Z [374D2B70 info 'Libs'] VigorClientStartMountCb Succeeeded

2016-02-12T09:41:04.740Z [374D2B70 info 'vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Mounting VM complete.

2016-02-12T09:41:04.741Z [374D2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Vigor client state callback 3 success

2016-02-12T09:41:04.893Z [380C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Handling message _vmx1: This virtual machine might have been moved or copied.

--> In order to configure certain management and networking features, VMware ESX needs to know if this virtual machine was moved or copied.

-->

--> If you don't know, answer "I Copied It".

-->

2016-02-12T09:41:04.894Z [380C2B70 info 'Vimsvc.ha-eventmgr'] Event 605 : Message on Liquidware_HUB on LK2BL2SRVVMW.domain.local in ha-datacenter: This virtual machine might have been moved or copied.

--> In order to configure certain management and networking features, VMware ESX needs to know if this virtual machine was moved or copied.

-->

--> If you don't know, answer "I Co_pied It".

-->

2016-02-12T09:41:04.894Z [380C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Setting current question to '_vmx1'

2016-02-12T09:41:04.898Z [369A1B70 verbose 'Default' opID=SWI-1cc0a5c6 user=vpxuser] AdapterServer: target='vim.VirtualMachine:72', method='answer'

2016-02-12T09:41:04.898Z [369A1B70 info 'Vimsvc.TaskManager' opID=SWI-1cc0a5c6 user=vpxuser] Task Created : haTask-72-vim.VirtualMachine.answer-124255242

2016-02-12T09:41:04.898Z [369A1B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx' opID=SWI-1cc0a5c6 user=vpxuser] Received answer: _vmx1, 1

2016-02-12T09:41:04.900Z [38281B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Answered user-visible question _vmx1

2016-02-12T09:41:04.900Z [38281B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Clearing VM question

2016-02-12T09:41:04.900Z [38281B70 info 'Vimsvc.TaskManager'] Task Completed : haTask-72-vim.VirtualMachine.answer-124255242 Status success

2016-02-12T09:41:05.011Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] MKS ready for connections: true

2016-02-12T09:41:05.134Z [374D2B70 info 'vm:Vix: [34961 foundryVMPowerOps.c:980]: FoundryVMPowerStateChangeCallback: /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] vmx/execState/val = poweredOn.

2016-02-12T09:41:05.134Z [374D2B70 info 'vm:Vix: [34961 foundryVMPowerOps.c:1176]: FoundryVMPowerStateChangeCallback: /vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] opening automation socket.

2016-02-12T09:41:05.134Z [374D2B70 info 'vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Disconnect check in progress.

2016-02-12T09:41:05.134Z [380C2B70 info 'Hostsvc'] Decremented SIOC Injector Flag2

2016-02-12T09:41:05.134Z [FFC81B70 info 'vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Connected to testAutomation-fd, remote end sent pid: 11753545

2016-02-12T09:41:05.154Z [38040B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] registering Vigor callbacks

2016-02-12T09:41:05.154Z [38040B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] sleepState value is 0

2016-02-12T09:41:05.155Z [38040B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] ToolsNamespaceGenerationInfo callback received. VM state = VM_STATE_POWERING_ON

2016-02-12T09:41:05.155Z [38040B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] guest operations are not ready

2016-02-12T09:41:05.155Z [38040B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Ignored toolsManifestInfo update of size 0

2016-02-12T09:41:05.156Z [380C2B70 info 'Vimsvc.ha-eventmgr'] Event 606 : Liquidware_HUB on LK2BL2SRVVMW.domain.local in ha-datacenter is powered on

2016-02-12T09:41:05.156Z [380C2B70 info 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] State Transition (VM_STATE_POWERING_ON -> VM_STATE_ON)

2016-02-12T09:41:05.157Z [36D40B70 verbose 'Hostsvc'] Received state change for VM '72'

2016-02-12T09:41:05.157Z [380C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Actual VM overhead: 55926784 bytes

2016-02-12T09:41:05.157Z [36D40B70 info 'Guestsvc.GuestFileTransferImpl'] Entered VmPowerStateListener

2016-02-12T09:41:05.157Z [36D40B70 info 'Guestsvc.GuestFileTransferImpl'] VmPowerStateListener succeeded

2016-02-12T09:41:05.157Z [36D40B70 info 'Hbrsvc'] Replicator: powerstate change VM: 72 Old: 0 New: 1

2016-02-12T09:41:05.168Z [380C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Time to gather config: 10 (msecs)

2016-02-12T09:41:05.168Z [38740B70 verbose 'Hbrsvc'] Replicator: ReconfigListener triggered for config VM 72

2016-02-12T09:41:05.170Z [380C2B70 info 'Vimsvc.TaskManager'] Task Completed : haTask-72-vim.VirtualMachine.powerOn-124255224 Status success

2016-02-12T09:41:05.170Z [36D40B70 verbose 'Hbrsvc'] Replicator: Remove group no matching entry for VM (id=72)

2016-02-12T09:41:05.171Z [38740B70 verbose 'Hbrsvc'] Replicator: VmReconfig ignoring VM 72 not configured for replication

2016-02-12T09:41:05.183Z [38281B70 verbose 'Vmsvc' opID=hostd-aed5] Vmsvc: Filtering Vms: ignored vm not ready to query power state vim.VirtualMachine:71

2016-02-12T09:41:05.211Z [38040B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Time to gather config: 18 (msecs)

2016-02-12T09:41:05.211Z [FFC81B70 verbose 'Hbrsvc'] Replicator: ReconfigListener triggered for config VM 72

2016-02-12T09:41:05.213Z [FFC81B70 verbose 'Hbrsvc'] Replicator: VmReconfig ignoring VM 72 not configured for replication

2016-02-12T09:41:07.618Z [380C2B70 verbose 'Default' opID=36b5a422-2c user=vpxuser:domain\vmwarevc] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='summarizeStats'

2016-02-12T09:41:09.047Z [380C2B70 verbose 'Hostsvc.HostConfigSyncManagerImpl'] Invoking '/sbin/backup.sh': pending sync requests: 2

2016-02-12T09:41:09.048Z [380C2B70 info 'SysCommandPosix'] ForkExec(/sbin/backup.sh) 11753569

2016-02-12T09:41:09.300Z [38281B70 info 'Libs'] Vix: [35327 foundryHandles.c:947]: Error VIX_E_FAIL in Vix_ReleaseHandleImpl(): Releasing a recycled or destroyed handle=-1, file=(null), line=0.

2016-02-12T09:41:10.246Z [380C2B70 info 'Hostsvc.HostConfigSyncManagerImpl'] /sbin/backup.sh succeeded

2016-02-12T09:41:14.568Z [38281B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Time to gather config: 22 (msecs)

2016-02-12T09:41:14.569Z [377C2B70 verbose 'Hbrsvc'] Replicator: ReconfigListener triggered for config VM 72

2016-02-12T09:41:14.571Z [377C2B70 verbose 'Hbrsvc'] Replicator: VmReconfig ignoring VM 72 not configured for replication

2016-02-12T09:41:14.596Z [377C2B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.option.OptionManager:ha-adv-options', method='queryView'

2016-02-12T09:41:14.599Z [377C2B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:41:14.617Z [377C2B70 verbose 'Default' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] AdapterServer: target='vim.VirtualMachine:71', method='unregister'

2016-02-12T09:41:14.617Z [377C2B70 info 'Vimsvc.TaskManager' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Task Created : haTask-71-vim.VirtualMachine.unregister-124255253

2016-02-12T09:41:14.617Z [38281B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:41:14.618Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Unregister called on virtual machine

2016-02-12T09:41:14.618Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] OpaqueNetworkVmOpContext::SetVmxPath: config is null, skipping vmx path

2016-02-12T09:41:14.618Z [377C2B70 info 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] State Transition (VM_STATE_INVALID_LOAD -> VM_STATE_UNREGISTERING)

2016-02-12T09:41:14.618Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] RemoveFromAutoStart

2016-02-12T09:41:14.618Z [377C2B70 verbose 'Hostsvc.AutoStartManager' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Request spec is (vim.host.AutoStartManager.Config) {

--> dynamicType = <unset>,

--> defaults = (vim.host.AutoStartManager.SystemDefaults) null,

--> powerInfo = (vim.host.AutoStartManager.AutoPowerInfo) [

--> (vim.host.AutoStartManager.AutoPowerInfo) {

--> dynamicType = <unset>,

--> key = 'vim.VirtualMachine:71',

--> startOrder = -1,

--> startDelay = -1,

--> waitForHeartbeat = "no",

--> startAction = "none",

--> stopDelay = -1,

--> stopAction = "none",

--> }

--> ],

--> }

2016-02-12T09:41:14.618Z [377C2B70 verbose 'Hostsvc.AutoStartManager' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Updated AutoStart sequence.

2016-02-12T09:41:14.619Z [377C2B70 verbose 'Hostsvc.AutoStartManager' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Autostart info after reconfiguration: (vim.host.AutoStartManager.Config) {

--> dynamicType = <unset>,

--> defaults = (vim.host.AutoStartManager.SystemDefaults) {

--> dynamicType = <unset>,

--> enabled = false,

--> startDelay = 120,

--> stopDelay = 120,

--> waitForHeartbeat = false,

--> stopAction = "PowerOff",

--> },

--> }

2016-02-12T09:41:14.619Z [377C2B70 verbose 'Hostsvc' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] RemoveEntry '71'

2016-02-12T09:41:14.620Z [377C2B70 verbose 'Hostsvc' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] RemoveEntry succeeded

2016-02-12T09:41:14.620Z [377C2B70 verbose 'Hostsvc.ResourcePool ha-root-pool' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Removed child 71 from pool

2016-02-12T09:41:14.620Z [377C2B70 verbose 'Hostsvc' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Security domain hostd71 not found

2016-02-12T09:41:14.620Z [FFC81B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:41:14.621Z [377C2B70 verbose 'Hostsvc.HostConfigSyncManagerImpl' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Sync called

2016-02-12T09:41:14.621Z [377C2B70 verbose 'Hostsvc.HostConfigSyncManagerImpl' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Scheduling '/sbin/backup.sh' in 9 seconds (backup counter = 0)

2016-02-12T09:41:14.621Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Closed VM handle.

2016-02-12T09:41:14.621Z [38740B70 info 'Guestsvc.GuestFileTransferImpl'] VmOperationListener: unregister notification received for VM: 71

2016-02-12T09:41:14.621Z [38740B70 info 'Guestsvc.GuestFileTransferImpl'] VmOperationListener succeeded

2016-02-12T09:41:14.621Z [38740B70 info 'Hbrsvc'] Replicator: UnregisterListener triggered for config VM 71

2016-02-12T09:41:14.621Z [377C2B70 info 'Vimsvc.ha-eventmgr' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Event 607 : Removed Unknown 13 on LK2BL2SRVVMW.domain.local from ha-datacenter

2016-02-12T09:41:14.621Z [377C2B70 info 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] State Transition (VM_STATE_UNREGISTERING -> VM_STATE_GONE)

2016-02-12T09:41:14.621Z [377C2B70 verbose 'Vmsvc' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Released Vm Id: 71.

2016-02-12T09:41:14.621Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Unregister completed

2016-02-12T09:41:14.621Z [377C2B70 info 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Virtual machine object cleanup

2016-02-12T09:41:14.621Z [377C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/566e764c-5c919368-e6de-0017a4771c1c/Netscaler_2/Netscaler_2.vmx' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Closed VM handle.

2016-02-12T09:41:14.622Z [377C2B70 info 'Vimsvc.TaskManager' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Task Completed : haTask-71-vim.VirtualMachine.unregister-124255253 Status success

2016-02-12T09:41:14.623Z [36D40B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:41:14.624Z [FFC81B70 verbose 'Default' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:41:14.625Z [377C2B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetChildConfiguration'

2016-02-12T09:41:14.631Z [38040B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.ResourcePool:pool0', method='GetName'

2016-02-12T09:41:14.635Z [38040B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.ResourcePool:pool0', method='GetChildConfiguration'

2016-02-12T09:41:14.642Z [FFC81B70 verbose 'Locale' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:41:14.642Z [38040B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.ResourcePool:pool1', method='GetName'

2016-02-12T09:41:14.644Z [377C2B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.ResourcePool:pool1', method='GetChildConfiguration'

2016-02-12T09:41:14.648Z [38040B70 verbose 'Default' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] AdapterServer: target='vim.ResourcePool:pool1', method='destroy'

2016-02-12T09:41:14.649Z [38040B70 info 'Vimsvc.TaskManager' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] Task Created : haTask-pool1-vim.ManagedEntity.destroy-124255256

2016-02-12T09:41:14.649Z [36D40B70 verbose 'Hostsvc' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] RemoveEntry 'pool1'

2016-02-12T09:41:14.652Z [38040B70 verbose 'Default' opID=SWI-d0b563c4 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:41:14.652Z [36D40B70 verbose 'Hostsvc' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] RemoveEntry succeeded

2016-02-12T09:41:14.652Z [36D40B70 verbose 'Hostsvc.ResourcePool ha-root-pool' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] Removed child pool1 from pool

2016-02-12T09:41:14.653Z [36D40B70 info 'Vimsvc.TaskManager' opID=HB-SpecSync-host-18@942-4ef3411e-6 user=vpxuser] Task Completed : haTask-pool1-vim.ManagedEntity.destroy-124255256 Status success

2016-02-12T09:41:14.656Z [FF9C2B70 verbose 'Default' opID=SWI-d0b563c4 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:41:14.660Z [FF9C2B70 verbose 'Default' opID=SWI-3d95611d user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:41:14.663Z [38281B70 verbose 'Default' opID=SWI-3d95611d user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:41:14.671Z [FFC81B70 verbose 'Default' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:41:14.673Z [FFC81B70 verbose 'Default' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:41:14.690Z [FF9C2B70 verbose 'Default' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:41:14.690Z [FF9C2B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:41:14.714Z [FF9C2B70 verbose 'Default' opID=HB-host-18@32470-522fa9e9-92 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:41:22.121Z [FFC81B70 verbose 'Hostsvc.ResourcePool ha-root-pool'] Root pool capacity changed from 27496MHz/92088MB to 27496MHz/92084MB

2016-02-12T09:41:22.125Z [FFC81B70 verbose 'Default' opID=SWI-ad578cca user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:41:22.127Z [FFC81B70 verbose 'Default' opID=SWI-ad578cca user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:41:24.047Z [38281B70 verbose 'Hostsvc.HostConfigSyncManagerImpl'] Invoking '/sbin/backup.sh': pending sync requests: 1

2016-02-12T09:41:24.049Z [38281B70 info 'SysCommandPosix'] ForkExec(/sbin/backup.sh) 11753755

2016-02-12T09:41:24.591Z [38040B70 verbose 'Default' opID=HB-host-18@32474-ddeac7e-85 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:41:24.605Z [38040B70 verbose 'Locale' opID=HB-host-18@32474-ddeac7e-85 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:41:24.633Z [38040B70 verbose 'Default' opID=HB-host-18@32474-ddeac7e-85 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:41:24.636Z [38040B70 verbose 'Default' opID=HB-host-18@32474-ddeac7e-85 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:41:24.653Z [38040B70 verbose 'Default' opID=HB-host-18@32474-ddeac7e-85 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:41:24.654Z [38040B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32474-ddeac7e-85 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:41:24.680Z [38040B70 verbose 'Default' opID=HB-host-18@32474-ddeac7e-85 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:41:25.103Z [38040B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:41:25.359Z [38281B70 info 'Hostsvc.HostConfigSyncManagerImpl'] /sbin/backup.sh succeeded

2016-02-12T09:41:28.214Z [FFC81B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:41:31.163Z [38040B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Actual VM overhead: 49864704 bytes

2016-02-12T09:41:31.164Z [38040B70 verbose 'Vmsvc'] RefreshVms updated overhead for 1 VM

2016-02-12T09:41:32.789Z [FF91E950 verbose 'Hostsvc.DvsTracker' opID=hostd-550a user=vpxuser] FetchSwitches: added 0 items

2016-02-12T09:41:32.789Z [FF91E950 verbose 'Hostsvc.DvsTracker' opID=hostd-550a user=vpxuser] FetchDVPortgroups: added 0 items

2016-02-12T09:41:32.789Z [FF91E950 info 'Hostsvc.DvsTracker' opID=hostd-550a user=vpxuser] FetchUplinkDVPortgroups: added 0 items

2016-02-12T09:41:34.591Z [369A1B70 verbose 'Default' opID=HB-host-18@32476-5151a754-d6 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:41:34.604Z [369A1B70 verbose 'Locale' opID=HB-host-18@32476-5151a754-d6 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:41:34.632Z [369A1B70 verbose 'Default' opID=HB-host-18@32476-5151a754-d6 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:41:34.634Z [369A1B70 verbose 'Default' opID=HB-host-18@32476-5151a754-d6 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:41:34.650Z [369A1B70 verbose 'Default' opID=HB-host-18@32476-5151a754-d6 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:41:34.651Z [369A1B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32476-5151a754-d6 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:41:34.674Z [369A1B70 verbose 'Default' opID=HB-host-18@32476-5151a754-d6 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:41:44.610Z [FFC81B70 verbose 'Default' opID=HB-host-18@32477-4a2de78d-36 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:41:44.624Z [FFC81B70 verbose 'Locale' opID=HB-host-18@32477-4a2de78d-36 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:41:44.650Z [36D40B70 verbose 'Default' opID=HB-host-18@32477-4a2de78d-36 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:41:44.652Z [36D40B70 verbose 'Default' opID=HB-host-18@32477-4a2de78d-36 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:41:44.669Z [38040B70 verbose 'Default' opID=HB-host-18@32477-4a2de78d-36 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:41:44.670Z [38040B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32477-4a2de78d-36 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:41:44.693Z [38040B70 verbose 'Default' opID=HB-host-18@32477-4a2de78d-36 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:41:47.249Z [369A1B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] guest operations are ready

2016-02-12T09:41:47.249Z [369A1B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] CheckHeartbeatMonitor: Reviving the heartbeat monitor in 30 seconds

2016-02-12T09:41:47.264Z [FFC81B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Tools are auto-upgrade capable

2016-02-12T09:41:47.264Z [FFC81B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Ignored toolsManifestInfo update of size 0

2016-02-12T09:41:47.265Z [FF9C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Tools are operations ready

2016-02-12T09:41:54.623Z [369A1B70 verbose 'Default' opID=HB-host-18@32480-5e380315-6 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:41:54.637Z [369A1B70 verbose 'Locale' opID=HB-host-18@32480-5e380315-6 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:41:54.675Z [369A1B70 verbose 'Default' opID=HB-host-18@32480-5e380315-6 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:41:54.677Z [369A1B70 verbose 'Default' opID=HB-host-18@32480-5e380315-6 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:41:54.693Z [369A1B70 verbose 'Default' opID=HB-host-18@32480-5e380315-6 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:41:54.693Z [369A1B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32480-5e380315-6 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:41:54.716Z [369A1B70 verbose 'Default' opID=HB-host-18@32480-5e380315-6 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:41:55.106Z [369A1B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:41:58.216Z [FF91E950 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:42:02.765Z [FFC81B70 verbose 'Hostsvc.DvsManager'] PersistAllDvsInfo called

2016-02-12T09:42:04.615Z [369A1B70 verbose 'Default' opID=HB-host-18@32481-36fd9d19-4e user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:42:04.629Z [369A1B70 verbose 'Locale' opID=HB-host-18@32481-36fd9d19-4e user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:42:04.657Z [38281B70 verbose 'Default' opID=HB-host-18@32481-36fd9d19-4e user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:42:04.659Z [38281B70 verbose 'Default' opID=HB-host-18@32481-36fd9d19-4e user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:42:04.676Z [38281B70 verbose 'Default' opID=HB-host-18@32481-36fd9d19-4e user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:42:04.676Z [38281B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32481-36fd9d19-4e user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:42:04.699Z [38281B70 verbose 'Default' opID=HB-host-18@32481-36fd9d19-4e user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:42:14.619Z [36D40B70 verbose 'Default' opID=HB-host-18@32483-5d225442-8 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:42:14.632Z [38281B70 verbose 'Locale' opID=HB-host-18@32483-5d225442-8 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:42:14.660Z [38281B70 verbose 'Default' opID=HB-host-18@32483-5d225442-8 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:42:14.662Z [38281B70 verbose 'Default' opID=HB-host-18@32483-5d225442-8 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:42:14.680Z [38281B70 verbose 'Default' opID=HB-host-18@32483-5d225442-8 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:42:14.680Z [38281B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32483-5d225442-8 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:42:14.704Z [38281B70 verbose 'Default' opID=HB-host-18@32483-5d225442-8 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:42:17.251Z [380C2B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Updating current heartbeatStatus: yellow

2016-02-12T09:42:22.124Z [380C2B70 verbose 'Hostsvc.ResourcePool ha-root-pool'] Root pool capacity changed from 27496MHz/92084MB to 27496MHz/92085MB

2016-02-12T09:42:22.128Z [377C2B70 verbose 'Default' opID=SWI-dcc26b9c user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:42:22.129Z [377C2B70 verbose 'Default' opID=SWI-dcc26b9c user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:42:24.602Z [FF9C2B70 verbose 'Default' opID=HB-host-18@32486-58d26e95-97 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:42:24.615Z [FF9C2B70 verbose 'Locale' opID=HB-host-18@32486-58d26e95-97 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:42:24.655Z [FF9C2B70 verbose 'Default' opID=HB-host-18@32486-58d26e95-97 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:42:24.659Z [FFC81B70 verbose 'Default' opID=HB-host-18@32486-58d26e95-97 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:42:24.677Z [FF9C2B70 verbose 'Default' opID=HB-SpecSync-host-18@948-1764d2cf-d3 user=vpxuser] AdapterServer: target='vim.option.OptionManager:ha-adv-options', method='queryView'

2016-02-12T09:42:24.685Z [380C2B70 verbose 'Default' opID=HB-SpecSync-host-18@948-1764d2cf-d3 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:42:24.685Z [FF91E950 verbose 'Default' opID=HB-host-18@32486-58d26e95-97 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:42:24.686Z [369A1B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32486-58d26e95-97 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:42:24.704Z [377C2B70 verbose 'Default' opID=HB-SpecSync-host-18@948-1764d2cf-d3 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:42:24.706Z [377C2B70 verbose 'Default' opID=HB-SpecSync-host-18@948-1764d2cf-d3 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:42:24.708Z [377C2B70 verbose 'Default' opID=HB-SpecSync-host-18@948-1764d2cf-d3 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:42:24.710Z [FF9C2B70 verbose 'Default' opID=HB-SpecSync-host-18@948-1764d2cf-d3 user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetChildConfiguration'

2016-02-12T09:42:24.712Z [FF9C2B70 verbose 'Default' opID=HB-SpecSync-host-18@948-1764d2cf-d3 user=vpxuser] AdapterServer: target='vim.ResourcePool:pool0', method='GetName'

2016-02-12T09:42:24.712Z [369A1B70 verbose 'Default' opID=HB-host-18@32486-58d26e95-97 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:42:24.713Z [FF9C2B70 verbose 'Default' opID=HB-SpecSync-host-18@948-1764d2cf-d3 user=vpxuser] AdapterServer: target='vim.ResourcePool:pool0', method='GetChildConfiguration'

2016-02-12T09:42:25.109Z [FFC81B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:42:28.218Z [369A1B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:42:31.646Z [FFC81B70 verbose 'Cimsvc'] Ticket issued for CIMOM version 1.0, user root

2016-02-12T09:42:33.080Z [FFC81B70 info 'Hostsvc' opID=hostd-b3ab] VsanSystemVmkProvider : GetRuntimeInfo: Start

2016-02-12T09:42:33.080Z [FFC81B70 info 'Hostsvc' opID=hostd-b3ab] VsanSystemVmkProvider : GetRuntimeInfo: Complete, runtime info: (vim.vsan.host.VsanRuntimeInfo) {

--> dynamicType = <unset>,

--> accessGenNo = 0,

--> }

2016-02-12T09:42:34.602Z [377C2B70 verbose 'Default' opID=HB-host-18@32487-4fd2775a-fe user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:42:34.616Z [377C2B70 verbose 'Locale' opID=HB-host-18@32487-4fd2775a-fe user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:42:34.644Z [377C2B70 verbose 'Default' opID=HB-host-18@32487-4fd2775a-fe user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:42:34.646Z [377C2B70 verbose 'Default' opID=HB-host-18@32487-4fd2775a-fe user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:42:34.663Z [377C2B70 verbose 'Default' opID=HB-host-18@32487-4fd2775a-fe user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:42:34.663Z [377C2B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32487-4fd2775a-fe user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:42:34.686Z [377C2B70 verbose 'Default' opID=HB-host-18@32487-4fd2775a-fe user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:42:47.252Z [FFC81B70 verbose 'Vmsvc.vm:/vmfs/volumes/56b32ed0-1f87cb6e-3edc-0017a4779c1e/Liquidware_HUB/Liquidware_HUB.vmx'] Updating current heartbeatStatus: green

2016-02-12T09:42:54.633Z [369A1B70 verbose 'Default' opID=HB-host-18@32490-120ce7cf-fa user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:42:54.645Z [369A1B70 verbose 'Locale' opID=HB-host-18@32490-120ce7cf-fa user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:42:54.672Z [369A1B70 verbose 'Default' opID=HB-host-18@32490-120ce7cf-fa user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:42:54.674Z [369A1B70 verbose 'Default' opID=HB-host-18@32490-120ce7cf-fa user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:42:54.691Z [369A1B70 verbose 'Default' opID=HB-host-18@32490-120ce7cf-fa user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:42:54.691Z [369A1B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32490-120ce7cf-fa user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:42:54.715Z [369A1B70 verbose 'Default' opID=HB-host-18@32490-120ce7cf-fa user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:42:55.112Z [369A1B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:42:58.222Z [FFC81B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:43:24.628Z [38081B70 verbose 'Default' opID=HB-host-18@32491-1142ea95-78 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:43:24.641Z [38081B70 verbose 'Locale' opID=HB-host-18@32491-1142ea95-78 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:43:24.667Z [38081B70 verbose 'Default' opID=HB-host-18@32491-1142ea95-78 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:43:24.669Z [38081B70 verbose 'Default' opID=HB-host-18@32491-1142ea95-78 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:43:24.685Z [38081B70 verbose 'Default' opID=HB-host-18@32491-1142ea95-78 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:43:24.685Z [38081B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32491-1142ea95-78 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:43:24.708Z [38081B70 verbose 'Default' opID=HB-host-18@32491-1142ea95-78 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:43:25.114Z [FFC81B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:43:28.224Z [FFC81B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:43:55.116Z [377C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:43:58.228Z [38081B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:44:03.076Z [36D40B70 verbose 'Cimsvc'] Ticket issued for CIMOM version 1.0, user root

2016-02-12T09:44:04.472Z [36D40B70 info 'Hostsvc' opID=hostd-ff9f] VsanSystemVmkProvider : GetRuntimeInfo: Start

2016-02-12T09:44:04.472Z [36D40B70 info 'Hostsvc' opID=hostd-ff9f] VsanSystemVmkProvider : GetRuntimeInfo: Complete, runtime info: (vim.vsan.host.VsanRuntimeInfo) {

--> dynamicType = <unset>,

--> accessGenNo = 0,

--> }

2016-02-12T09:44:22.126Z [38281B70 verbose 'Hostsvc.ResourcePool ha-root-pool'] Root pool capacity changed from 27496MHz/92085MB to 27496MHz/92089MB

2016-02-12T09:44:22.130Z [380C2B70 verbose 'Default' opID=SWI-4b23109c user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:44:22.132Z [380C2B70 verbose 'Default' opID=SWI-4b23109c user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:44:25.119Z [38281B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:44:28.229Z [369A1B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:44:34.647Z [369A1B70 verbose 'Default' opID=HB-host-18@32492-51150b65-4f user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:44:34.660Z [369A1B70 verbose 'Locale' opID=HB-host-18@32492-51150b65-4f user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:44:34.687Z [369A1B70 verbose 'Default' opID=HB-host-18@32492-51150b65-4f user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:44:34.689Z [369A1B70 verbose 'Default' opID=HB-host-18@32492-51150b65-4f user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:44:34.706Z [FF9C2B70 verbose 'Default' opID=HB-host-18@32492-51150b65-4f user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:44:34.706Z [FF9C2B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32492-51150b65-4f user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:44:34.720Z [FF9C2B70 verbose 'Default' opID=HB-host-18@32492-51150b65-4f user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:44:44.630Z [36D40B70 verbose 'Default' opID=HB-host-18@32493-7560a44e-ef user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:44:44.643Z [36D40B70 verbose 'Locale' opID=HB-host-18@32493-7560a44e-ef user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:44:44.673Z [36D40B70 verbose 'Default' opID=HB-host-18@32493-7560a44e-ef user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:44:44.675Z [38081B70 verbose 'Default' opID=HB-host-18@32493-7560a44e-ef user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:44:44.692Z [38081B70 verbose 'Default' opID=HB-host-18@32493-7560a44e-ef user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:44:44.692Z [38081B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32493-7560a44e-ef user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:44:44.715Z [38081B70 verbose 'Default' opID=HB-host-18@32493-7560a44e-ef user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:44:55.122Z [FF9C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:44:58.232Z [FF9C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:45:01.432Z [36D40B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:45:04.644Z [36D40B70 verbose 'Default' opID=HB-host-18@32494-b3ac7c2-37 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:45:04.652Z [36D40B70 verbose 'Locale' opID=HB-host-18@32494-b3ac7c2-37 user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:45:04.670Z [FF9C2B70 verbose 'Default' opID=HB-host-18@32494-b3ac7c2-37 user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:45:04.673Z [369A1B70 verbose 'Default' opID=HB-host-18@32494-b3ac7c2-37 user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:45:04.684Z [380C2B70 verbose 'Default' opID=HB-host-18@32494-b3ac7c2-37 user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:45:04.684Z [380C2B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32494-b3ac7c2-37 user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:45:04.702Z [380C2B70 verbose 'Default' opID=HB-host-18@32494-b3ac7c2-37 user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:45:14.647Z [38281B70 verbose 'Default' opID=HB-host-18@32495-78f8d7bf-8e user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='GetPerfCounter'

2016-02-12T09:45:14.659Z [FFC81B70 verbose 'Locale' opID=HB-host-18@32495-78f8d7bf-8e user=vpxuser] Default resource used for 'counter.vsanDomObj.writeThroughput.summary' expected in module 'perf'.

2016-02-12T09:45:14.689Z [FFC81B70 verbose 'Default' opID=HB-host-18@32495-78f8d7bf-8e user=vpxuser] AdapterServer: target='vim.HostSystem:ha-host', method='retrieveInternalCapability'

2016-02-12T09:45:14.691Z [FFC81B70 verbose 'Default' opID=HB-host-18@32495-78f8d7bf-8e user=vpxuser] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='queryPerfCounterInt'

2016-02-12T09:45:14.704Z [369A1B70 verbose 'Default' opID=HB-host-18@32495-78f8d7bf-8e user=vpxuser] AdapterServer: target='vim.LicenseManager:ha-license-manager', method='GetLicenses'

2016-02-12T09:45:14.704Z [FFC81B70 verbose 'Vimsvc.ha-license-manager' opID=HB-host-18@32495-78f8d7bf-8e user=vpxuser] Load: Loading existing file: /etc/vmware/license.cfg

2016-02-12T09:45:14.719Z [FFC81B70 verbose 'Default' opID=HB-host-18@32495-78f8d7bf-8e user=vpxuser] ha-license-manager:Validate -> Valid license detected for "VMware ESX Server 5.0" (lastError=0, desc.IsValid:Yes)

2016-02-12T09:45:15.089Z [36D40B70 verbose 'Default' opID=58c155d7-1e user=vpxuser:VSPHERE.LOCAL\vpxd-extension-d9ab40f0-9b7b-11e5-b3d2-0050568d589a] AdapterServer: target='vim.HostSystem:ha-host', method='acquireCimServicesTicket'

2016-02-12T09:45:15.089Z [36D40B70 info 'Vimsvc.TaskManager' opID=58c155d7-1e user=vpxuser:VSPHERE.LOCAL\vpxd-extension-d9ab40f0-9b7b-11e5-b3d2-0050568d589a] Task Created : haTask-ha-host-vim.HostSystem.acquireCimServicesTicket-124255475

2016-02-12T09:45:15.090Z [36D40B70 verbose 'Cimsvc' opID=58c155d7-1e user=vpxuser:VSPHERE.LOCAL\vpxd-extension-d9ab40f0-9b7b-11e5-b3d2-0050568d589a] Ticket issued for CIMOM version 1.0, user vpxuser

2016-02-12T09:45:15.090Z [36D40B70 info 'Vimsvc.TaskManager' opID=58c155d7-1e user=vpxuser:VSPHERE.LOCAL\vpxd-extension-d9ab40f0-9b7b-11e5-b3d2-0050568d589a] Task Completed : haTask-ha-host-vim.HostSystem.acquireCimServicesTicket-124255475 Status success

2016-02-12T09:45:22.127Z [38281B70 verbose 'Hostsvc.ResourcePool ha-root-pool'] Root pool capacity changed from 27496MHz/92089MB to 27496MHz/92085MB

2016-02-12T09:45:22.131Z [38281B70 verbose 'Default' opID=SWI-9071bcfe user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:45:22.132Z [FF91E950 verbose 'Default' opID=SWI-9071bcfe user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:45:25.125Z [369A1B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:45:28.236Z [369A1B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:45:34.470Z [380C2B70 verbose 'Cimsvc'] Ticket issued for CIMOM version 1.0, user root

2016-02-12T09:45:35.853Z [380C2B70 info 'Hostsvc' opID=hostd-4aff] VsanSystemVmkProvider : GetRuntimeInfo: Start

2016-02-12T09:45:35.853Z [380C2B70 info 'Hostsvc' opID=hostd-4aff] VsanSystemVmkProvider : GetRuntimeInfo: Complete, runtime info: (vim.vsan.host.VsanRuntimeInfo) {

--> dynamicType = <unset>,

--> accessGenNo = 0,

--> }

2016-02-12T09:45:39.764Z [377C2B70 verbose 'Default' opID=28322e9f-58 user=vpxuser:domain\vmwarevc] AdapterServer: target='vim.PerformanceManager:ha-perfmgr', method='summarizeStats'

2016-02-12T09:45:41.168Z [377C2B70 verbose 'Default' opID=d616672-9 user=vpxuser:domain\vmwarevc] AdapterServer: target='vim.host.NetworkSystem:networkSystem', method='queryNetworkHint'

2016-02-12T09:45:55.126Z [380C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:45:58.238Z [377C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:46:22.128Z [FF9C2B70 verbose 'Hostsvc.ResourcePool ha-root-pool'] Root pool capacity changed from 27496MHz/92085MB to 27496MHz/92089MB

2016-02-12T09:46:22.132Z [FF9C2B70 verbose 'Default' opID=SWI-5c2690ee user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:46:22.133Z [369A1B70 verbose 'Default' opID=SWI-5c2690ee user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:46:25.127Z [FF9C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:46:28.241Z [369A1B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:46:32.939Z [FFC81B70 verbose 'Hostsvc.DvsTracker' opID=hostd-61de user=vpxuser] FetchSwitches: added 0 items

2016-02-12T09:46:32.939Z [FFC81B70 verbose 'Hostsvc.DvsTracker' opID=hostd-61de user=vpxuser] FetchDVPortgroups: added 0 items

2016-02-12T09:46:32.939Z [FFC81B70 info 'Hostsvc.DvsTracker' opID=hostd-61de user=vpxuser] FetchUplinkDVPortgroups: added 0 items

2016-02-12T09:46:55.130Z [FFC81B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:46:58.243Z [380C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:47:00.115Z [380C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil read workload parameters for 566ed363-d066fb8c-9dca-0017a4779c1e are out of range,oIO = 3111660984 ioSizeBytes = 0

2016-02-12T09:47:00.115Z [380C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil write workload parameters for 566ed363-d066fb8c-9dca-0017a4779c1e are out of range,oIO = 2639053518 ioSizeBytes = 0

2016-02-12T09:47:00.117Z [380C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil read workload parameters for 569e380c-769fb6c4-bd59-0017a4779c1e are out of range,oIO = 3816107534 ioSizeBytes = 0

2016-02-12T09:47:00.117Z [380C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil write workload parameters for 569e380c-769fb6c4-bd59-0017a4779c1e are out of range,oIO = 3660054787 ioSizeBytes = 1

2016-02-12T09:47:00.117Z [380C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil read workload parameters for 56ab5c7a-0d136ec0-c940-0017a4779c1e are out of range,oIO = 2252886035 ioSizeBytes = 2

2016-02-12T09:47:00.117Z [380C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil write workload parameters for 56ab5c7a-0d136ec0-c940-0017a4779c1e are out of range,oIO = 914246645 ioSizeBytes = 0

2016-02-12T09:47:00.118Z [380C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil read workload parameters for 56b32ed0-1f87cb6e-3edc-0017a4779c1e are out of range,oIO = 2824414168 ioSizeBytes = 0

2016-02-12T09:47:00.118Z [380C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil write workload parameters for 56b32ed0-1f87cb6e-3edc-0017a4779c1e are out of range,oIO = 2616043187 ioSizeBytes = 0

2016-02-12T09:47:02.766Z [380C2B70 verbose 'Hostsvc.DvsManager'] PersistAllDvsInfo called

2016-02-12T09:47:05.851Z [FF9C2B70 verbose 'Cimsvc'] Ticket issued for CIMOM version 1.0, user root

2016-02-12T09:47:07.287Z [FF9C2B70 info 'Hostsvc' opID=hostd-b6e7] VsanSystemVmkProvider : GetRuntimeInfo: Start

2016-02-12T09:47:07.288Z [FF9C2B70 info 'Hostsvc' opID=hostd-b6e7] VsanSystemVmkProvider : GetRuntimeInfo: Complete, runtime info: (vim.vsan.host.VsanRuntimeInfo) {

--> dynamicType = <unset>,

--> accessGenNo = 0,

--> }

2016-02-12T09:47:20.112Z [FF9C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil read workload parameters for 566810d1-90c784c0-eb6d-0017a4779c1e are out of range,oIO = 2951075918 ioSizeBytes = 3

2016-02-12T09:47:20.112Z [FF9C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil write workload parameters for 566810d1-90c784c0-eb6d-0017a4779c1e are out of range,oIO = 808397217 ioSizeBytes = 0

2016-02-12T09:47:20.113Z [FF9C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil read workload parameters for 566b336c-01421234-18e8-0017a4771c1c are out of range,oIO = 477371017 ioSizeBytes = 0

2016-02-12T09:47:20.113Z [FF9C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil write workload parameters for 566b336c-01421234-18e8-0017a4771c1c are out of range,oIO = 967780349 ioSizeBytes = 0

2016-02-12T09:47:22.129Z [FF91E950 verbose 'Hostsvc.ResourcePool ha-root-pool'] Root pool capacity changed from 27496MHz/92089MB to 27496MHz/92088MB

2016-02-12T09:47:22.132Z [FF9C2B70 verbose 'Default' opID=SWI-e674217a user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetConfig'

2016-02-12T09:47:22.134Z [FFC81B70 verbose 'Default' opID=SWI-e674217a user=vpxuser] AdapterServer: target='vim.ResourcePool:ha-root-pool', method='GetName'

2016-02-12T09:47:25.133Z [FF91E950 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:47:28.245Z [36D40B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:47:40.116Z [FF9C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil read workload parameters for 566b336c-01421234-18e8-0017a4771c1c are out of range,oIO = 477371017 ioSizeBytes = 0

2016-02-12T09:47:40.116Z [FF9C2B70 warning 'Statssvc.vim.PerformanceManager'] Basil write workload parameters for 566b336c-01421234-18e8-0017a4771c1c are out of range,oIO = 967780349 ioSizeBytes = 0

2016-02-12T09:47:45.809Z [369A1B70 verbose 'Default' opID=6B0930CC-0000261E-dd-69 user=vpxuser:domain\user] AdapterServer: target='vim.AuthorizationManager:ha-authmgr', method='queryPermissions'

2016-02-12T09:47:45.815Z [36D40B70 verbose 'Default' opID=6B0930CC-0000261E-dd-20 user=vpxuser:domain\user] AdapterServer: target='vim.SessionManager:ha-sessionmgr', method='acquireGenericServiceTicket'

2016-02-12T09:47:45.815Z [36D40B70 info 'Vimsvc.TaskManager' opID=6B0930CC-0000261E-dd-20 user=vpxuser:domain\user] Task Created : haTask--vim.SessionManager.acquireGenericServiceTicket-124255592

2016-02-12T09:47:45.815Z [36D40B70 verbose 'Vimsvc.HaCgiServiceManagerImpl' opID=6B0930CC-0000261E-dd-20 user=vpxuser:domain\user] Ticket created: ((null) /cgi-bin/vm-support.cgi?listmanifests=1) - ***e964e

2016-02-12T09:47:45.816Z [36D40B70 info 'Vimsvc.TaskManager' opID=6B0930CC-0000261E-dd-20 user=vpxuser:domain\user] Task Completed : haTask--vim.SessionManager.acquireGenericServiceTicket-124255592 Status success

2016-02-12T09:47:45.840Z [FF9C2B70 verbose 'Vimsvc.HaCgiServiceManagerImpl'] Ticket valid: ***e964e: (GET /cgi-bin/vm-support.cgi?listmanifests=1) == ((null) /cgi-bin/vm-support.cgi?listmanifests=1)

2016-02-12T09:47:45.842Z [FF9C2B70 info 'SysCommandPosix'] ForkExec(/usr/bin/sh) 11755004

2016-02-12T09:47:45.842Z [FF9C2B70 verbose 'Solo.CgiSysCommandHandler (/cgi-bin/vm-support.cgi)'] CGI command started.

2016-02-12T09:47:46.119Z [FF9C2B70 verbose 'Solo.CgiResponseWriter (/cgi-bin/vm-support.cgi)'] Close

2016-02-12T09:47:46.120Z [FF9C2B70 verbose 'Solo.CgiResponseWriter (/cgi-bin/vm-support.cgi)'] Sent Ok response

2016-02-12T09:47:46.120Z [FF9C2B70 verbose 'Solo.CgiSysCommandHandler (/cgi-bin/vm-support.cgi)'] CGI command finished.

2016-02-12T09:47:55.136Z [380C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:47:58.247Z [36D40B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:48:25.138Z [38281B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:48:28.249Z [FFC81B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:48:31.427Z [FF91E950 error 'Solo.HttpSvc.HTTPService'] Failed to read request; stream: <io_obj p:0x3866be30, h:-1, <TCP '0.0.0.0:0'>, <TCP '0.0.0.0:0'>>, error: N7Vmacore16TimeoutExceptionE(Operation timed out)

2016-02-12T09:48:37.287Z [38281B70 verbose 'Cimsvc'] Ticket issued for CIMOM version 1.0, user root

2016-02-12T09:48:38.666Z [38281B70 info 'Hostsvc' opID=hostd-2234] VsanSystemVmkProvider : GetRuntimeInfo: Start

2016-02-12T09:48:38.666Z [38281B70 info 'Hostsvc' opID=hostd-2234] VsanSystemVmkProvider : GetRuntimeInfo: Complete, runtime info: (vim.vsan.host.VsanRuntimeInfo) {

--> dynamicType = <unset>,

--> accessGenNo = 0,

--> }

2016-02-12T09:48:55.140Z [FF9C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:48:58.252Z [369A1B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:49:25.142Z [FFC81B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:49:28.254Z [FF9C2B70 verbose 'SoapAdapter'] Responded to service state request

2016-02-12T09:49:39.966Z [FF9C2B70 info 'Hostsvc.VmkVprobSource'] VmkVprobSource::Post event: (vim.event.EventEx) {

--> dynamicType = <unset>,

--> key = 825110831,