CVP Courtesy Callback Failing

[Ahmad Nizami]

Hi

I have confiured CCB on CVP 9.0.1. Able to dial into CCB ok however after i select to be called back and leave my number nothing happens. Looked through activity and error logs on all applications and found following errors on 'CallbackQueue' app;

                 

172.22.100.23.1375354715803.16.CallbackQueue,08/01/2013 11:58:40.062,An error occurred in the on end call class com.cisco.cvp.vxml.custelem.callback.LeaveQueue. The error was: onEndCall - "Error: GUID is not in queue table" returned from sending Callback_Leave_Queue request to CallbackServlet
com.audium.server.AudiumException: onEndCall - "Error: GUID is not in queue table" returned from sending Callback_Leave_Queue request to CallbackServlet
at com.cisco.cvp.vxml.custelem.callback.LeaveQueue.onEndCall(LeaveQueue.java:93)
at com.audium.server.controller.Controller.runOnEndCall(Controller.java:3367)
at com.audium.server.controller.Controller.onEndCall(Controller.java:3257)
at com.audium.server.controller.Controller.handleActionConditions(Controller.java:1267)
at com.audium.server.controller.Controller.actionRequest(Controller.java:1239)
at com.audium.server.controller.Controller.doPost(Controller.java:610)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:647)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:172)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:174)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:879)
at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)
at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)
at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689)
at java.lang.Thread.run(Thread.java:662)

Any idea what is this about?

Regards,

Ahmad

[Senthil Kumar Sankar]

Hello Ahmad,

We may need to look into the Reporting Server Logs for this call. Can you cross verify whether you have configured the Allowed Dialed Numbers correctly ? Can you copy the complete log sets here ?

Regards,

Senthil

[Ahmad Nizami]

Thanks Senthilkumar

I have double checked Allowed DN and its OK.

Copying from reporting server logs file:

8: Aug 06 2013 17:06:23.259 +0100: %CVP_9_0_RPT-7-NewCall HANDLER:  {Thrd=Thread-24}  

136985: 172.22.100.28: Aug 06 2013 17:06:23.259 +0100: %CVP_9_0_RPT-7-createNewCallEvent:  {Thrd=Thread-24}  

136986: 172.22.100.28: Aug 06 2013 17:06:23.867 +0100: %CVP_9_0_RPT-7-NewCall HANDLER:  {Thrd=Thread-24}  

136987: 172.22.100.28: Aug 06 2013 17:06:23.867 +0100: %CVP_9_0_RPT-7-createNewCallEvent:  {Thrd=Thread-24}  

136988: 172.22.100.28: Aug 06 2013 17:06:24.195 +0100: %CVP_9_0_RPT-7-createNewCallEvent:  {Thrd=Thread-24}  

136989: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} EnterQueueHandler:validate: validate guid=004C1C97FDE911E28192F78DF697AB03 icmewt=100 

136990: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} EnterQueueHandler:EnterQueueHandler.exec: EnterQueueHandler CALLGUID=004C1C97FDE911E28192F78DF697AB03 CallStartDate=Tue Aug 06 17:06:36 BST 2013 

136991: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} EnterQueue CALLGUID=004C1C97FDE911E28192F78DF697AB03 QueueName=billing ani=901908666555 

136992: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} QueueStats putEntry: 004C1C97FDE911E28192F78DF697AB03 

136993: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} JdbcCallbackQueueDAO:store: Request to store CallbackQueue record. CallGUID=004C1C97FDE911E28192F78DF697AB03 CallbackQueueDTO=CallbackQueueDTO::' SurrogateId: '-1' QueueName: 'billing' QueueId: '-1' DbDateTime: 'null' QueueStatus: '0' ValidationStatus: '0' EnterDateTime: 'Tue Aug 06 17:06:24 BST 2013' LeaveDateTime: 'null' CVPEstimatedWaitTime: '0' ICMEstimatedWaitTime: '100' CallStartDate: 'Tue Aug 06 17:06:24 BST 2013' 

