this is what the vpxd showed
2020-07-23T19:29:03.127Z verbose vpxd[04919] [Originator@6876 sub=Default opID=sps-Main-522629-955-13] [VpxVmomi] Invoking [hasPrivilegeOnEntities] on [vim.AuthorizationManager:AuthorizationManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:03.127Z info vpxd[04919] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-13] [VpxLRO] -- BEGIN lro-3955343 -- AuthorizationManager -- vim.AuthorizationManager.hasPrivilegeOnEntities -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:03.127Z verbose vpxd[04919] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-13] Invoke done: vim.AuthorizationManager.hasPrivilegeOnEntities session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:03.128Z info vpxd[04919] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-13] [VpxLRO] -- FINISH lro-3955343
2020-07-23T19:29:03.130Z verbose vpxd[04735] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501071-38] [VpxVmomi] Invoking [createContainerView] on [vim.view.ViewManager:ViewManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:03.130Z info vpxd[04735] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501071-38] [VpxLRO] -- BEGIN lro-3955344 -- ViewManager -- vim.view.ViewManager.createContainerView -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:03.131Z verbose vpxd[04735] [Originator@6876 sub=PropertyProvider opID=sps-Main-522629-955-W501071-38] RecordOp ASSIGN: view, session[523cf992-803f-720e-46de-924d7ddc151d]520f9f66-d78f-4e29-ae4e-492cb2ce4a23. Applied change to temp map.
2020-07-23T19:29:03.131Z verbose vpxd[04735] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501071-38] Invoke done: vim.view.ViewManager.createContainerView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:03.131Z info vpxd[04735] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501071-38] [VpxLRO] -- FINISH lro-3955344
2020-07-23T19:29:03.132Z verbose vpxd[04756] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501071-d9] [VpxVmomi] Invoking [GetView] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]520f9f66-d78f-4e29-ae4e-492cb2ce4a23] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:03.132Z verbose vpxd[04756] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501071-d9] Invoke done: vim.view.ManagedObjectView.GetView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:03.133Z verbose vpxd[04815] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501071-d] [VpxVmomi] Invoking [retrievePropertiesEx] on [vmodl.query.PropertyCollector:propertyCollector] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:03.134Z verbose vpxd[04815] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501071-d] Invoke done: vmodl.query.PropertyCollector.retrievePropertiesEx session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:03.137Z verbose vpxd[04666] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501071-7c] [VpxVmomi] Invoking [destroy] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]520f9f66-d78f-4e29-ae4e-492cb2ce4a23] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:03.137Z info vpxd[63790] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501071-7c] [VpxLRO] -- BEGIN lro-3955347 -- session[523cf992-803f-720e-46de-924d7ddc151d]520f9f66-d78f-4e29-ae4e-492cb2ce4a23 -- vim.view.View.destroy -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:03.137Z verbose vpxd[63790] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501071-7c] Invoke done: vim.view.View.destroy session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:03.137Z info vpxd[63790] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501071-7c] [VpxLRO] -- FINISH lro-3955347
2020-07-23T19:29:04.439Z verbose vpxd[04598] [Originator@6876 sub=Vmomi opID=34c23654] Invoke done: vmodl.query.PropertyCollector.waitForUpdatesEx session: 52941fb6-6976-be5f-e7e6-31057958de82
2020-07-23T19:29:04.441Z verbose vpxd[04637] [Originator@6876 sub=Default opID=3df1ba80] [VpxVmomi] Invoking [waitForUpdatesEx] on [vmodl.query.PropertyCollector:session[52941fb6-6976-be5f-e7e6-31057958de82]52d48ad3-a8ae-5051-c2dc-9724db113264] session [52941fb6-6976-be5f-e7e6-31057958de82(52460c19-be10-aff1-3a78-fd56b89e4512)]
2020-07-23T19:29:05.882Z verbose vpxd[04642] [Originator@6876 sub=SSL SoapAdapter.HTTPService] HTTP Response: Auto-completing at 129/129 bytes
2020-07-23T19:29:05.882Z verbose vpxd[04642] [Originator@6876 sub=SSL SoapAdapter] Responded to service state request
2020-07-23T19:29:06.869Z verbose vpxd[04706] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9] Sleep for 5000 ms, waiting to execute domain-c26
2020-07-23T19:29:11.615Z verbose vpxd[04677] [Originator@6876 sub=Default opID=3f7767a] [VpxVmomi] Invoking [retrieveContent] on [vim.ServiceInstance:ServiceInstance] session [52f7522b-a17f-b56d-1261-c6d4026d4ac0]
2020-07-23T19:29:11.616Z info vpxd[06631] [Originator@6876 sub=vpxLro opID=3f7767a] [VpxLRO] -- BEGIN lro-3955348 -- ServiceInstance -- vim.ServiceInstance.retrieveContent -- 52f7522b-a17f-b56d-1261-c6d4026d4ac0
2020-07-23T19:29:11.616Z verbose vpxd[06631] [Originator@6876 sub=Vmomi opID=3f7767a] Invoke done: vim.ServiceInstance.retrieveContent session: 52f7522b-a17f-b56d-1261-c6d4026d4ac0
2020-07-23T19:29:11.616Z info vpxd[06631] [Originator@6876 sub=vpxLro opID=3f7767a] [VpxLRO] -- FINISH lro-3955348
2020-07-23T19:29:11.616Z verbose vpxd[06631] [Originator@6876 sub=Default opID=3f7767a] CloseSession called for session id=52f7522b-a17f-b56d-1261-c6d4026d4ac0
2020-07-23T19:29:11.645Z verbose vpxd[04712] [Originator@6876 sub=Default opID=6b83459e] [VpxVmomi] Invoking [logout] on [vim.SessionManager:SessionManager] session [52a50b8c-960b-1e0b-acd3-cced1add4f03]
2020-07-23T19:29:11.645Z verbose vpxd[04712] [Originator@6876 sub=Vmomi opID=6b83459e] Invoke error: vim.SessionManager.logout session: 52a50b8c-960b-1e0b-acd3-cced1add4f03 Throw: vim.fault.NotAuthenticated
2020-07-23T19:29:11.645Z verbose vpxd[04712] [Originator@6876 sub=Default] CloseSession called for session id=52a50b8c-960b-1e0b-acd3-cced1add4f03
2020-07-23T19:29:11.663Z verbose vpxd[04701] [Originator@6876 sub=Default opID=774fbf36] [VpxVmomi] Invoking [retrieveContent] on [vim.ServiceInstance:ServiceInstance] session [5279f0e3-ea05-3c75-7ab9-bb5b28812342]
2020-07-23T19:29:11.663Z info vpxd[04701] [Originator@6876 sub=vpxLro opID=774fbf36] [VpxLRO] -- BEGIN lro-3955349 -- ServiceInstance -- vim.ServiceInstance.retrieveContent -- 5279f0e3-ea05-3c75-7ab9-bb5b28812342
2020-07-23T19:29:11.663Z verbose vpxd[04701] [Originator@6876 sub=Vmomi opID=774fbf36] Invoke done: vim.ServiceInstance.retrieveContent session: 5279f0e3-ea05-3c75-7ab9-bb5b28812342
2020-07-23T19:29:11.663Z info vpxd[04701] [Originator@6876 sub=vpxLro opID=774fbf36] [VpxLRO] -- FINISH lro-3955349
2020-07-23T19:29:11.663Z verbose vpxd[04701] [Originator@6876 sub=Default opID=774fbf36] CloseSession called for session id=5279f0e3-ea05-3c75-7ab9-bb5b28812342
2020-07-23T19:29:11.665Z verbose vpxd[04655] [Originator@6876 sub=Default opID=1492af6c] [VpxVmomi] Invoking [logout] on [vim.SessionManager:SessionManager] session [5236e4d5-6684-d904-2065-45faf6434349]
2020-07-23T19:29:11.665Z verbose vpxd[04655] [Originator@6876 sub=Vmomi opID=1492af6c] Invoke error: vim.SessionManager.logout session: 5236e4d5-6684-d904-2065-45faf6434349 Throw: vim.fault.NotAuthenticated
2020-07-23T19:29:11.665Z verbose vpxd[04655] [Originator@6876 sub=Default] CloseSession called for session id=5236e4d5-6684-d904-2065-45faf6434349
2020-07-23T19:29:11.869Z verbose vpxd[04706] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9] Sleep for 5000 ms, waiting to execute domain-c26
2020-07-23T19:29:13.127Z verbose vpxd[04751] [Originator@6876 sub=Default opID=sps-Main-522629-955-1] [VpxVmomi] Invoking [hasPrivilegeOnEntities] on [vim.AuthorizationManager:AuthorizationManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:13.127Z info vpxd[04751] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-1] [VpxLRO] -- BEGIN lro-3955350 -- AuthorizationManager -- vim.AuthorizationManager.hasPrivilegeOnEntities -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:13.127Z verbose vpxd[04751] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-1] Invoke done: vim.AuthorizationManager.hasPrivilegeOnEntities session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:13.128Z info vpxd[04751] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-1] [VpxLRO] -- FINISH lro-3955350
2020-07-23T19:29:13.130Z verbose vpxd[21744] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501072-50] [VpxVmomi] Invoking [createContainerView] on [vim.view.ViewManager:ViewManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:13.131Z info vpxd[04740] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501072-50] [VpxLRO] -- BEGIN lro-3955351 -- ViewManager -- vim.view.ViewManager.createContainerView -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:13.131Z verbose vpxd[04740] [Originator@6876 sub=PropertyProvider opID=sps-Main-522629-955-W501072-50] RecordOp ASSIGN: view, session[523cf992-803f-720e-46de-924d7ddc151d]52699843-ed97-ec78-25a8-20ad2def2540. Applied change to temp map.
2020-07-23T19:29:13.131Z verbose vpxd[04740] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501072-50] Invoke done: vim.view.ViewManager.createContainerView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:13.131Z info vpxd[04740] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501072-50] [VpxLRO] -- FINISH lro-3955351
2020-07-23T19:29:13.132Z verbose vpxd[04686] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501072-9b] [VpxVmomi] Invoking [GetView] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]52699843-ed97-ec78-25a8-20ad2def2540] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:13.132Z verbose vpxd[04686] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501072-9b] Invoke done: vim.view.ManagedObjectView.GetView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:13.134Z verbose vpxd[04700] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501072-95] [VpxVmomi] Invoking [retrievePropertiesEx] on [vmodl.query.PropertyCollector:propertyCollector] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:13.134Z verbose vpxd[04700] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501072-95] Invoke done: vmodl.query.PropertyCollector.retrievePropertiesEx session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:13.137Z verbose vpxd[04799] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501072-af] [VpxVmomi] Invoking [destroy] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]52699843-ed97-ec78-25a8-20ad2def2540] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:13.137Z info vpxd[04627] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501072-af] [VpxLRO] -- BEGIN lro-3955354 -- session[523cf992-803f-720e-46de-924d7ddc151d]52699843-ed97-ec78-25a8-20ad2def2540 -- vim.view.View.destroy -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:13.137Z verbose vpxd[04627] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501072-af] Invoke done: vim.view.View.destroy session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:13.137Z info vpxd[04627] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501072-af] [VpxLRO] -- FINISH lro-3955354
2020-07-23T19:29:13.484Z verbose vpxd[04634] [Originator@6876 sub=Default opID=6d0434c9] [VpxVmomi] Invoking [retrieveContents] on [vmodl.query.PropertyCollector:propertyCollector] session [52433f7d-1ca0-c439-8917-2f853958d363(52a6d0f9-3a17-0147-e5b4-3ae081a9677d)]
2020-07-23T19:29:13.484Z verbose vpxd[04634] [Originator@6876 sub=Vmomi opID=6d0434c9] Invoke done: vmodl.query.PropertyCollector.retrieveContents session: 52433f7d-1ca0-c439-8917-2f853958d363
2020-07-23T19:29:15.943Z verbose vpxd[04625] [Originator@6876 sub=Vmomi opID=PollQuickStatsLoop-4b63962d] [ClientAdapterBase::InvokeOnSoap] Invoke done (de1vm00cpub01az1001.dev.us.corp, vpxapi.VpxaService.fetchQuickStats)
2020-07-23T19:29:15.943Z verbose vpxd[04625] [Originator@6876 sub=Vmomi opID=PollQuickStatsLoop-4b63962d] [ClientAdapterBase::InvokeOnSoap] Invoke done (qavmesx228.dev.us.corp, vpxapi.VpxaService.fetchQuickStats)
2020-07-23T19:29:15.943Z verbose vpxd[04625] [Originator@6876 sub=Vmomi opID=PollQuickStatsLoop-4b63962d] [ClientAdapterBase::InvokeOnSoap] Invoke done (de1vm00cpub01az1002.dev.us.corp, vpxapi.VpxaService.fetchQuickStats)
2020-07-23T19:29:15.943Z verbose vpxd[04625] [Originator@6876 sub=Vmomi opID=PollQuickStatsLoop-4b63962d] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vpxapi.VpxaService.fetchQuickStats)
2020-07-23T19:29:15.945Z verbose vpxd[04624] [Originator@6876 sub=MoHost opID=QuickStatsProcessPollResults-57e82b11] Found linked vm; [vim.HostSystem:host-31,qavmesx228.dev.us.corp], vmid: 2, [vim.VirtualMachine:vm-35,DM99SVICCL01]
2020-07-23T19:29:15.945Z verbose vpxd[04824] [Originator@6876 sub=MoHost opID=QuickStatsProcessPollResults-88e4954] Found linked vm; [vim.HostSystem:host-50,de1vm00cpub01az1001.dev.us.corp], vmid: 3, [vim.VirtualMachine:vm-60,devkronosdbtest]
2020-07-23T19:29:16.869Z verbose vpxd[04706] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9] Sleep for 5000 ms, waiting to execute domain-c26
2020-07-23T19:29:18.348Z verbose vpxd[04660] [Originator@6876 sub=Vmomi opID=3f14452a] Invoke done: vmodl.query.PropertyCollector.waitForUpdatesEx session: 52941fb6-6976-be5f-e7e6-31057958de82
2020-07-23T19:29:18.351Z verbose vpxd[04603] [Originator@6876 sub=Default opID=2f25b009] [VpxVmomi] Invoking [waitForUpdatesEx] on [vmodl.query.PropertyCollector:session[52941fb6-6976-be5f-e7e6-31057958de82]52df123c-0819-bcfc-7235-555d45a3604e] session [52941fb6-6976-be5f-e7e6-31057958de82(52460c19-be10-aff1-3a78-fd56b89e4512)]
2020-07-23T19:29:21.869Z verbose vpxd[04706] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9] Sleep for 5000 ms, waiting to execute domain-c26
2020-07-23T19:29:23.127Z verbose vpxd[04704] [Originator@6876 sub=Default opID=sps-Main-522629-955-32] [VpxVmomi] Invoking [hasPrivilegeOnEntities] on [vim.AuthorizationManager:AuthorizationManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:23.127Z info vpxd[04704] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-32] [VpxLRO] -- BEGIN lro-3955356 -- AuthorizationManager -- vim.AuthorizationManager.hasPrivilegeOnEntities -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:23.127Z verbose vpxd[04704] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-32] Invoke done: vim.AuthorizationManager.hasPrivilegeOnEntities session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:23.128Z info vpxd[04704] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-32] [VpxLRO] -- FINISH lro-3955356
2020-07-23T19:29:23.130Z verbose vpxd[63795] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501073-34] [VpxVmomi] Invoking [createContainerView] on [vim.view.ViewManager:ViewManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:23.130Z info vpxd[63795] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501073-34] [VpxLRO] -- BEGIN lro-3955357 -- ViewManager -- vim.view.ViewManager.createContainerView -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:23.131Z verbose vpxd[63795] [Originator@6876 sub=PropertyProvider opID=sps-Main-522629-955-W501073-34] RecordOp ASSIGN: view, session[523cf992-803f-720e-46de-924d7ddc151d]52af7597-563a-396c-197c-4df6ff27f449. Applied change to temp map.
2020-07-23T19:29:23.131Z verbose vpxd[63795] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501073-34] Invoke done: vim.view.ViewManager.createContainerView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:23.131Z info vpxd[63795] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501073-34] [VpxLRO] -- FINISH lro-3955357
2020-07-23T19:29:23.132Z verbose vpxd[04692] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501073-4] [VpxVmomi] Invoking [GetView] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]52af7597-563a-396c-197c-4df6ff27f449] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:23.132Z verbose vpxd[04692] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501073-4] Invoke done: vim.view.ManagedObjectView.GetView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:23.134Z verbose vpxd[04683] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501073-1c] [VpxVmomi] Invoking [retrievePropertiesEx] on [vmodl.query.PropertyCollector:propertyCollector] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:23.134Z verbose vpxd[04683] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501073-1c] Invoke done: vmodl.query.PropertyCollector.retrievePropertiesEx session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:23.138Z verbose vpxd[63788] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501073-e4] [VpxVmomi] Invoking [destroy] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]52af7597-563a-396c-197c-4df6ff27f449] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:23.138Z info vpxd[63788] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501073-e4] [VpxLRO] -- BEGIN lro-3955360 -- session[523cf992-803f-720e-46de-924d7ddc151d]52af7597-563a-396c-197c-4df6ff27f449 -- vim.view.View.destroy -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:23.138Z verbose vpxd[63788] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501073-e4] Invoke done: vim.view.View.destroy session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:23.138Z info vpxd[63788] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501073-e4] [VpxLRO] -- FINISH lro-3955360
2020-07-23T19:29:26.869Z verbose vpxd[04706] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9] Sleep for 5000 ms, waiting to execute domain-c26
2020-07-23T19:29:27.997Z verbose vpxd[04648] [Originator@6876 sub=InvtHostCnx opID=HeartbeatStartHandler-437266f5] Need inventory sync for: [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:27.997Z verbose vpxd[04648] [Originator@6876 sub=InvtHostCnx opID=HeartbeatStartHandler-437266f5] Queuing host sync; [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:27.998Z verbose vpxd[04639] [Originator@6876 sub=vpxLro opID=HB-host-126@496-57d655ee] [VpxLRO] -- BEGIN lro-3955361 -- -- VpxdInvtHostSyncHostLRO.Synchronize --
2020-07-23T19:29:27.998Z verbose vpxd[04639] [Originator@6876 sub=InvtHostCnx opID=HB-host-126@496-57d655ee] Synchronizing host; [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:27.999Z verbose vpxd[04639] [Originator@6876 sub=Vmomi opID=HB-host-126@496-57d655ee] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vpxapi.VpxaService.getChanges)
2020-07-23T19:29:27.999Z verbose vpxd[04639] [Originator@6876 sub=InvtHostCnx opID=HB-host-126@496-57d655ee] Processing vpxa changes: [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp], gen.no: from 495 to 496
2020-07-23T19:29:28.000Z verbose vpxd[04639] [Originator@6876 sub=PropertyProvider opID=HB-host-126@496-57d655ee] RecordOp ASSIGN: runtime.memoryCapacityForVm, host-126. Applied change to temp map.
2020-07-23T19:29:28.000Z verbose vpxd[04639] [Originator@6876 sub=PropertyProvider opID=HB-host-126@496-57d655ee] RecordOp ASSIGN: summary.runtime.memoryCapacityForVm, host-126. Applied change to temp map.
2020-07-23T19:29:28.000Z verbose vpxd[04639] [Originator@6876 sub=MoHost opID=HB-host-126@496-57d655ee] [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]: cpuCapacity=65619 memCapacity=766041
2020-07-23T19:29:28.000Z verbose vpxd[04639] [Originator@6876 sub=PropertyProvider opID=HB-host-126@496-57d655ee] RecordOp ASSIGN: summary, domain-c26. Applied change to temp map.
2020-07-23T19:29:28.000Z verbose vpxd[04639] [Originator@6876 sub=ResMgr opID=HB-host-126@496-57d655ee] Reloading host [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:28.002Z verbose vpxd[04639] [Originator@6876 sub=InvtHostCnx opID=HB-host-126@496-57d655ee] Done processing vpxa changes; gen.no: 496
2020-07-23T19:29:28.002Z verbose vpxd[04639] [Originator@6876 sub=InvtHostCnx opID=HB-host-126@496-57d655ee] Done synchronizing host; [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:28.002Z verbose vpxd[04639] [Originator@6876 sub=vpxLro opID=HB-host-126@496-57d655ee] [VpxLRO] -- FINISH lro-3955361
2020-07-23T19:29:28.207Z verbose vpxd[04630] [Originator@6876 sub=vpxLro] [VpxLRO] Task lro-3955254 has been rescheduled
2020-07-23T19:29:28.207Z verbose vpxd[04630] [Originator@6876 sub=vpxLro opID=kaoc3e7x-dkr-h5:70010494-dc] [VpxLRO] LRO lro-3955254 has been resumed
2020-07-23T19:29:28.207Z verbose vpxd[04630] [Originator@6876 sub=Vmomi opID=kaoc3e7x-dkr-h5:70010494-dc] Invoke done: vim.cdc.ChangeLogCollector.waitForChanges session: 52dc8a2d-7306-8454-5fb2-b25bfa875d20
2020-07-23T19:29:28.207Z info vpxd[04630] [Originator@6876 sub=vpxLro opID=kaoc3e7x-dkr-h5:70010494-dc] [VpxLRO] -- FINISH lro-3955254
2020-07-23T19:29:28.441Z verbose vpxd[04740] [Originator@6876 sub=MoComputeRes] Recomputing stale res pool tree for computeRes [vim.ClusterComputeResource:domain-c26,cl-m0-infra]
2020-07-23T19:29:29.209Z verbose vpxd[04686] [Originator@6876 sub=Default opID=kaoc3e7x-dkv-h5:70010494-4f] [VpxVmomi] Invoking [waitForChanges] on [vim.cdc.ChangeLogCollector:ChangeLogCollector] session [52dc8a2d-7306-8454-5fb2-b25bfa875d20(526275ec-b6af-4550-69fa-56d175d0aa67)]
2020-07-23T19:29:29.209Z info vpxd[04686] [Originator@6876 sub=vpxLro opID=kaoc3e7x-dkv-h5:70010494-4f] [VpxLRO] -- BEGIN lro-3955362 -- ChangeLogCollector -- vim.cdc.ChangeLogCollector.waitForChanges -- 52dc8a2d-7306-8454-5fb2-b25bfa875d20(526275ec-b6af-4550-69fa-56d175d0aa67)
2020-07-23T19:29:29.209Z verbose vpxd[04686] [Originator@6876 sub=vpxLro] [VpxLRO] Task lro-3955362 has been requeued
2020-07-23T19:29:29.950Z verbose vpxd[04700] [Originator@6876 sub=SSL SoapAdapter.HTTPService] HTTP Response: Auto-completing at 129/129 bytes
2020-07-23T19:29:29.950Z verbose vpxd[04700] [Originator@6876 sub=SSL SoapAdapter] Responded to service state request
2020-07-23T19:29:31.540Z verbose vpxd[04799] [Originator@6876 sub=SoapAdapter] Unrecognized version URI "urn:vim25/6.7.2"; using default handler for "urn:vim25/6.7.1"
2020-07-23T19:29:31.540Z verbose vpxd[04679] [Originator@6876 sub=Default opID=65eb8a3c] [VpxVmomi] Invoking [updateNetworkConfig] on [vim.host.NetworkSystem:networkSystem-126] session [5298788a-c71f-36d9-92e6-cd48acf36141(528edd01-7516-c2c2-53e8-282a5718b6f2)]
2020-07-23T19:29:31.540Z verbose vpxd[04679] [Originator@6876 sub=DvsUtils opID=65eb8a3c] New assignment for pnicSpec.pnicDevice [vmnic0]
2020-07-23T19:29:31.540Z verbose vpxd[04679] [Originator@6876 sub=DvsUtils opID=65eb8a3c] New assignment for pnicSpec.pnicDevice [vmnic1]
2020-07-23T19:29:31.540Z verbose vpxd[04679] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ASSIGN: info.reason, task-1835. Applied change to temp map.
2020-07-23T19:29:31.540Z verbose vpxd[04679] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ASSIGN: info.eventChainId, task-1835. Applied change to temp map.
2020-07-23T19:29:31.541Z verbose vpxd[04679] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ADD: recentTask["task-1835"], TaskManager. Sent notification immediately.
2020-07-23T19:29:31.541Z verbose vpxd[04679] [Originator@6876 sub=MoEvent opID=65eb8a3c] [EventManagerMoImpl::EventBatchAppender::EnqueueJob] Scheduled a new worker (1, 0)
2020-07-23T19:29:31.541Z verbose vpxd[04679] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ASSIGN: info.eventChainId, task-1835. Applied change to temp map.
2020-07-23T19:29:31.541Z verbose vpxd[04634] [Originator@6876 sub=Vmomi opID=kaoc3e7x-dkl-h5:70010494-96] Invoke done: vmodl.query.PropertyCollector.waitForUpdatesEx session: 52dc8a2d-7306-8454-5fb2-b25bfa875d20
2020-07-23T19:29:31.541Z verbose vpxd[04634] [Originator@6876 sub=MoEvent opID=EventManagerProcessJobs-4ac09d6a] [EventManagerMoImpl::EventBatchAppender::WorkerStarted] Start (0, 1)
2020-07-23T19:29:31.544Z verbose vpxd[04679] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ADD: recentTask["task-1835"], host-126. Sent notification immediately.
2020-07-23T19:29:31.544Z verbose vpxd[04635] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: info.cancelable, task-1835. Applied change to temp map.
2020-07-23T19:29:31.544Z verbose vpxd[04635] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: info.startTime, task-1835. Applied change to temp map.
2020-07-23T19:29:31.546Z verbose vpxd[04635] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ASSIGN: info.state, task-1835. Applied change to temp map.
2020-07-23T19:29:31.546Z verbose vpxd[04635] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ASSIGN: info.cancelable, task-1835. Applied change to temp map.
2020-07-23T19:29:31.547Z info vpxd[04635] [Originator@6876 sub=vpxLro opID=65eb8a3c] [VpxLRO] -- BEGIN task-1835 -- networkSystem-126 -- vim.host.NetworkSystem.updateNetworkConfig -- 5298788a-c71f-36d9-92e6-cd48acf36141(528edd01-7516-c2c2-53e8-282a5718b6f2)
2020-07-23T19:29:31.547Z verbose vpxd[04635] [Originator@6876 sub=MoDVPortGroup opID=65eb8a3c] Found non-sticky port [114] for entity [host-126], vnic [vmk0]
2020-07-23T19:29:31.547Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Vnic is connecting to portgroup [dvportgroup-92], auto-select the port [114]
2020-07-23T19:29:31.547Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Eagerly reserve port [114] for the vnic
2020-07-23T19:29:31.547Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Reserving Port [<dvs=50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26 portgroup=dvportgroup-92 port=114] on DVS [vds-dev-lan]
2020-07-23T19:29:31.548Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Set vnic cookie [1613207556] to port [1613207556]
2020-07-23T19:29:31.548Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] Updating port [114] persistence location to [empty]
2020-07-23T19:29:31.549Z verbose vpxd[04635] [Originator@6876 sub=PortSender opID=65eb8a3c] Sending port to hosts: host-126<={114}
2020-07-23T19:29:31.549Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.invokeHostTransactionCall)
2020-07-23T19:29:31.559Z verbose vpxd[04687] [Originator@6876 sub=Vmomi] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.commitTransaction)
2020-07-23T19:29:31.559Z verbose vpxd[04687] [Originator@6876 sub=hostMethod] Host call [applyDVPort] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.559Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.dvs.HostDistributedVirtualSwitchManager.applyDVPortgroup)
2020-07-23T19:29:31.564Z verbose vpxd[04720] [Originator@6876 sub=hostMethod] Host call [applyDVPortgroup] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.565Z verbose vpxd[04635] [Originator@6876 sub=MoDVPortGroup opID=65eb8a3c] Found non-sticky port [1878] for entity [host-126], vnic [vmk1]
2020-07-23T19:29:31.565Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Vnic is connecting to portgroup [dvportgroup-130], auto-select the port [1878]
2020-07-23T19:29:31.565Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Eagerly reserve port [1878] for the vnic
2020-07-23T19:29:31.565Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Reserving Port [<dvs=50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26 portgroup=dvportgroup-130 port=1878] on DVS [vds-dev-lan]
2020-07-23T19:29:31.566Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Set vnic cookie [1613225100] to port [1613225100]
2020-07-23T19:29:31.566Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] Updating port [1878] persistence location to [empty]
2020-07-23T19:29:31.567Z verbose vpxd[04635] [Originator@6876 sub=PortSender opID=65eb8a3c] Sending port to hosts: host-126<={1878}
2020-07-23T19:29:31.567Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.invokeHostTransactionCall)
2020-07-23T19:29:31.584Z verbose vpxd[04720] [Originator@6876 sub=Vmomi] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.commitTransaction)
2020-07-23T19:29:31.584Z verbose vpxd[04720] [Originator@6876 sub=hostMethod] Host call [applyDVPort] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.584Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.dvs.HostDistributedVirtualSwitchManager.applyDVPortgroup)
2020-07-23T19:29:31.588Z verbose vpxd[04599] [Originator@6876 sub=hostMethod] Host call [applyDVPortgroup] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.589Z verbose vpxd[04635] [Originator@6876 sub=MoDVPortGroup opID=65eb8a3c] Found non-sticky port [1] for entity [host-126], vnic [vmk2]
2020-07-23T19:29:31.589Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Vnic is connecting to portgroup [dvportgroup-65], auto-select the port [1]
2020-07-23T19:29:31.589Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Eagerly reserve port [1] for the vnic
2020-07-23T19:29:31.589Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Reserving Port [<dvs=50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26 portgroup=dvportgroup-65 port=1] on DVS [vds-dev-lan]
2020-07-23T19:29:31.589Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Set vnic cookie [1613248952] to port [1613248952]
2020-07-23T19:29:31.589Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] Updating port [1] persistence location to [empty]
2020-07-23T19:29:31.590Z verbose vpxd[04635] [Originator@6876 sub=PortSender opID=65eb8a3c] Sending port to hosts: host-126<={1}
2020-07-23T19:29:31.590Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.invokeHostTransactionCall)
2020-07-23T19:29:31.625Z verbose vpxd[04743] [Originator@6876 sub=Vmomi] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.commitTransaction)
2020-07-23T19:29:31.625Z verbose vpxd[04743] [Originator@6876 sub=hostMethod] Host call [applyDVPort] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.626Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.dvs.HostDistributedVirtualSwitchManager.applyDVPortgroup)
2020-07-23T19:29:31.636Z verbose vpxd[04637] [Originator@6876 sub=hostMethod] Host call [applyDVPortgroup] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.636Z verbose vpxd[04635] [Originator@6876 sub=MoDVPortGroup opID=65eb8a3c] Found non-sticky port [21] for entity [host-126], vnic [vmk3]
2020-07-23T19:29:31.636Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Vnic is connecting to portgroup [dvportgroup-66], auto-select the port [21]
2020-07-23T19:29:31.636Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Eagerly reserve port [21] for the vnic
2020-07-23T19:29:31.636Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Reserving Port [<dvs=50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26 portgroup=dvportgroup-66 port=21] on DVS [vds-dev-lan]
2020-07-23T19:29:31.637Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Set vnic cookie [1613296566] to port [1613296566]
2020-07-23T19:29:31.637Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] Updating port [21] persistence location to [empty]
2020-07-23T19:29:31.638Z verbose vpxd[04635] [Originator@6876 sub=PortSender opID=65eb8a3c] Sending port to hosts: host-126<={21}
2020-07-23T19:29:31.638Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.invokeHostTransactionCall)
2020-07-23T19:29:31.683Z verbose vpxd[04713] [Originator@6876 sub=Vmomi] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.commitTransaction)
2020-07-23T19:29:31.683Z verbose vpxd[04713] [Originator@6876 sub=hostMethod] Host call [applyDVPort] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.683Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.dvs.HostDistributedVirtualSwitchManager.applyDVPortgroup)
2020-07-23T19:29:31.693Z verbose vpxd[04668] [Originator@6876 sub=hostMethod] Host call [applyDVPortgroup] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.693Z verbose vpxd[04635] [Originator@6876 sub=MoDVPortGroup opID=65eb8a3c] Found non-sticky port [1718] for entity [host-126], vnic [vmk4]
2020-07-23T19:29:31.693Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Vnic is connecting to portgroup [dvportgroup-97], auto-select the port [1718]
2020-07-23T19:29:31.693Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Eagerly reserve port [1718] for the vnic
2020-07-23T19:29:31.693Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Reserving Port [<dvs=50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26 portgroup=dvportgroup-97 port=1718] on DVS [vds-dev-lan]
2020-07-23T19:29:31.694Z verbose vpxd[04635] [Originator@6876 sub=coreanchor opID=65eb8a3c] Set vnic cookie [1613353794] to port [1613353794]
2020-07-23T19:29:31.694Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] Updating port [1718] persistence location to [empty]
2020-07-23T19:29:31.695Z verbose vpxd[04635] [Originator@6876 sub=PortSender opID=65eb8a3c] Sending port to hosts: host-126<={1718}
2020-07-23T19:29:31.695Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.invokeHostTransactionCall)
2020-07-23T19:29:31.738Z verbose vpxd[04668] [Originator@6876 sub=Vmomi] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.commitTransaction)
2020-07-23T19:29:31.738Z verbose vpxd[04668] [Originator@6876 sub=hostMethod] Host call [applyDVPort] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.738Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.dvs.HostDistributedVirtualSwitchManager.applyDVPortgroup)
2020-07-23T19:29:31.748Z verbose vpxd[04681] [Originator@6876 sub=hostMethod] Host call [applyDVPortgroup] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.748Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] Updating port [1757] persistence location to [empty]
2020-07-23T19:29:31.749Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] [MoDVSwitch::UpdateUplinkPortsInPnicProcessing] new DVS connection: port[1757]<->pnic[vmnic0], generate new cookie [1613408804]
2020-07-23T19:29:31.749Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] [MoDVSwitch::UpdateUplinkPortsInPnicProcessing] Eagerly reserve port [1757] for pnic [vmnic0], set as connected
2020-07-23T19:29:31.749Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] Updating port [1758] persistence location to [empty]
2020-07-23T19:29:31.750Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] [MoDVSwitch::UpdateUplinkPortsInPnicProcessing] new DVS connection: port[1758]<->pnic[vmnic1], generate new cookie [1613409609]
2020-07-23T19:29:31.750Z verbose vpxd[04635] [Originator@6876 sub=MoDVSwitch opID=65eb8a3c] [MoDVSwitch::UpdateUplinkPortsInPnicProcessing] Eagerly reserve port [1758] for pnic [vmnic1], set as connected
2020-07-23T19:29:31.759Z verbose vpxd[04635] [Originator@6876 sub=PortSender opID=65eb8a3c] Sending port to hosts: host-126<={1758,1757}
2020-07-23T19:29:31.759Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.invokeHostTransactionCall)
2020-07-23T19:29:31.803Z verbose vpxd[04692] [Originator@6876 sub=Vmomi] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.commitTransaction)
2020-07-23T19:29:31.804Z verbose vpxd[04692] [Originator@6876 sub=hostMethod] Host call [applyDVPort] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] succeeded
2020-07-23T19:29:31.804Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vim.host.NetworkSystem.invokeHostTransactionCall)
2020-07-23T19:29:31.831Z error vpxd[04664] [Originator@6876 sub=hostMethod] Host call [updateNetworkConfig] for host [[vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]] failed with exception [Fault cause: vmodl.fault.InvalidArgument
--> ]
2020-07-23T19:29:31.837Z verbose vpxd[04635] [Originator@6876 sub=Vmomi opID=65eb8a3c] Invoke error: vim.host.NetworkSystem.updateNetworkConfig session: 5298788a-c71f-36d9-92e6-cd48acf36141 Throw: vmodl.fault.InvalidArgument
2020-07-23T19:29:31.837Z verbose vpxd[04635] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ASSIGN: info.state, task-1835. Applied change to temp map.
2020-07-23T19:29:31.837Z verbose vpxd[04635] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ASSIGN: info.cancelable, task-1835. Applied change to temp map.
2020-07-23T19:29:31.837Z verbose vpxd[04635] [Originator@6876 sub=PropertyProvider opID=65eb8a3c] RecordOp ASSIGN: info.error, task-1835. Applied change to temp map.
2020-07-23T19:29:31.837Z info vpxd[04635] [Originator@6876 sub=vpxLro opID=65eb8a3c] [VpxLRO] -- FINISH task-1835
2020-07-23T19:29:31.837Z info vpxd[04635] [Originator@6876 sub=Default opID=65eb8a3c] [VpxLRO] -- ERROR task-1835 -- networkSystem-126 -- vim.host.NetworkSystem.updateNetworkConfig: vmodl.fault.InvalidArgument:
--> Result:
--> (vmodl.fault.InvalidArgument) {
--> faultCause = (vmodl.MethodFault) null,
--> faultMessage = <unset>,
--> invalidProperty = <unset>
--> msg = "Received SOAP response fault from [<cs p:00007fdbe8086830, TCP:dmia1vm00cpua031004.dev.us.corp:443>]: invokeHostTransactionCall
--> Received SOAP response fault from [<cs p:0000002dcd6c5a00, TCP:localhost:8307>]: invokeHostTransactionCall
--> A specified parameter was not correct: "
--> }
--> Args:
-->
--> Arg config:
--> (vim.host.NetworkConfig) {
--> vswitch = <unset>,
--> proxySwitch = (vim.host.HostProxySwitch.Config) [
--> (vim.host.HostProxySwitch.Config) {
--> changeOperation = "edit",
--> uuid = "50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26",
--> spec = (vim.host.HostProxySwitch.Specification) {
--> backing = (vim.dvs.HostMember.PnicBacking) {
--> pnicSpec = (vim.dvs.HostMember.PnicSpec) [
--> (vim.dvs.HostMember.PnicSpec) {
--> pnicDevice = "vmnic0",
--> uplinkPortKey = "1757",
--> uplinkPortgroupKey = <unset>,
--> connectionCookie = 1613408804
--> },
--> (vim.dvs.HostMember.PnicSpec) {
--> pnicDevice = "vmnic1",
--> uplinkPortKey = "1758",
--> uplinkPortgroupKey = <unset>,
--> connectionCookie = 1613409609
--> }
--> ]
--> }
--> }
--> }
--> ],
--> portgroup = <unset>,
--> pnic = <unset>,
--> vnic = (vim.host.VirtualNic.Config) [
--> (vim.host.VirtualNic.Config) {
--> changeOperation = "edit",
--> device = "vmk0",
--> portgroup = "MGMT-612",
--> spec = (vim.host.VirtualNic.Specification) {
--> dynamicProperty = <unset>,
--> ip = (vim.host.IpConfig) null,
--> mac = <unset>,
--> distributedVirtualPort = (vim.dvs.PortConnection) {
--> switchUuid = "50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26",
--> portgroupKey = "dvportgroup-92",
--> portKey = "114",
--> connectionCookie = 1613207556
--> },
--> portgroup = <unset>,
--> mtu = <unset>,
--> tsoEnabled = <unset>,
--> netStackInstanceKey = <unset>,
--> opaqueNetwork = (vim.host.VirtualNic.OpaqueNetworkSpec) null,
--> externalId = <unset>,
--> pinnedPnic = <unset>,
--> ipRouteSpec = (vim.host.VirtualNic.IpRouteSpec) null
--> }
--> },
--> (vim.host.VirtualNic.Config) {
--> changeOperation = "edit",
--> device = "vmk1",
--> portgroup = "Vmotion-611",
--> spec = (vim.host.VirtualNic.Specification) {
--> dynamicProperty = <unset>,
--> ip = (vim.host.IpConfig) null,
--> mac = <unset>,
--> distributedVirtualPort = (vim.dvs.PortConnection) {
--> switchUuid = "50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26",
--> portgroupKey = "dvportgroup-130",
--> portKey = "1878",
--> connectionCookie = 1613225100
--> },
--> portgroup = <unset>,
--> mtu = <unset>,
--> tsoEnabled = <unset>,
--> netStackInstanceKey = <unset>,
--> opaqueNetwork = (vim.host.VirtualNic.OpaqueNetworkSpec) null,
--> externalId = <unset>,
--> pinnedPnic = <unset>,
--> ipRouteSpec = (vim.host.VirtualNic.IpRouteSpec) null
--> }
--> },
--> (vim.host.VirtualNic.Config) {
--> changeOperation = "edit",
--> device = "vmk2",
--> portgroup = "SF-ISCSI-A-108",
--> spec = (vim.host.VirtualNic.Specification) {
--> dynamicProperty = <unset>,
--> ip = (vim.host.IpConfig) null,
--> mac = <unset>,
--> distributedVirtualPort = (vim.dvs.PortConnection) {
--> switchUuid = "50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26",
--> portgroupKey = "dvportgroup-65",
--> portKey = "1",
--> connectionCookie = 1613248952
--> },
--> portgroup = <unset>,
--> mtu = <unset>,
--> tsoEnabled = <unset>,
--> netStackInstanceKey = <unset>,
--> opaqueNetwork = (vim.host.VirtualNic.OpaqueNetworkSpec) null,
--> externalId = <unset>,
--> pinnedPnic = <unset>,
--> ipRouteSpec = (vim.host.VirtualNic.IpRouteSpec) null
--> }
--> },
--> (vim.host.VirtualNic.Config) {
--> changeOperation = "edit",
--> device = "vmk3",
--> portgroup = "SF-ISCSI-B-108",
--> spec = (vim.host.VirtualNic.Specification) {
--> dynamicProperty = <unset>,
--> ip = (vim.host.IpConfig) null,
--> mac = <unset>,
--> distributedVirtualPort = (vim.dvs.PortConnection) {
--> switchUuid = "50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26",
--> portgroupKey = "dvportgroup-66",
--> portKey = "21",
--> connectionCookie = 1613296566
--> },
--> portgroup = <unset>,
--> mtu = <unset>,
--> tsoEnabled = <unset>,
--> netStackInstanceKey = <unset>,
--> opaqueNetwork = (vim.host.VirtualNic.OpaqueNetworkSpec) null,
--> externalId = <unset>,
--> pinnedPnic = <unset>,
--> ipRouteSpec = (vim.host.VirtualNic.IpRouteSpec) null
--> }
--> },
--> (vim.host.VirtualNic.Config) {
--> changeOperation = "edit",
--> device = "vmk4",
--> portgroup = "NFS-694",
--> spec = (vim.host.VirtualNic.Specification) {
--> dynamicProperty = <unset>,
--> ip = (vim.host.IpConfig) null,
--> mac = <unset>,
--> distributedVirtualPort = (vim.dvs.PortConnection) {
--> switchUuid = "50 29 6d 53 73 90 0e 1a-08 62 6e 21 d1 2b a2 26",
--> portgroupKey = "dvportgroup-97",
--> portKey = "1718",
--> connectionCookie = 1613353794
--> },
--> portgroup = <unset>,
--> mtu = <unset>,
--> tsoEnabled = <unset>,
--> netStackInstanceKey = <unset>,
--> opaqueNetwork = (vim.host.VirtualNic.OpaqueNetworkSpec) null,
--> externalId = <unset>,
--> pinnedPnic = <unset>,
--> ipRouteSpec = (vim.host.VirtualNic.IpRouteSpec) null
--> }
--> }
--> ],
--> consoleVnic = <unset>,
--> dnsConfig = (vim.host.DnsConfig) null,
--> ipRouteConfig = (vim.host.IpRouteConfig) null,
--> consoleIpRouteConfig = (vim.host.IpRouteConfig) null,
--> routeTableConfig = (vim.host.IpRouteTableConfig) null,
--> dhcp = <unset>,
--> nat = <unset>,
--> ipV6Enabled = <unset>,
--> netStackSpec = <unset>
--> }
--> Arg changeMode:
--> "modify"
2020-07-23T19:29:31.837Z verbose vpxd[04635] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: info.completeTime, task-1835. Applied change to temp map.
2020-07-23T19:29:31.869Z verbose vpxd[04706] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9] Sleep for 5000 ms, waiting to execute domain-c26
2020-07-23T19:29:32.481Z verbose vpxd[04620] [Originator@6876 sub=PropertyProvider opID=ProcessEventNotifications-56f32f43] RecordOp ASSIGN: latestEvent, EventManager. Applied change to temp map.
2020-07-23T19:29:32.545Z verbose vpxd[04742] [Originator@6876 sub=Default opID=kaoc3e7x-dkw-h5:70010494-7d] [VpxVmomi] Invoking [waitForUpdatesEx] on [vmodl.query.PropertyCollector:propertyCollector] session [52dc8a2d-7306-8454-5fb2-b25bfa875d20(526275ec-b6af-4550-69fa-56d175d0aa67)]
2020-07-23T19:29:32.545Z verbose vpxd[04742] [Originator@6876 sub=Vmomi opID=kaoc3e7x-dkw-h5:70010494-7d] Invoke done: vmodl.query.PropertyCollector.waitForUpdatesEx session: 52dc8a2d-7306-8454-5fb2-b25bfa875d20
2020-07-23T19:29:33.127Z verbose vpxd[04936] [Originator@6876 sub=Default opID=sps-Main-522629-955-ea] [VpxVmomi] Invoking [hasPrivilegeOnEntities] on [vim.AuthorizationManager:AuthorizationManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:33.127Z info vpxd[04936] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-ea] [VpxLRO] -- BEGIN lro-3955364 -- AuthorizationManager -- vim.AuthorizationManager.hasPrivilegeOnEntities -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:33.127Z verbose vpxd[04936] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-ea] Invoke done: vim.AuthorizationManager.hasPrivilegeOnEntities session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:33.128Z info vpxd[04936] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-ea] [VpxLRO] -- FINISH lro-3955364
2020-07-23T19:29:33.130Z verbose vpxd[04639] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501074-3c] [VpxVmomi] Invoking [createContainerView] on [vim.view.ViewManager:ViewManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:33.130Z info vpxd[04630] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501074-3c] [VpxLRO] -- BEGIN lro-3955365 -- ViewManager -- vim.view.ViewManager.createContainerView -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:33.130Z verbose vpxd[04630] [Originator@6876 sub=PropertyProvider opID=sps-Main-522629-955-W501074-3c] RecordOp ASSIGN: view, session[523cf992-803f-720e-46de-924d7ddc151d]52cd9f47-a1f7-4588-f209-cec57498dbb4. Applied change to temp map.
2020-07-23T19:29:33.130Z verbose vpxd[04630] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501074-3c] Invoke done: vim.view.ViewManager.createContainerView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:33.130Z info vpxd[04630] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501074-3c] [VpxLRO] -- FINISH lro-3955365
2020-07-23T19:29:33.131Z verbose vpxd[04615] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501074-31] [VpxVmomi] Invoking [GetView] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]52cd9f47-a1f7-4588-f209-cec57498dbb4] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:33.131Z verbose vpxd[04615] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501074-31] Invoke done: vim.view.ManagedObjectView.GetView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:33.133Z verbose vpxd[04806] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501074-5c] [VpxVmomi] Invoking [retrievePropertiesEx] on [vmodl.query.PropertyCollector:propertyCollector] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:33.133Z verbose vpxd[04806] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501074-5c] Invoke done: vmodl.query.PropertyCollector.retrievePropertiesEx session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:33.136Z verbose vpxd[04747] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501074-b] [VpxVmomi] Invoking [destroy] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]52cd9f47-a1f7-4588-f209-cec57498dbb4] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:33.136Z info vpxd[04747] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501074-b] [VpxLRO] -- BEGIN lro-3955368 -- session[523cf992-803f-720e-46de-924d7ddc151d]52cd9f47-a1f7-4588-f209-cec57498dbb4 -- vim.view.View.destroy -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:33.136Z verbose vpxd[04747] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501074-b] Invoke done: vim.view.View.destroy session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:33.136Z info vpxd[04747] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501074-b] [VpxLRO] -- FINISH lro-3955368
2020-07-23T19:29:33.483Z verbose vpxd[04700] [Originator@6876 sub=Default opID=7c6be3c2] [VpxVmomi] Invoking [retrieveContents] on [vmodl.query.PropertyCollector:propertyCollector] session [52433f7d-1ca0-c439-8917-2f853958d363(52a6d0f9-3a17-0147-e5b4-3ae081a9677d)]
2020-07-23T19:29:33.484Z verbose vpxd[04799] [Originator@6876 sub=Vmomi opID=7c6be3c2] Invoke done: vmodl.query.PropertyCollector.retrieveContents session: 52433f7d-1ca0-c439-8917-2f853958d363
2020-07-23T19:29:33.547Z verbose vpxd[04597] [Originator@6876 sub=Default opID=kaoc3e7x-dkx-h5:70010494-5f] [VpxVmomi] Invoking [waitForUpdatesEx] on [vmodl.query.PropertyCollector:propertyCollector] session [52dc8a2d-7306-8454-5fb2-b25bfa875d20(526275ec-b6af-4550-69fa-56d175d0aa67)]
2020-07-23T19:29:33.708Z verbose vpxd[04694] [Originator@6876 sub=SoapAdapter] Unrecognized version URI "urn:vim25/6.7.2"; using default handler for "urn:vim25/6.7.1"
2020-07-23T19:29:33.708Z verbose vpxd[04694] [Originator@6876 sub=Default opID=27b517c6] [VpxVmomi] Invoking [currentTime] on [vim.ServiceInstance:ServiceInstance] session [5298788a-c71f-36d9-92e6-cd48acf36141(528edd01-7516-c2c2-53e8-282a5718b6f2)]
2020-07-23T19:29:33.708Z verbose vpxd[04694] [Originator@6876 sub=Vmomi opID=27b517c6] Invoke done: vim.ServiceInstance.currentTime session: 5298788a-c71f-36d9-92e6-cd48acf36141
2020-07-23T19:29:35.883Z verbose vpxd[04628] [Originator@6876 sub=SSL SoapAdapter.HTTPService] HTTP Response: Auto-completing at 129/129 bytes
2020-07-23T19:29:35.883Z verbose vpxd[04628] [Originator@6876 sub=SSL SoapAdapter] Responded to service state request
2020-07-23T19:29:36.869Z verbose vpxd[04706] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9] Found an entry to execute: domain-c26
2020-07-23T19:29:36.869Z info vpxd[04656] [Originator@6876 sub=vpxLro opID=lro-2-60b7acd9-13b3e] [VpxLRO] -- BEGIN lro-3955370 -- -- AskResSettingRecLro --
2020-07-23T19:29:36.869Z verbose vpxd[04656] [Originator@6876 sub=MoCluster opID=lro-2-60b7acd9-13b3e] RpTreeGenNo: current = 0, lastDivvyCall = 0, lastDivvyDone = 0; isRpUpdateNeeded = false
2020-07-23T19:29:36.869Z verbose vpxd[04656] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9-13b3e] Ignoring host [vim.HostSystem:host-113,dmia1vm00cpua031001.dev.us.corp]: IsConnected: false, InMaintenanceMode: true, InStandbyMode: false
2020-07-23T19:29:36.869Z verbose vpxd[04656] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9-13b3e] Ignoring host [vim.HostSystem:host-116,dmia1vm00cpua031002.dev.us.corp]: IsConnected: false, InMaintenanceMode: true, InStandbyMode: false
2020-07-23T19:29:36.869Z verbose vpxd[04656] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9-13b3e] Ignoring host [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]: IsConnected: true, InMaintenanceMode: true, InStandbyMode: false
2020-07-23T19:29:36.869Z verbose vpxd[04656] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9-13b3e] Snapshot Vms on host qavmesx228.dev.us.corp
2020-07-23T19:29:36.870Z verbose vpxd[04656] [Originator@6876 sub=MoComputeRes opID=lro-2-60b7acd9-13b3e] Setting _resDirty flag and recompute ResPoolTree
2020-07-23T19:29:36.870Z verbose vpxd[04656] [Originator@6876 sub=drsExec opID=lro-2-60b7acd9-13b3e] VM [vim.VirtualMachine:vm-35,DM99SVICCL01] received overheadLimit = 288 from DRS
2020-07-23T19:29:36.870Z verbose vpxd[04656] [Originator@6876 sub=MoHost opID=lro-2-60b7acd9-13b3e] Host [vim.HostSystem:host-31,qavmesx228.dev.us.corp]: increment ResPoolSpecGenNo (159066) and MasterSpecGenNo (159067)
2020-07-23T19:29:36.870Z verbose vpxd[04656] [Originator@6876 sub=PropertyProvider opID=lro-2-60b7acd9-13b3e] RecordOp ASSIGN: summary.usageSummary, domain-c26. Applied change to temp map.
2020-07-23T19:29:36.870Z info vpxd[04656] [Originator@6876 sub=vpxLro opID=lro-2-60b7acd9-13b3e] [VpxLRO] -- FINISH lro-3955370
2020-07-23T19:29:36.870Z verbose vpxd[04706] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9] Sleep for 5000 ms, waiting to execute domain-c26
2020-07-23T19:29:36.870Z verbose vpxd[04656] [Originator@6876 sub=MoComputeRes opID=lro-2-60b7acd9-13b3e-ComputeResEntityStateChangeAction-622bdc87] Recomputing stale res pool tree for computeRes [vim.ClusterComputeResource:domain-c26,cl-m0-infra]
2020-07-23T19:29:38.001Z verbose vpxd[04648] [Originator@6876 sub=InvtHostCnx opID=HeartbeatStartHandler-437266f5] Need inventory sync for: [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:38.001Z verbose vpxd[04648] [Originator@6876 sub=InvtHostCnx opID=HeartbeatStartHandler-437266f5] Queuing host sync; [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:38.001Z verbose vpxd[04638] [Originator@6876 sub=vpxLro opID=HB-host-126@513-2d9b556b] [VpxLRO] -- BEGIN lro-3955371 -- -- VpxdInvtHostSyncHostLRO.Synchronize --
2020-07-23T19:29:38.001Z verbose vpxd[04638] [Originator@6876 sub=InvtHostCnx opID=HB-host-126@513-2d9b556b] Synchronizing host; [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:38.050Z verbose vpxd[04638] [Originator@6876 sub=Vmomi opID=HB-host-126@513-2d9b556b] [ClientAdapterBase::InvokeOnSoap] Invoke done (dmia1vm00cpua031004.dev.us.corp, vpxapi.VpxaService.getChanges)
2020-07-23T19:29:38.050Z verbose vpxd[04638] [Originator@6876 sub=InvtHostCnx opID=HB-host-126@513-2d9b556b] Processing vpxa changes: [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp], gen.no: from 496 to 513
2020-07-23T19:29:38.050Z verbose vpxd[04638] [Originator@6876 sub=dbQuery opID=HB-host-126@513-2d9b556b] FDCFEIDB(): Entered: host-126
2020-07-23T19:29:38.051Z verbose vpxd[04638] [Originator@6876 sub=corehostSync opID=HB-host-126@513-2d9b556b] Old dvs connection: [], new dvs connection: []
2020-07-23T19:29:38.051Z verbose vpxd[04638] [Originator@6876 sub=corehostSync opID=HB-host-126@513-2d9b556b] Disconnecting: [], connecting: []
2020-07-23T19:29:38.056Z verbose vpxd[04638] [Originator@6876 sub=InvtId opID=HB-host-126@513-2d9b556b] [VpxdInvtId]RegisterHostIp(dmia1vm00cpua031004.dev.us.corp)
2020-07-23T19:29:38.059Z verbose vpxd[04638] [Originator@6876 sub=PropertyProvider opID=HB-host-126@513-2d9b556b] RecordOp ASSIGN: datastore, datastoreSystem-126. Sent notification immediately.
2020-07-23T19:29:38.059Z verbose vpxd[04638] [Originator@6876 sub=PropertyProvider opID=HB-host-126@513-2d9b556b] RecordOp ASSIGN: runtime, healthStatusSystem-126. Sent notification immediately.
2020-07-23T19:29:38.059Z verbose vpxd[04638] [Originator@6876 sub=PropertyProvider opID=HB-host-126@513-2d9b556b] RecordOp ASSIGN: configInfo, esxAgentHostManager-126. Sent notification immediately.
2020-07-23T19:29:38.059Z verbose vpxd[04638] [Originator@6876 sub=PropertyProvider opID=HB-host-126@513-2d9b556b] RecordOp ASSIGN: lockdownMode, hostAccessManager-126. Sent notification immediately.
2020-07-23T19:29:38.059Z verbose vpxd[04638] [Originator@6876 sub=ResMgr opID=HB-host-126@513-2d9b556b] Reloading host [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:38.060Z verbose vpxd[04638] [Originator@6876 sub=InvtHostCnx opID=HB-host-126@513-2d9b556b] Done processing vpxa changes; gen.no: 513
2020-07-23T19:29:38.060Z verbose vpxd[04638] [Originator@6876 sub=InvtHostCnx opID=HB-host-126@513-2d9b556b] Done synchronizing host; [vim.HostSystem:host-126,dmia1vm00cpua031004.dev.us.corp]
2020-07-23T19:29:38.060Z verbose vpxd[04638] [Originator@6876 sub=vpxLro opID=HB-host-126@513-2d9b556b] [VpxLRO] -- FINISH lro-3955371
2020-07-23T19:29:38.691Z verbose vpxd[04598] [Originator@6876 sub=Default opID=sps-Main-522629-955-31] [VpxVmomi] Invoking [sessionIsActive] on [vim.SessionManager:SessionManager] session [52cb3e28-0846-eee4-cc8a-265cbe817502(5243bcfa-ae50-ae7d-f593-28ca670320ce)]
2020-07-23T19:29:38.691Z info vpxd[04598] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-31] [VpxLRO] -- BEGIN lro-3955372 -- SessionManager -- vim.SessionManager.sessionIsActive -- 52cb3e28-0846-eee4-cc8a-265cbe817502(5243bcfa-ae50-ae7d-f593-28ca670320ce)
2020-07-23T19:29:38.691Z verbose vpxd[04598] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-31] Invoke done: vim.SessionManager.sessionIsActive session: 52cb3e28-0846-eee4-cc8a-265cbe817502
2020-07-23T19:29:38.691Z info vpxd[04598] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-31] [VpxLRO] -- FINISH lro-3955372
2020-07-23T19:29:41.870Z verbose vpxd[04706] [Originator@6876 sub=drmLogger opID=lro-2-60b7acd9] Sleep for 5000 ms, waiting to execute domain-c26
2020-07-23T19:29:43.127Z verbose vpxd[04821] [Originator@6876 sub=Default opID=sps-Main-522629-955-6d] [VpxVmomi] Invoking [hasPrivilegeOnEntities] on [vim.AuthorizationManager:AuthorizationManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:43.127Z info vpxd[04821] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-6d] [VpxLRO] -- BEGIN lro-3955373 -- AuthorizationManager -- vim.AuthorizationManager.hasPrivilegeOnEntities -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:43.127Z verbose vpxd[04821] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-6d] Invoke done: vim.AuthorizationManager.hasPrivilegeOnEntities session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:43.128Z info vpxd[04821] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-6d] [VpxLRO] -- FINISH lro-3955373
2020-07-23T19:29:43.132Z verbose vpxd[04617] [Originator@6876 sub=Default opID=sps-Main-522629-955-3e] [VpxVmomi] Invoking [hasPrivilegeOnEntities] on [vim.AuthorizationManager:AuthorizationManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:43.132Z info vpxd[04617] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-3e] [VpxLRO] -- BEGIN lro-3955374 -- AuthorizationManager -- vim.AuthorizationManager.hasPrivilegeOnEntities -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:43.132Z verbose vpxd[04617] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-3e] Invoke done: vim.AuthorizationManager.hasPrivilegeOnEntities session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:43.132Z info vpxd[04617] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-3e] [VpxLRO] -- FINISH lro-3955374
2020-07-23T19:29:43.135Z verbose vpxd[63789] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501075-90] [VpxVmomi] Invoking [createContainerView] on [vim.view.ViewManager:ViewManager] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:43.135Z info vpxd[63789] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501075-90] [VpxLRO] -- BEGIN lro-3955375 -- ViewManager -- vim.view.ViewManager.createContainerView -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:43.135Z verbose vpxd[63789] [Originator@6876 sub=PropertyProvider opID=sps-Main-522629-955-W501075-90] RecordOp ASSIGN: view, session[523cf992-803f-720e-46de-924d7ddc151d]52d437cd-2ee2-b732-5813-d3811692d228. Applied change to temp map.
2020-07-23T19:29:43.135Z verbose vpxd[63789] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501075-90] Invoke done: vim.view.ViewManager.createContainerView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:43.135Z info vpxd[63789] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501075-90] [VpxLRO] -- FINISH lro-3955375
2020-07-23T19:29:43.136Z verbose vpxd[06631] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501075-83] [VpxVmomi] Invoking [GetView] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]52d437cd-2ee2-b732-5813-d3811692d228] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:43.136Z verbose vpxd[06631] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501075-83] Invoke done: vim.view.ManagedObjectView.GetView session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:43.138Z verbose vpxd[04819] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501075-3f] [VpxVmomi] Invoking [retrievePropertiesEx] on [vmodl.query.PropertyCollector:propertyCollector] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:43.138Z verbose vpxd[04819] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501075-3f] Invoke done: vmodl.query.PropertyCollector.retrievePropertiesEx session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:43.141Z verbose vpxd[04675] [Originator@6876 sub=Default opID=sps-Main-522629-955-W501075-4] [VpxVmomi] Invoking [destroy] on [vim.view.ContainerView:session[523cf992-803f-720e-46de-924d7ddc151d]52d437cd-2ee2-b732-5813-d3811692d228] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:43.141Z info vpxd[04636] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501075-4] [VpxLRO] -- BEGIN lro-3955378 -- session[523cf992-803f-720e-46de-924d7ddc151d]52d437cd-2ee2-b732-5813-d3811692d228 -- vim.view.View.destroy -- 523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)
2020-07-23T19:29:43.141Z verbose vpxd[04636] [Originator@6876 sub=Vmomi opID=sps-Main-522629-955-W501075-4] Invoke done: vim.view.View.destroy session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:43.142Z info vpxd[04636] [Originator@6876 sub=vpxLro opID=sps-Main-522629-955-W501075-4] [VpxLRO] -- FINISH lro-3955378
2020-07-23T19:29:44.607Z verbose vpxd[04636] [Originator@6876 sub=Default opID=19e20320] [VpxVmomi] Invoking [currentTime] on [vim.ServiceInstance:ServiceInstance] session [523cf992-803f-720e-46de-924d7ddc151d(52e67096-22d5-f1bf-49e7-fd1fbe150c0b)]
2020-07-23T19:29:44.607Z verbose vpxd[04636] [Originator@6876 sub=Vmomi opID=19e20320] Invoke done: vim.ServiceInstance.currentTime session: 523cf992-803f-720e-46de-924d7ddc151d
2020-07-23T19:29:44.971Z verbose vpxd[04648] [Originator@6876 sub=InvtHostCnx opID=HeartbeatStartHandler-437266f5] Need spec sync for [vim.HostSystem:host-31,qavmesx228.dev.us.corp]
2020-07-23T19:29:44.971Z verbose vpxd[04648] [Originator@6876 sub=InvtHostCnx opID=HeartbeatStartHandler-437266f5] Queuing spec sync LRO for: [vim.HostSystem:host-31,qavmesx228.dev.us.corp]
2020-07-23T19:29:44.972Z info vpxd[04636] [Originator@6876 sub=vpxLro opID=HB-SpecSync-host-31@159065-453b3726] [VpxLRO] -- BEGIN lro-3955379 -- -- SpecSyncLRO.Synchronize --
2020-07-23T19:29:44.972Z verbose vpxd[04636] [Originator@6876 sub=InvtHostCnx opID=HB-SpecSync-host-31@159065-453b3726] Preparing spec sync for [vim.HostSystem:host-31,qavmesx228.dev.us.corp], vpxaMasterSpecGenNo = 159065, vpxdMasterSpecGenNo = 159067
2020-07-23T19:29:44.972Z verbose vpxd[04636] [Originator@6876 sub=ResMgr opID=HB-SpecSync-host-31@159065-453b3726] During attachment VM [vim.VirtualMachine:vm-35,DM99SVICCL01]: parentRespool = vim.ResourcePool:resgroup-27, using root pool because: unknown reason
2020-07-23T19:29:44.972Z verbose vpxd[04636] [Originator@6876 sub=InvtHostCnx opID=HB-SpecSync-host-31@159065-453b3726] Set respool spec to (vpxapi.ResourcePoolSpec) {
--> name = "Resources",
--> spec = (vim.ResourceConfigSpec) {
--> entity = <unset>,
--> changeVersion = <unset>,
--> lastModified = <unset>,
--> cpuAllocation = (vim.ResourceAllocationInfo) {
--> reservation = 0,
--> expandableReservation = true,
--> limit = -1,
--> shares = (vim.SharesInfo) {
--> shares = 4000,
--> level = "normal"
--> },
--> overheadLimit = <unset>
--> },
--> memoryAllocation = (vim.ResourceAllocationInfo) {
--> reservation = 0,
--> expandableReservation = true,
--> limit = -1,
--> shares = (vim.SharesInfo) {
--> shares = 163840,
--> level = "normal"
--> },
--> overheadLimit = <unset>
--> },
--> networkBandwidthAllocation = <unset>
--> },
--> entity = 'vim.ResourcePool:a9f1f368-5f94-48b5-ab36-72e764e828e1:resgroup-27',
--> childSpec = <unset>,
--> childVm = (vpxapi.VmResourcePoolSpec) [
--> (vpxapi.VmResourcePoolSpec) {
--> childVmId = 2,
--> vmConfigResPoolSpec = (vim.ResourceConfigSpec) {
--> entity = 'vim.VirtualMachine:a9f1f368-5f94-48b5-ab36-72e764e828e1:vm-35',
--> changeVersion = <unset>,
--> lastModified = <unset>,
--> cpuAllocation = (vim.ResourceAllocationInfo) {
--> reservation = 0,
--> expandableReservation = <unset>,
--> limit = -1,
--> shares = (vim.SharesInfo) {
--> shares = 16000,
--> level = "normal"
--> },
--> overheadLimit = <unset>
--> },
--> memoryAllocation = (vim.ResourceAllocationInfo) {
--> reservation = 0,
--> expandableReservation = <unset>,
--> limit = -1,
--> shares = (vim.SharesInfo) {
--> shares = 327680,
--> level = "normal"
--> },
--> overheadLimit = -1
--> },
--> networkBandwidthAllocation = <unset>
--> }
--> }
--> ]
--> }
2020-07-23T19:29:44.972Z verbose vpxd[04636] [Originator@6876 sub=licenseClient opID=HB-SpecSync-host-31@159065-453b3726] UpdateHostLicenseSpec host: [vim.HostSystem:host-31,qavmesx228.dev.us.corp] vpxaMasterSpecGenNo: 159065 pendingChanges: false
2020-07-23T19:29:44.976Z verbose vpxd[04636] [Originator@6876 sub=Vmomi opID=HB-SpecSync-host-31@159065-453b3726] [ClientAdapterBase::InvokeOnSoap] Invoke done (qavmesx228.dev.us.corp, vpxapi.VpxaService.setConfig)
2020-07-23T19:29:44.977Z info vpxd[04636] [Originator@6876 sub=vpxLro opID=HB-SpecSync-host-31@159065-453b3726] [VpxLRO] -- FINISH lro-3955379
2020-07-23T19:29:45.648Z verbose vpxd[21744] [Originator@6876 sub=Default opID=32a1c34c] [VpxVmomi] Invoking [retrieveContent] on [vim.ServiceInstance:ServiceInstance] session [52d1fd3d-783d-0b82-06a8-4d769a5f59b0]
2020-07-23T19:29:45.648Z info vpxd[21744] [Originator@6876 sub=vpxLro opID=32a1c34c] [VpxLRO] -- BEGIN lro-3955380 -- ServiceInstance -- vim.ServiceInstance.retrieveContent -- 52d1fd3d-783d-0b82-06a8-4d769a5f59b0
2020-07-23T19:29:45.648Z verbose vpxd[21744] [Originator@6876 sub=Vmomi opID=32a1c34c] Invoke done: vim.ServiceInstance.retrieveContent session: 52d1fd3d-783d-0b82-06a8-4d769a5f59b0
2020-07-23T19:29:45.648Z info vpxd[21744] [Originator@6876 sub=vpxLro opID=32a1c34c] [VpxLRO] -- FINISH lro-3955380
2020-07-23T19:29:45.648Z verbose vpxd[21744] [Originator@6876 sub=Default opID=32a1c34c] CloseSession called for session id=52d1fd3d-783d-0b82-06a8-4d769a5f59b0
2020-07-23T19:29:45.685Z verbose vpxd[04711] [Originator@6876 sub=Default opID=1a1ccc8c] [VpxVmomi] Invoking [logout] on [vim.SessionManager:SessionManager] session [5231d34d-7cf7-b0b0-69b4-a27fb1bc78e9]
2020-07-23T19:29:45.685Z verbose vpxd[04711] [Originator@6876 sub=Vmomi opID=1a1ccc8c] Invoke error: vim.SessionManager.logout session: 5231d34d-7cf7-b0b0-69b4-a27fb1bc78e9 Throw: vim.fault.NotAuthenticated
2020-07-23T19:29:45.685Z verbose vpxd[04711] [Originator@6876 sub=Default] CloseSession called for session id=5231d34d-7cf7-b0b0-69b4-a27fb1bc78e9
2020-07-23T19:29:45.698Z verbose vpxd[04686] [Originator@6876 sub=Default opID=58b908b8] [VpxVmomi] Invoking [retrieveContent] on [vim.ServiceInstance:ServiceInstance] session [525bfd57-a625-3699-9114-5175f2535222]
2020-07-23T19:29:45.698Z info vpxd[04686] [Originator@6876 sub=vpxLro opID=58b908b8] [VpxLRO] -- BEGIN lro-3955381 -- ServiceInstance -- vim.ServiceInstance.retrieveContent -- 525bfd57-a625-3699-9114-5175f2535222
2020-07-23T19:29:45.698Z verbose vpxd[04686] [Originator@6876 sub=Vmomi opID=58b908b8] Invoke done: vim.ServiceInstance.retrieveContent session: 525bfd57-a625-3699-9114-5175f2535222
2020-07-23T19:29:45.698Z info vpxd[04686] [Originator@6876 sub=vpxLro opID=58b908b8] [VpxLRO] -- FINISH lro-3955381
2020-07-23T19:29:45.698Z verbose vpxd[04686] [Originator@6876 sub=Default opID=58b908b8] CloseSession called for session id=525bfd57-a625-3699-9114-5175f2535222
2020-07-23T19:29:45.700Z verbose vpxd[04686] [Originator@6876 sub=Default opID=5beb9589] [VpxVmomi] Invoking [queryView] on [vim.option.OptionManager:VpxSettings] session [52aea863-035b-8a65-362b-bb7576606423]
2020-07-23T19:29:45.700Z verbose vpxd[04686] [Originator@6876 sub=Vmomi opID=5beb9589] Invoke error: vim.option.OptionManager.queryView session: 52aea863-035b-8a65-362b-bb7576606423 Throw: vim.fault.NotAuthenticated
2020-07-23T19:29:45.700Z verbose vpxd[04686] [Originator@6876 sub=Default] CloseSession called for session id=52aea863-035b-8a65-362b-bb7576606423
2020-07-23T19:29:45.920Z verbose vpxd[04654] [Originator@6876 sub=Default opID=47a68484] [VpxVmomi] Invoking [loginByToken] on [vim.SessionManager:SessionManager] session [52619c4a-e700-ece6-ba71-c0e100499423]
2020-07-23T19:29:45.920Z info vpxd[04654] [Originator@6876 sub=vpxLro opID=47a68484] [VpxLRO] -- BEGIN lro-3955382 -- SessionManager -- vim.SessionManager.loginByToken -- 52619c4a-e700-ece6-ba71-c0e100499423
2020-07-23T19:29:45.922Z verbose vpxd[04654] [Originator@6876 sub=Vmacore::Xml::Security opID=47a68484] Verification of signature Reference URI: `#_5bb6fc36-c99c-4ce9-bc85-f0645fac85c4' ; is-valid: true
2020-07-23T19:29:45.922Z verbose vpxd[04654] [Originator@6876 sub=Vmacore::Xml::Security opID=47a68484] Missing reference count: 0
2020-07-23T19:29:45.922Z verbose vpxd[04654] [Originator@6876 sub=Vmacore::Xml::Security opID=47a68484] Verification of signature SignedInfo: is-valid: true
2020-07-23T19:29:45.922Z verbose vpxd[04654] [Originator@6876 sub=Vmacore::Xml::Security opID=47a68484] Verification of signature Reference URI: `#wssu-timestamp' ; is-valid: true
2020-07-23T19:29:45.922Z verbose vpxd[04654] [Originator@6876 sub=Vmacore::Xml::Security opID=47a68484] Verification of signature Reference URI: `#body' ; is-valid: true
2020-07-23T19:29:45.922Z verbose vpxd[04654] [Originator@6876 sub=Vmacore::Xml::Security opID=47a68484] Missing reference count: 0
2020-07-23T19:29:45.922Z verbose vpxd[04654] [Originator@6876 sub=Default opID=47a68484] Found confirmation cert: 'OU=mID-babc4424-167d-4e16-9f78-6e66378b9914,C=US,DC=vsphere.sso,DC=mia,CN=vpxd-extension'
2020-07-23T19:29:45.922Z verbose vpxd[04654] [Originator@6876 sub=Vmacore::Xml::Security opID=47a68484] Verification of signature SignedInfo: is-valid: true
2020-07-23T19:29:45.922Z verbose vpxd[04654] [Originator@6876 sub=Default opID=47a68484] Found security token in request message
2020-07-23T19:29:45.923Z verbose vpxd[04654] [Originator@6876 sub=[SSO] opID=47a68484] [UserDirectorySso] NormalizeUserName(mia.vsphere.sso\vpxd-extension-babc4424-167d-4e16-9f78-6e66378b9914, false)
2020-07-23T19:29:45.923Z verbose vpxd[04654] [Originator@6876 sub=[SSO] opID=47a68484] [UserDirectorySso] GetUserInfo(mia.vsphere.sso\vpxd-extension-babc4424-167d-4e16-9f78-6e66378b9914, false) cached res: MIA.VSPHERE.SSO\vpxd-extension-babc4424-167d-4e16-9f78-6e66378b9914
2020-07-23T19:29:45.923Z verbose vpxd[04654] [Originator@6876 sub=[SSO] opID=47a68484] [UserDirectorySso] NormalizeUserName(mia.vsphere.sso\vpxd-extension-babc4424-167d-4e16-9f78-6e66378b9914, false) res: MIA.VSPHERE.SSO\vpxd-extension-babc4424-167d-4e16-9f78-6e66378b9914
2020-07-23T19:29:45.923Z verbose vpxd[04654] [Originator@6876 sub=[SSO] opID=47a68484] [UserDirectorySso] First/last name extracted from token
2020-07-23T19:29:45.923Z verbose vpxd[04654] [Originator@6876 sub=[SSO] opID=47a68484] [UserDirectorySso] GetUserFullName(AuthTokenHelper) res:
2020-07-23T19:29:45.923Z verbose vpxd[04654] [Originator@6876 sub=User opID=47a68484] [VpxdUser] Registering session with cnxId=52619c4a-e700-ece6-ba71-c0e100499423
2020-07-23T19:29:45.923Z verbose vpxd[04654] [Originator@6876 sub=PropertyProvider opID=47a68484] RecordOp ADD: sessionList["52393bf2-1e66-e23c-affa-9f37d0903764"], SessionManager. Applied change to temp map.
2020-07-23T19:29:45.923Z verbose vpxd[04654] [Originator@6876 sub=Vmomi opID=47a68484] Invoke done: vim.SessionManager.loginByToken session: 52619c4a-e700-ece6-ba71-c0e100499423
2020-07-23T19:29:45.923Z info vpxd[04654] [Originator@6876 sub=vpxLro opID=47a68484] [VpxLRO] -- FINISH lro-3955382
2020-07-23T19:29:45.923Z verbose vpxd[04597] [Originator@6876 sub=Vmomi opID=76374bb] Invoke done: vmodl.query.PropertyCollector.waitForUpdatesEx session: 52680fb6-3881-803b-11ee-e5ae830ab454
2020-07-23T19:29:45.924Z verbose vpxd[04654] [Originator@6876 sub=Vmomi opID=2f25b009] Invoke done: vmodl.query.PropertyCollector.waitForUpdatesEx session: 52941fb6-6976-be5f-e7e6-31057958de82
2020-07-23T19:29:45.925Z verbose vpxd[04694] [Originator@6876 sub=Default opID=5461adc] [VpxVmomi] Invoking [queryView] on [vim.option.OptionManager:VpxSettings] session [52619c4a-e700-ece6-ba71-c0e100499423(52393bf2-1e66-e23c-affa-9f37d0903764)]
2020-07-23T19:29:45.925Z info vpxd[04694] [Originator@6876 sub=vpxLro opID=5461adc] [VpxLRO] -- BEGIN lro-3955383 -- VpxSettings -- vim.option.OptionManager.queryView -- 52619c4a-e700-ece6-ba71-c0e100499423(52393bf2-1e66-e23c-affa-9f37d0903764)
2020-07-23T19:29:45.925Z verbose vpxd[04694] [Originator@6876 sub=Vmomi opID=5461adc] Invoke done: vim.option.OptionManager.queryView session: 52619c4a-e700-ece6-ba71-c0e100499423
2020-07-23T19:29:45.925Z info vpxd[04694] [Originator@6876 sub=vpxLro opID=5461adc] [VpxLRO] -- FINISH lro-3955383
2020-07-23T19:29:45.927Z verbose vpxd[31872] [Originator@6876 sub=Default opID=3d8d6438] [VpxVmomi] Invoking [waitForUpdatesEx] on [vmodl.query.PropertyCollector:propertyCollector] session [52680fb6-3881-803b-11ee-e5ae830ab454(5239a4e3-b32d-1f66-d5d2-d5b32bbb86c6)]
2020-07-23T19:29:45.927Z verbose vpxd[04756] [Originator@6876 sub=Default opID=daeb81a] [VpxVmomi] Invoking [logout] on [vim.SessionManager:SessionManager] session [52619c4a-e700-ece6-ba71-c0e100499423(52393bf2-1e66-e23c-affa-9f37d0903764)]
2020-07-23T19:29:45.927Z verbose vpxd[63793] [Originator@6876 sub=Default opID=49b8b9fa] [VpxVmomi] Invoking [waitForUpdatesEx] on [vmodl.query.PropertyCollector:session[52941fb6-6976-be5f-e7e6-31057958de82]52df123c-0819-bcfc-7235-555d45a3604e] session [52941fb6-6976-be5f-e7e6-31057958de82(52460c19-be10-aff1-3a78-fd56b89e4512)]
2020-07-23T19:29:45.927Z info vpxd[04756] [Originator@6876 sub=vpxLro opID=daeb81a] [VpxLRO] -- BEGIN lro-3955384 -- SessionManager -- vim.SessionManager.logout -- 52619c4a-e700-ece6-ba71-c0e100499423(52393bf2-1e66-e23c-affa-9f37d0903764)
2020-07-23T19:29:45.927Z verbose vpxd[04756] [Originator@6876 sub=Default opID=daeb81a] CloseSession called for session id=52619c4a-e700-ece6-ba71-c0e100499423
2020-07-23T19:29:45.927Z verbose vpxd[04756] [Originator@6876 sub=PropertyProvider opID=daeb81a] RecordOp REMOVE: sessionList["52393bf2-1e66-e23c-affa-9f37d0903764"], SessionManager. Applied change to temp map.
2020-07-23T19:29:45.927Z verbose vpxd[04756] [Originator@6876 sub=Vmomi opID=daeb81a] Invoke done: vim.SessionManager.logout session: 52619c4a-e700-ece6-ba71-c0e100499423
2020-07-23T19:29:45.927Z info vpxd[04756] [Originator@6876 sub=vpxLro opID=daeb81a] [VpxLRO] -- FINISH lro-3955384
2020-07-23T19:29:45.927Z verbose vpxd[63793] [Originator@6876 sub=Vmomi opID=3d8d6438] Invoke done: vmodl.query.PropertyCollector.waitForUpdatesEx session: 52680fb6-3881-803b-11ee-e5ae830ab454
2020-07-23T19:29:45.928Z verbose vpxd[04756] [Originator@6876 sub=Vmomi opID=49b8b9fa] Invoke done: vmodl.query.PropertyCollector.waitForUpdatesEx session: 52941fb6-6976-be5f-e7e6-31057958de82
2020-07-23T19:29:45.934Z verbose vpxd[04744] [Originator@6876 sub=Default opID=7eb9f1e5] [VpxVmomi] Invoking [waitForUpdatesEx] on [vmodl.query.PropertyCollector:propertyCollector] session [52680fb6-3881-803b-11ee-e5ae830ab454(5239a4e3-b32d-1f66-d5d2-d5b32bbb86c6)]
2020-07-23T19:29:45.946Z verbose vpxd[04678] [Originator@6876 sub=Default opID=61b83262] [VpxVmomi] Invoking [waitForUpdatesEx] on [vmodl.query.PropertyCollector:session[52941fb6-6976-be5f-e7e6-31057958de82]52df123c-0819-bcfc-7235-555d45a3604e] session [52941fb6-6976-be5f-e7e6-31057958de82(52460c19-be10-aff1-3a78-fd56b89e4512)]