Henrik Skoog Posted December 20, 2018 Share Posted December 20, 2018 When installing VDA 7.18 (1811) on RHEL 7.5 i get: [ERROR] [47] - CommandQueue.GetRegularMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetRegularMessages in vda.log Everything looks fine, but i cannot find out whats causing this problem. Link to comment
0 Jigao Huang Posted December 21, 2018 Share Posted December 21, 2018 Something is wrong with vda registration. For further analysis, please follow the guide(https://support.citrix.com/article/CTX220130) to set the log level of ctxvda to trace, collect the vda.log which records the whole process of this issue and send it to Citrix Tech Support. Link to comment
0 Henrik Skoog Posted January 28, 2019 Author Share Posted January 28, 2019 It was because of RHEL7.6 instead of 7.5. Installation with 7.5 worked. Thanx Link to comment
0 John Salvo Posted March 14, 2019 Share Posted March 14, 2019 I am getting this too, but I am running RHEL 7.5. The LVDA is currently only soft-registered .... because the Citrix engineers have not created a machine catalog yet ( could that be the reason ? ). Anyway, I have also enabled trace log in vda.log, and here is what I have: 2019-03-14 19:30:31.747 [TRACE] [37] - RegistrationManager.AttemptRegistrationWithSingleDdc: >>>>>>>>>>>>> REGISTERED REGISTERED - aalxndsyd102.corp.aal.au - (Soft) - (CBPv1_5) - REGISTERED REGISTERED <<<<<<<<<<<<< 2019-03-14 19:30:31.747 [TRACE] [37] - RegistrationManager.AttemptRegistrationWithSingleDdc: Calling registration event with Registered event 2019-03-14 19:30:31.747 [TRACE] [37] - RegistryKey.OpenSubkey: open writable, key is 'VirtualDesktopAgent', subkey is 'State2' 2019-03-14 19:30:31.751 [TRACE] [37] - CommandQueue.RegistrationEventHandler: Entry, registrationEvent: RegisteredSoft 2019-03-14 19:30:31.751 [TRACE] [37] - CommandQueue.RegistrationEventHandler: Soft-registered 2019-03-14 19:30:31.751 [TRACE] [37] - CommandQueue.RegistrationEventHandler: Exit 2019-03-14 19:30:31.751 [TRACE] [37] - PluginManager.RegistrationEventHandler: Enter, registrationEvent: RegisteredSoft 2019-03-14 19:30:31.751 [TRACE] [37] - PluginManager.RegistrationEventHandler: Exit 2019-03-14 19:30:31.751 [TRACE] [37] - PluginManager.NotifyRegistrationEvent: Enter 2019-03-14 19:30:31.751 [TRACE] [37] - PluginManager.NotifyRegistrationEvent: Exit 2019-03-14 19:30:31.751 [TRACE] [35] - PluginManager.NotifyPlugins: Enter 2019-03-14 19:30:31.752 [TRACE] [37] - NotificationManager.RegistrationEventHandler: Entry (RegistrationEvent:RegisteredSoft, SessionMode:SingleSession, CbpVersion:CBPv1_5) 2019-03-14 19:30:31.752 [TRACE] [35] - PluginManager.NotifyPlugins: Registration Notification with event: Registered 2019-03-14 19:30:31.752 [TRACE] [35] - PluginManager.NotifyPlugins: Exit 2019-03-14 19:30:31.752 [TRACE] [37] - LaunchManager.RegistrationEventHandler: Enter 2019-03-14 19:30:31.752 [TRACE] [37] - LaunchManager.RegistrationEventHandler: Ignoring registration event: RegisteredSoft, Session Mode UnInitialized 2019-03-14 19:30:31.752 [TRACE] [37] - LaunchManager.RegistrationEventHandler: Exit, registration event = RegisteredSoft 2019-03-14 19:30:31.752 [INFO ] [37] - The Citrix Desktop Service successfully registered with delivery controller aalxndsyd102.corp.aal.au (IP Address 10.197.90.101). The endpoint address of the controller is http://aalxndsyd102.corp.aal.au:80/Citrix/CdsController/IRegistrar. 2019-03-14 19:30:31.752 [TRACE] [37] - RegistrationManager.AttemptRegistrationWithSingleDdc: Exit, registration attempt result: true 2019-03-14 19:30:31.752 [TRACE] [37] - ###,CBPv1_5#S-1-5-21-1659004503-1563985344-839522115-149043#Cbp.IRegistrar.Register#MultiSession#Soft#STARTUP#aalxndsyd102.corp.aal.au#true 2019-03-14 19:30:31.752 [TRACE] [37] - CDE: Broker Agent Register [Success]: Registered DDC: aalxndsyd102.corp.aal.au 2019-03-14 19:30:31.752 [TRACE] [37] - VdaState.RegisterVdaStateInfo: Enter - registeredMode Soft 2019-03-14 19:30:31.775 [TRACE] [37] - RegistrationManager.AttemptRegistration: Exit 2019-03-14 19:30:36.747 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping now. First ping. 2019-03-14 19:30:36.747 [TRACE] [48] - CDE: WorkerState change: New RDS worker state -> Available Last reported RDS worker state -> LoadManagementInitializing 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetUrgentMessages: Entry 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetUrgentMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetUrgentMessages 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetUrgentMessages: Exit 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetRegularMessages: Entry 2019-03-14 19:30:36.748 [ERROR] [48] - CommandQueue.GetRegularMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetRegularMessages 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetRegularMessages: Exit 2019-03-14 19:30:36.749 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 WorkerState = Available, RdsMaintenanceMode = Enable, time since last ping 5000 , Command Queue Length 0 2019-03-14 19:30:36.749 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 WorkerState = Available, RdsMaintenanceMode = Enable, time since last ping 5000 , Command Queue Length 0, Lhc Active No, Is Lhc Active and sending Command Queue Data? No 2019-03-14 19:30:36.749 [TRACE] [48] - CDE: Ping2Now, Reason -> DoPing2FirstTime 2019-03-14 19:30:36.769 [TRACE] [48] - RequestURI: http://10.197.90.101/Citrix/CdsController/IRegistrar.Ping2, duration: 12ms 2019-03-14 19:30:36.781 [TRACE] [48] - CDE: Ping2Sent, Result -> true 2019-03-14 19:30:36.781 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 minHeartbeatPeriod = 30000, maxHeartBeatPeriod 300000.0 2019-03-14 19:30:36.781 [TRACE] [48] - VdaState.UpdateImageOutOfDateVdaStateInfo: Enter - ImageOutOfDate false 2019-03-14 19:30:36.785 [TRACE] [48] - ###,CBPv1_5#S-1-5-21-1659004503-1563985344-839522115-149043#Cbp.IRegistrar.Ping2#Available#DoPing2FirstTime#Enable#-1#2#true Link to comment
0 John Salvo Posted March 14, 2019 Share Posted March 14, 2019 7 minutes ago, John Salvo said: I am getting this too, but I am running RHEL 7.5. The LVDA is currently only soft-registered .... because the Citrix engineers have not created a machine catalog yet ( could that be the reason ? ). Anyway, I have also enabled trace log in vda.log, and here is what I have: 2019-03-14 19:30:31.747 [TRACE] [37] - RegistrationManager.AttemptRegistrationWithSingleDdc: >>>>>>>>>>>>> REGISTERED REGISTERED - aalxndsyd102.corp.aal.au - (Soft) - (CBPv1_5) - REGISTERED REGISTERED <<<<<<<<<<<<< 2019-03-14 19:30:31.747 [TRACE] [37] - RegistrationManager.AttemptRegistrationWithSingleDdc: Calling registration event with Registered event 2019-03-14 19:30:31.747 [TRACE] [37] - RegistryKey.OpenSubkey: open writable, key is 'VirtualDesktopAgent', subkey is 'State2' 2019-03-14 19:30:31.751 [TRACE] [37] - CommandQueue.RegistrationEventHandler: Entry, registrationEvent: RegisteredSoft 2019-03-14 19:30:31.751 [TRACE] [37] - CommandQueue.RegistrationEventHandler: Soft-registered 2019-03-14 19:30:31.751 [TRACE] [37] - CommandQueue.RegistrationEventHandler: Exit 2019-03-14 19:30:31.751 [TRACE] [37] - PluginManager.RegistrationEventHandler: Enter, registrationEvent: RegisteredSoft 2019-03-14 19:30:31.751 [TRACE] [37] - PluginManager.RegistrationEventHandler: Exit 2019-03-14 19:30:31.751 [TRACE] [37] - PluginManager.NotifyRegistrationEvent: Enter 2019-03-14 19:30:31.751 [TRACE] [37] - PluginManager.NotifyRegistrationEvent: Exit 2019-03-14 19:30:31.751 [TRACE] [35] - PluginManager.NotifyPlugins: Enter 2019-03-14 19:30:31.752 [TRACE] [37] - NotificationManager.RegistrationEventHandler: Entry (RegistrationEvent:RegisteredSoft, SessionMode:SingleSession, CbpVersion:CBPv1_5) 2019-03-14 19:30:31.752 [TRACE] [35] - PluginManager.NotifyPlugins: Registration Notification with event: Registered 2019-03-14 19:30:31.752 [TRACE] [35] - PluginManager.NotifyPlugins: Exit 2019-03-14 19:30:31.752 [TRACE] [37] - LaunchManager.RegistrationEventHandler: Enter 2019-03-14 19:30:31.752 [TRACE] [37] - LaunchManager.RegistrationEventHandler: Ignoring registration event: RegisteredSoft, Session Mode UnInitialized 2019-03-14 19:30:31.752 [TRACE] [37] - LaunchManager.RegistrationEventHandler: Exit, registration event = RegisteredSoft 2019-03-14 19:30:31.752 [INFO ] [37] - The Citrix Desktop Service successfully registered with delivery controller aalxndsyd102.corp.aal.au (IP Address 10.197.90.101). The endpoint address of the controller is http://aalxndsyd102.corp.aal.au:80/Citrix/CdsController/IRegistrar. 2019-03-14 19:30:31.752 [TRACE] [37] - RegistrationManager.AttemptRegistrationWithSingleDdc: Exit, registration attempt result: true 2019-03-14 19:30:31.752 [TRACE] [37] - ###,CBPv1_5#S-1-5-21-1659004503-1563985344-839522115-149043#Cbp.IRegistrar.Register#MultiSession#Soft#STARTUP#aalxndsyd102.corp.aal.au#true 2019-03-14 19:30:31.752 [TRACE] [37] - CDE: Broker Agent Register [Success]: Registered DDC: aalxndsyd102.corp.aal.au 2019-03-14 19:30:31.752 [TRACE] [37] - VdaState.RegisterVdaStateInfo: Enter - registeredMode Soft 2019-03-14 19:30:31.775 [TRACE] [37] - RegistrationManager.AttemptRegistration: Exit 2019-03-14 19:30:36.747 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping now. First ping. 2019-03-14 19:30:36.747 [TRACE] [48] - CDE: WorkerState change: New RDS worker state -> Available Last reported RDS worker state -> LoadManagementInitializing 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetUrgentMessages: Entry 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetUrgentMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetUrgentMessages 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetUrgentMessages: Exit 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetRegularMessages: Entry 2019-03-14 19:30:36.748 [ERROR] [48] - CommandQueue.GetRegularMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetRegularMessages 2019-03-14 19:30:36.748 [TRACE] [48] - CommandQueue.GetRegularMessages: Exit 2019-03-14 19:30:36.749 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 WorkerState = Available, RdsMaintenanceMode = Enable, time since last ping 5000 , Command Queue Length 0 2019-03-14 19:30:36.749 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 WorkerState = Available, RdsMaintenanceMode = Enable, time since last ping 5000 , Command Queue Length 0, Lhc Active No, Is Lhc Active and sending Command Queue Data? No 2019-03-14 19:30:36.749 [TRACE] [48] - CDE: Ping2Now, Reason -> DoPing2FirstTime 2019-03-14 19:30:36.769 [TRACE] [48] - RequestURI: http://10.197.90.101/Citrix/CdsController/IRegistrar.Ping2, duration: 12ms 2019-03-14 19:30:36.781 [TRACE] [48] - CDE: Ping2Sent, Result -> true 2019-03-14 19:30:36.781 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 minHeartbeatPeriod = 30000, maxHeartBeatPeriod 300000.0 2019-03-14 19:30:36.781 [TRACE] [48] - VdaState.UpdateImageOutOfDateVdaStateInfo: Enter - ImageOutOfDate false 2019-03-14 19:30:36.785 [TRACE] [48] - ###,CBPv1_5#S-1-5-21-1659004503-1563985344-839522115-149043#Cbp.IRegistrar.Ping2#Available#DoPing2FirstTime#Enable#-1#2#true ... and more: 2019-03-14 19:34:59.007 [TRACE] [14] - VdaMetaData.SessionMetaDataAuditMethod - Waking up... 2019-03-14 19:34:59.007 [TRACE] [14] - VdaMetaData.SessionMetaDataAuditMethod - Session MetaData Registry does not exist! 2019-03-14 19:35:36.786 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping now. Max heartbeat exceeded. 2019-03-14 19:35:36.786 [TRACE] [48] - CommandQueue.GetUrgentMessages: Entry 2019-03-14 19:35:36.786 [TRACE] [48] - CommandQueue.GetUrgentMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetUrgentMessages 2019-03-14 19:35:36.786 [TRACE] [48] - CommandQueue.GetUrgentMessages: Exit 2019-03-14 19:35:36.786 [TRACE] [48] - CommandQueue.GetRegularMessages: Entry 2019-03-14 19:35:36.786 [ERROR] [48] - CommandQueue.GetRegularMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetRegularMessages 2019-03-14 19:35:36.786 [TRACE] [48] - CommandQueue.GetRegularMessages: Exit 2019-03-14 19:35:36.786 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 WorkerState = Available, RdsMaintenanceMode = Enable, time since last ping 300037 , Command Queue Length 0 2019-03-14 19:35:36.786 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 WorkerState = Available, RdsMaintenanceMode = Enable, time since last ping 300037 , Command Queue Length 0, Lhc Active No, Is Lhc Active and sending Command Queue Data? No 2019-03-14 19:35:36.786 [TRACE] [48] - CDE: Ping2Now, Reason -> DoPing2MaxPeriod 2019-03-14 19:35:36.802 [TRACE] [48] - RequestURI: http://10.197.90.101/Citrix/CdsController/IRegistrar.Ping2, duration: 11ms 2019-03-14 19:35:36.810 [TRACE] [48] - CDE: Ping2Sent, Result -> true 2019-03-14 19:35:36.810 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 minHeartbeatPeriod = 30000, maxHeartBeatPeriod 300000.0 2019-03-14 19:35:36.810 [TRACE] [48] - VdaState.UpdateImageOutOfDateVdaStateInfo: Enter - ImageOutOfDate false 2019-03-14 19:35:36.814 [TRACE] [48] - ###,CBPv1_5#S-1-5-21-1659004503-1563985344-839522115-149043#Cbp.IRegistrar.Ping2#Available#DoPing2MaxPeriod#Enable#-1#2#true 2019-03-14 19:35:58.993 [TRACE] [13] - FASProxyServer.monitorChannel probe 2019-03-14 19:35:58.993 [TRACE] [13] - FASProxyServer: probe 0 Fas Services. 2019-03-14 19:36:58.993 [TRACE] [13] - FASProxyServer.monitorChannel probe 2019-03-14 19:36:58.993 [TRACE] [13] - FASProxyServer: probe 0 Fas Services. 2019-03-14 19:37:58.993 [TRACE] [13] - FASProxyServer.monitorChannel probe 2019-03-14 19:37:58.993 [TRACE] [13] - FASProxyServer: probe 0 Fas Services. 2019-03-14 19:38:58.993 [TRACE] [13] - FASProxyServer.monitorChannel probe 2019-03-14 19:38:58.993 [TRACE] [13] - FASProxyServer: probe 0 Fas Services. 2019-03-14 19:39:58.993 [TRACE] [13] - FASProxyServer.monitorChannel probe 2019-03-14 19:39:58.993 [TRACE] [13] - FASProxyServer: probe 0 Fas Services. 2019-03-14 19:39:59.007 [TRACE] [14] - CommandQueue.CommandQueueCleanupMethod: Entry 2019-03-14 19:39:59.007 [TRACE] [14] - CommandQueue.CommandQueueCleanupMethod: Exit 2019-03-14 19:39:59.007 [TRACE] [14] - MonitorManager.MonitorManagementAuditMethod: Waking up... 2019-03-14 19:39:59.007 [TRACE] [14] - StackManager.EnumerateSessionKeys: Enter 2019-03-14 19:39:59.008 [TRACE] [14] - *#*#,Stack.EnumerateSessionKeys##true#true 2019-03-14 19:39:59.008 [TRACE] [14] - VdaMetaData.SessionMetaDataAuditMethod - Waking up... 2019-03-14 19:39:59.009 [TRACE] [14] - VdaMetaData.SessionMetaDataAuditMethod - Session MetaData Registry does not exist! 2019-03-14 19:40:36.815 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping now. Max heartbeat exceeded. 2019-03-14 19:40:36.815 [TRACE] [48] - CommandQueue.GetUrgentMessages: Entry 2019-03-14 19:40:36.815 [TRACE] [48] - CommandQueue.GetUrgentMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetUrgentMessages 2019-03-14 19:40:36.815 [TRACE] [48] - CommandQueue.GetUrgentMessages: Exit 2019-03-14 19:40:36.815 [TRACE] [48] - CommandQueue.GetRegularMessages: Entry 2019-03-14 19:40:36.815 [ERROR] [48] - CommandQueue.GetRegularMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetRegularMessages 2019-03-14 19:40:36.815 [TRACE] [48] - CommandQueue.GetRegularMessages: Exit 2019-03-14 19:40:36.815 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 WorkerState = Available, RdsMaintenanceMode = Enable, time since last ping 300029 , Command Queue Length 0 2019-03-14 19:40:36.815 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 WorkerState = Available, RdsMaintenanceMode = Enable, time since last ping 300029 , Command Queue Length 0, Lhc Active No, Is Lhc Active and sending Command Queue Data? No 2019-03-14 19:40:36.815 [TRACE] [48] - CDE: Ping2Now, Reason -> DoPing2MaxPeriod 2019-03-14 19:40:36.832 [TRACE] [48] - RequestURI: http://10.197.90.101/Citrix/CdsController/IRegistrar.Ping2, duration: 12ms 2019-03-14 19:40:36.840 [TRACE] [48] - CDE: Ping2Sent, Result -> true 2019-03-14 19:40:36.840 [TRACE] [48] - AgentHeartBeatCBPv1_5.SendHeartbeatCbpV15Thread: Ping2 minHeartbeatPeriod = 30000, maxHeartBeatPeriod 300000.0 2019-03-14 19:40:36.840 [TRACE] [48] - VdaState.UpdateImageOutOfDateVdaStateInfo: Enter - ImageOutOfDate false 2019-03-14 19:40:36.843 [TRACE] [48] - ###,CBPv1_5#S-1-5-21-1659004503-1563985344-839522115-149043#Cbp.IRegistrar.Ping2#Available#DoPing2MaxPeriod#Enable#-1#2#true So it seems like it is the ping generating this ERROR message ??? Link to comment
0 Henrik Skoog Posted March 14, 2019 Author Share Posted March 14, 2019 This is probably caused by the lack of registration. I also got the error before my VDA:s was added to catalogue. Link to comment
0 John Salvo Posted March 14, 2019 Share Posted March 14, 2019 1 minute ago, Henrik Skoog said: This is probably caused by the lack of registration. I also got the error before my VDA:s was added to catalogue. Thanks. I will wait then for our Citrix engineers to create the machine catalog for this LVDA so that it is not just soft-registered anymore. I am not a Citrix engineer, but I do a lot of Linux work, so I did all the changes for the RHEL7 VM ... just waiting for them to complete their side. Will update when it has been done. Link to comment
0 Shweta Kabade Posted June 6, 2019 Share Posted June 6, 2019 I am getting the same error. I am having Linux 7 VM . Its showing unregistered in Delivery Controller . Facing this issue only for one VM. other VMs are registered properly. Link to comment
Question
Henrik Skoog
When installing VDA 7.18 (1811) on RHEL 7.5 i get:
[ERROR] [47] - CommandQueue.GetRegularMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetRegularMessages
in vda.log
Everything looks fine, but i cannot find out whats causing this problem.
Link to comment
7 answers to this question
Recommended Posts
Create an account or sign in to comment
You need to be a member in order to leave a comment
Create an account
Sign up for a new account in our community. It's easy!
Register a new accountSign in
Already have an account? Sign in here.
Sign In Now