Jump to content
Updated Privacy Statement
  • 0

[ERROR] [47] - CommandQueue.GetRegularMessages: CbpVersion: UnInitialized not CBPv1_5, ignoring call to GetRegularMessages

Henrik Skoog

Asked by Henrik Skoog,

Question

7 answers to this question

Recommended Posts

  • 0
John Salvo Contributor

John Salvo

Posted
Posted

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 Contributor

John Salvo

Posted
Posted
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
John Salvo Contributor

John Salvo

Posted
Posted
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

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 account

Sign in

Already have an account? Sign in here.

Sign In Now
Go to question listing

© Cloud Software Group, Inc. All rights reserved.

×
×
  • Create New...