136994: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} JdbcCallbackQueueDAO:store: Responded with retCode: 0 to request to store CallbackQueue record CALLGUID=004C1C97FDE911E28192F78DF697AB03 

136995: 172.22.100.28: Aug 06 2013 17:06:24.476 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor20} ValidateHandler:ValidateHandler.exec: ValidateHandler GUID=004C1C97FDE911E28192F78DF697AB03 

136996: 172.22.100.28: Aug 06 2013 17:06:24.476 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor20} ValidateHandler:ValidateHandler.exec: ValidateHandler GUID=004C1C97FDE911E28192F78DF697AB03 refresh 

136997: 172.22.100.28: Aug 06 2013 17:06:24.476 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor23} SetQueueDefaultsHandler:SetQueueDefaultsHandler.exec: SetQueueDefaultsHandler QueueName=billing 

136998: 172.22.100.28: Aug 06 2013 17:06:24.491 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} ValidateHandler:ValidateHandler.exec: ValidateHandler GUID=004C1C97FDE911E28192F78DF697AB03 

136999: 172.22.100.28: Aug 06 2013 17:06:24.491 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackDAO:isExceededCapacity: Request to check if exceeded capacity in Callback. Gateway=172.22.100.252 NumOfGatewayAllowed=2 inteval=60 

137000: 172.22.100.28: Aug 06 2013 17:06:24.507 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackDAO:isExceededCapacity: Returning false for query on whether capacity is exceeded. Input parameters: Gateway=172.22.100.252 NumberOfGatewayCallbacksAllowed=2 inteval=60 

137001: 172.22.100.28: Aug 06 2013 17:06:24.507 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackQueueDAO:updateValidationStatus: Request to update validation status in CallbackQueue. CallGUID=004C1C97FDE911E28192F78DF697AB03 validationStatus=2 

137002: 172.22.100.28: Aug 06 2013 17:06:24.507 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackQueueDAO:updateValidationStatus: Validation status of 1 rows were updated in CallbackQueue. CALLGUID=004C1C97FDE911E28192F78DF697AB03 

137003: 172.22.100.28: Aug 06 2013 17:06:24.507 +0100: %CVP_9_0_RPT-7-LOW_LEVEL:  {Thrd=http-8000-Processor25} ValidateHandler:ValidateHandler.exec: ValidateHandler GUID=004C1C97FDE911E28192F78DF697AB03 results:preemptive validation status bitmask=0x00000003 

137004: 172.22.100.28: Aug 06 2013 17:06:27.736 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=CallbackTimerThread} QueueStats:recalculateRemainingWaitTimes: recalculateRemainingWaitTimes CALLGUID=004C1C97FDE911E28192F78DF697AB03 queuePos=0 dqRateA=100 timeInFirstPlace(Secs)=4 reconnectTime=30 SLA_time=60 RemainingTime=96 

137005: 172.22.100.28: Aug 06 2013 17:06:37.876 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=CallbackTimerThread} QueueStats:recalculateRemainingWaitTimes: recalculateRemainingWaitTimes CALLGUID=004C1C97FDE911E28192F78DF697AB03 queuePos=0 dqRateA=100 timeInFirstPlace(Secs)=14 reconnectTime=30 SLA_time=60 RemainingTime=86 

137006: 172.22.100.28: Aug 06 2013 17:06:48.016 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=CallbackTimerThread} QueueStats:recalculateRemainingWaitTimes: recalculateRemainingWaitTimes CALLGUID=004C1C97FDE911E28192F78DF697AB03 queuePos=0 dqRateA=100 timeInFirstPlace(Secs)=24 reconnectTime=30 SLA_time=60 RemainingTime=76 

137007: 172.22.100.28: Aug 06 2013 17:06:49.062 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor24} AddCallbackHandler:AddCallbackHandler.exec: AddCallbackHandler CALLGUID=004C1C97FDE911E28192F78DF697AB03 

137008: 172.22.100.28: Aug 06 2013 17:06:49.062 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor24} CALLGUID=004C1C97FDE911E28192F78DF697AB03 Callback number 901908666555 is allowed - [CCB DN Pattern: 90> : Allow] 

137009: 172.22.100.28: Aug 06 2013 17:06:49.062 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor24} JdbcCallbackDAO:store: Request to store Callback record. CallbackDTO=CallbackDTO::  SurrogateId: '-1' CallGUID: '004C1C97FDE911E28192F78DF697AB03' ANI: '901908666555' DbDateTime: 'null' EventTypeId: '21' CauseId: '0' CallBackType: 'p' OldGUID: 'null' Gateway: '172.22.100.252' Location: 'HCS' NbrAttempts: '0' ScheduledCallBackDateTime: 'null' ScheduledCallBackDN: 'null' CallStartDate: 'Tue Aug 06 17:06:24 BST 2013' RecordingURL: 'http://172.22.100.23:7000/CVP/audio/RecordedName.wav' QueueName: 'null' eventDateTime: Tue Aug 06 17:06:24 BST 2013 

137010: 172.22.100.28: Aug 06 2013 17:06:49.062 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor24} JdbcCallbackDAO:store: Responded with retCode: 0 to request to store Callback record CALLGUID=004C1C97FDE911E28192F78DF697AB03 

137011: 172.22.100.28: Aug 06 2013 17:06:49.701 +0100: %CVP_9_0_RPT-7-createNewCallEvent:  {Thrd=Thread-24}  

137012: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} LeaveQueue CALLGUID=004C1C97FDE911E28192F78DF697AB03 

137013: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackQueueDAO:updateQueueStatus: Request to update CallbackQueue status. CallGUID=004C1C97FDE911E28192F78DF697AB03 queueStatus=0 leaveDateTime=Tue Aug 06 17:06:57 BST 2013 

137014: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackQueueDAO:updateQueueStatus: Queue status of 1 rows were updated in CallbackQueue. CALLGUID=004C1C97FDE911E28192F78DF697AB03 

137015: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} removeQueueEntry CALLGUID=004C1C97FDE911E28192F78DF697AB03 

137016: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} QueueStats removeEntry: 004C1C97FDE911E28192F78DF697AB03 8: Aug 06 2013 17:06:23.259 +0100: %CVP_9_0_RPT-7-NewCall HANDLER:  {Thrd=Thread-24}  
136985: 172.22.100.28: Aug 06 2013 17:06:23.259 +0100: %CVP_9_0_RPT-7-createNewCallEvent:  {Thrd=Thread-24}  
136986: 172.22.100.28: Aug 06 2013 17:06:23.867 +0100: %CVP_9_0_RPT-7-NewCall HANDLER:  {Thrd=Thread-24}  
136987: 172.22.100.28: Aug 06 2013 17:06:23.867 +0100: %CVP_9_0_RPT-7-createNewCallEvent:  {Thrd=Thread-24}  
136988: 172.22.100.28: Aug 06 2013 17:06:24.195 +0100: %CVP_9_0_RPT-7-createNewCallEvent:  {Thrd=Thread-24}  
136989: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} EnterQueueHandler:validate: validate guid=004C1C97FDE911E28192F78DF697AB03 icmewt=100 
136990: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} EnterQueueHandler:EnterQueueHandler.exec: EnterQueueHandler CALLGUID=004C1C97FDE911E28192F78DF697AB03 CallStartDate=Tue Aug 06 17:06:36 BST 2013 
136991: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} EnterQueue CALLGUID=004C1C97FDE911E28192F78DF697AB03 QueueName=billing ani=901908666555 
136992: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} QueueStats putEntry: 004C1C97FDE911E28192F78DF697AB03 
136993: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} JdbcCallbackQueueDAO:store: Request to store CallbackQueue record. CallGUID=004C1C97FDE911E28192F78DF697AB03 CallbackQueueDTO=CallbackQueueDTO::' SurrogateId: '-1' QueueName: 'billing' QueueId: '-1' DbDateTime: 'null' QueueStatus: '0' ValidationStatus: '0' EnterDateTime: 'Tue Aug 06 17:06:24 BST 2013' LeaveDateTime: 'null' CVPEstimatedWaitTime: '0' ICMEstimatedWaitTime: '100' CallStartDate: 'Tue Aug 06 17:06:24 BST 2013' 
136994: 172.22.100.28: Aug 06 2013 17:06:24.444 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor21} JdbcCallbackQueueDAO:store: Responded with retCode: 0 to request to store CallbackQueue record CALLGUID=004C1C97FDE911E28192F78DF697AB03 
136995: 172.22.100.28: Aug 06 2013 17:06:24.476 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor20} ValidateHandler:ValidateHandler.exec: ValidateHandler GUID=004C1C97FDE911E28192F78DF697AB03 
136996: 172.22.100.28: Aug 06 2013 17:06:24.476 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor20} ValidateHandler:ValidateHandler.exec: ValidateHandler GUID=004C1C97FDE911E28192F78DF697AB03 refresh 
136997: 172.22.100.28: Aug 06 2013 17:06:24.476 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor23} SetQueueDefaultsHandler:SetQueueDefaultsHandler.exec: SetQueueDefaultsHandler QueueName=billing 
136998: 172.22.100.28: Aug 06 2013 17:06:24.491 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} ValidateHandler:ValidateHandler.exec: ValidateHandler GUID=004C1C97FDE911E28192F78DF697AB03 
136999: 172.22.100.28: Aug 06 2013 17:06:24.491 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackDAO:isExceededCapacity: Request to check if exceeded capacity in Callback. Gateway=172.22.100.252 NumOfGatewayAllowed=2 inteval=60 
137000: 172.22.100.28: Aug 06 2013 17:06:24.507 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackDAO:isExceededCapacity: Returning false for query on whether capacity is exceeded. Input parameters: Gateway=172.22.100.252 NumberOfGatewayCallbacksAllowed=2 inteval=60 
137001: 172.22.100.28: Aug 06 2013 17:06:24.507 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackQueueDAO:updateValidationStatus: Request to update validation status in CallbackQueue. CallGUID=004C1C97FDE911E28192F78DF697AB03 validationStatus=2 
137002: 172.22.100.28: Aug 06 2013 17:06:24.507 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackQueueDAO:updateValidationStatus: Validation status of 1 rows were updated in CallbackQueue. CALLGUID=004C1C97FDE911E28192F78DF697AB03 
137003: 172.22.100.28: Aug 06 2013 17:06:24.507 +0100: %CVP_9_0_RPT-7-LOW_LEVEL:  {Thrd=http-8000-Processor25} ValidateHandler:ValidateHandler.exec: ValidateHandler GUID=004C1C97FDE911E28192F78DF697AB03 results:preemptive validation status bitmask=0x00000003 
137004: 172.22.100.28: Aug 06 2013 17:06:27.736 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=CallbackTimerThread} QueueStats:recalculateRemainingWaitTimes: recalculateRemainingWaitTimes CALLGUID=004C1C97FDE911E28192F78DF697AB03 queuePos=0 dqRateA=100 timeInFirstPlace(Secs)=4 reconnectTime=30 SLA_time=60 RemainingTime=96 
137005: 172.22.100.28: Aug 06 2013 17:06:37.876 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=CallbackTimerThread} QueueStats:recalculateRemainingWaitTimes: recalculateRemainingWaitTimes CALLGUID=004C1C97FDE911E28192F78DF697AB03 queuePos=0 dqRateA=100 timeInFirstPlace(Secs)=14 reconnectTime=30 SLA_time=60 RemainingTime=86 
137006: 172.22.100.28: Aug 06 2013 17:06:48.016 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=CallbackTimerThread} QueueStats:recalculateRemainingWaitTimes: recalculateRemainingWaitTimes CALLGUID=004C1C97FDE911E28192F78DF697AB03 queuePos=0 dqRateA=100 timeInFirstPlace(Secs)=24 reconnectTime=30 SLA_time=60 RemainingTime=76 
137007: 172.22.100.28: Aug 06 2013 17:06:49.062 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor24} AddCallbackHandler:AddCallbackHandler.exec: AddCallbackHandler CALLGUID=004C1C97FDE911E28192F78DF697AB03 
137008: 172.22.100.28: Aug 06 2013 17:06:49.062 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor24} CALLGUID=004C1C97FDE911E28192F78DF697AB03 Callback number 901908666555 is allowed - [CCB DN Pattern: 90> : Allow] 
137009: 172.22.100.28: Aug 06 2013 17:06:49.062 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor24} JdbcCallbackDAO:store: Request to store Callback record. CallbackDTO=CallbackDTO::  SurrogateId: '-1' CallGUID: '004C1C97FDE911E28192F78DF697AB03' ANI: '901908666555' DbDateTime: 'null' EventTypeId: '21' CauseId: '0' CallBackType: 'p' OldGUID: 'null' Gateway: '172.22.100.252' Location: 'HCS' NbrAttempts: '0' ScheduledCallBackDateTime: 'null' ScheduledCallBackDN: 'null' CallStartDate: 'Tue Aug 06 17:06:24 BST 2013' RecordingURL: 'http://172.22.100.23:7000/CVP/audio/RecordedName.wav' QueueName: 'null' eventDateTime: Tue Aug 06 17:06:24 BST 2013 
137010: 172.22.100.28: Aug 06 2013 17:06:49.062 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor24} JdbcCallbackDAO:store: Responded with retCode: 0 to request to store Callback record CALLGUID=004C1C97FDE911E28192F78DF697AB03 
137011: 172.22.100.28: Aug 06 2013 17:06:49.701 +0100: %CVP_9_0_RPT-7-createNewCallEvent:  {Thrd=Thread-24}  
137012: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} LeaveQueue CALLGUID=004C1C97FDE911E28192F78DF697AB03 
137013: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackQueueDAO:updateQueueStatus: Request to update CallbackQueue status. CallGUID=004C1C97FDE911E28192F78DF697AB03 queueStatus=0 leaveDateTime=Tue Aug 06 17:06:57 BST 2013 
137014: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} JdbcCallbackQueueDAO:updateQueueStatus: Queue status of 1 rows were updated in CallbackQueue. CALLGUID=004C1C97FDE911E28192F78DF697AB03 
137015: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} removeQueueEntry CALLGUID=004C1C97FDE911E28192F78DF697AB03 
137016: 172.22.100.28: Aug 06 2013 17:06:57.626 +0100: %CVP_9_0_RPT-7-CALL:  {Thrd=http-8000-Processor25} QueueStats removeEntry: 004C1C97FDE911E28192F78DF697AB03 

The other thing I'm confused about is if outbound call is made directly from GW or its is done via consulting CVP dial-plan?

Thanks in advance.

Regards,

Ahmad

[Senthil Kumar Sankar]

Just the snippet will not help in determining the cause. Can you attach the complete log sets. CVP Call Server/VXML Server/Reporting Server.

[ciscorajaraman]

Hi,

I am also facing this issue, How you guys found any solution for this

[Muhammad Fahad Raza]

Hi guys, I am getting the same behavior.

Please find below the logs and suggest solution.

10.83.10.14.1424436542353.1703226.CallbackEntry,02/20/2015 12:49:02.665,Validate_01,enter,

10.83.10.14.1424436542353.1703226.CallbackEntry,02/20/2015 12:49:02.743,Validate_01,element,warning,"Error: no Capacity field defined" returned from sending Callback_Validate request to CallbackServlet
10.83.10.14.1424436542353.1703226.CallbackEntry,02/20/2015 12:49:02.743,Validate_01,custom,Callback_Validate,ELEMENT_EXIT
10.83.10.14.1424436542353.1703226.CallbackEntry,02/20/2015 12:49:02.743,Validate_01,data,gw,10.82.10.101
10.83.10.14.1424436542353.1703226.CallbackEntry,02/20/2015 12:49:02.743,Validate_01,exit,error
10.83.10.14.1424436542353.1703226.CallbackEntry,02/20/2015 12:49:02.743,Get Status_01,enter,
10.83.10.14.1424436542353.1703226.CallbackEntry,02/20/2015 12:49:02.743,Get Status_01,custom,Callback_Get_Status,ELEMENT_ENTRY
10.83.10.14.1424436542353.1703226.CallbackEntry,02/20/2015 12:49:02.790,Get Status_01,custom,Callback_Get_Status,ELEMENT_EXIT

Regards,

Muhammad Fahad Raza

[Kiran Nagaraj]

Hi, my client is using CVP 10.5 and i have implemented CCB newly, it seems we are missing some configuration in the setup which is leading to below scenario.

The validate element in CallbackEntry application fails, it is exiting out of ‘none’ instead of ‘preemptive’.

The first validate exits ‘refresh’ and goes to SetQueueDefault element to callback setting, later the validate 2 element exits out of ‘none’ causing the call to go to queue instead of preemptive section.

Can anyone please let me know if there is any other configuration on gateway or any other component which the validate element is looking for to exit into preemptive section for proper callback to happen.

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.610,Validate_01,enter,

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.610,Validate_01,custom,Callback_Validate,ELEMENT_ENTRY

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.735,Validate_01,custom,Callback_Validate,ELEMENT_ENTRY

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.735,Validate_01,custom,probe outcome,id:172.18.216.132;loc:Australia-LOC;trunks:1

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.798,Validate_01,custom,Callback_Validate,ELEMENT_EXIT

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.798,Validate_01,data,gw,172.18.216.132

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.798,Validate_01,data,loc,Australia-LOC

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.798,Validate_01,data,capacity,1

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.798,Validate_01,data,result,refresh

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.798,Validate_01,exit,refresh

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.798,SetQueueDefaults_01,enter,

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.798,SetQueueDefaults_01,custom,Callback_Set_Queue_Defaults,ELEMENT_ENTRY

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.860,SetQueueDefaults_01,custom,Callback_Set_Queue_Defaults,ELEMENT_EXIT

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.860,SetQueueDefaults_01,exit,done

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.860,Validate_02,enter,

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:11.876,Validate_02,custom,Callback_Validate,ELEMENT_ENTRY

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:12.000,Validate_02,custom,Callback_Validate,ELEMENT_ENTRY

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:12.000,Validate_02,custom,probe outcome,id:172.18.216.132;loc:Australia-LOC;trunks:1

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:12.063,Validate_02,custom,Callback_Validate,ELEMENT_EXIT

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:12.063,Validate_02,data,gw,172.18.216.132

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:12.063,Validate_02,data,loc,Australia-LOC

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:12.063,Validate_02,data,capacity,1

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:12.063,Validate_02,data,result,none

172.24.194.94.1471344910628.0.CallbackEntry,08/16/2016 18:55:12.063,Validate_02,exit,none

Regards,

Kiran

[Muhammad Fahad Raza]

Hi Kiran,

Can you check where the call is failing from the script.

I am attaching a working configuration for Voice gateway.

you can verify your configuration using it.

Regards,

Fahad Raza

[Coty Condry]

Make sure your ingress VXML gateway (the one running the survivability and CCB scripts) has 'signaling forward unconditional' enabled:

voice service voip
 signaling forward unconditional

For reference, this is how I solved this same problem that I was having. I was also getting this error in the CallbackEngine activity and error logs:

onEndCall - "Error: Unable to retrieve GUID status" returned from sending Callback_Get_Status request to CallbackServlet

and in the CVP Reporting server running CCB, the CVP.log logs showed this message:

LeaveCallbackHandler CALLGUID=49CC2F2723D711E480B Unable to get GUID 

[ineslovric]

Hi,

I'm also facing this same issue (same errors).

Do you have any suggestions?

We have enabled:

voice service voip
 signaling forward unconditional

[ineslovric]

In my case problem was the length of ECC variable.

If someone hits this problem also check ECC variables that are used.