Author Message
maspandel
Joined: Apr 22, 2020
Messages: 40
Offline
Hi team. AOD 8.0. When i dial to application then input the number I want to conference, I can hear an error message but the conference goes on.

On trace.log I can see the error 'holdCall failure':
':
01/07/2021 05:29:19:635 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 491105 is 4
01/07/2021 05:29:19:636 DEBUG - CTICallObserver.callChangedEvent 491105: Event: 0 is 117 for call 13165 Provider:NSEAES
01/07/2021 05:29:19:636 DEBUG - CTICallObserver.callChangedEvent:491105: got an unknown event 117
01/07/2021 05:29:19:636 DEBUG - CTICallObserver.callChangedEvent 491105: Event: 1 is 215 for call 13165 Provider:NSEAES
01/07/2021 05:29:19:636 DEBUG - CTICallObserver.callChangedEvent:491105: got an unknown event 215
01/07/2021 05:29:19:636 DEBUG - CTICallObserver.callChangedEvent 491105: Event: 2 is 107 for call 13165 Provider:NSEAES
01/07/2021 05:29:19:636 DEBUG - CTICallObserver.callChangedEvent:491105: got Connection Disconnected Event 107
01/07/2021 05:29:19:636 DEBUG - CTICallObserver.notifyApplicationObserver: Notifying 491105 about call event
01/07/2021 05:29:19:737 ERROR - Hold.doHold 491105: error:holdCall failure
01/07/2021 05:29:19:737 DEBUG -CTICallObserver.removeApplicationObserver: removing an app observer from extension:491105


but nothing on tsapi_trace.txt around the same time:

2021-07-01 05:29:19,634 [DistributeCSTAEvent] INFO TSDevice - TSDevice state transition: Deleted -> Active, device TSDevice[490010]@48c5f012
2021-07-01 05:29:19,634 [DistributeCSTAEvent] INFO TSDevice - Device 490010 (object= TSDevice[490010]@48c5f012) being re-added for TSProvider[#2]@5a701768
2021-07-01 05:29:19,634 [GetEventThread] INFO TsapiSession - Received invokeID 128 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,634 [DistributeCSTAEvent] INFO TSEventHandler - ********************* Call is not monitored, going for it
2021-07-01 05:29:19,634 [DistributeCSTAEvent] INFO TSEventHandler - DONE handling CSTA_CONNECTION_CLEARED for TSProvider[#2]@5a701768
2021-07-01 05:29:19,634 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTAEscapeSvcConfEvent]@643f80e4. EVENT Q SIZE = 1 MAX Q SIZE = 2 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,634 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTAEscapeSvcConfEvent]@643f80e4 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,634 [DistributeCSTAEvent] INFO TSInvokeID - Handling INVOKE ID 128 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,634 [DistributeCSTAEvent] INFO TSInvokeID - DONE handling INVOKE ID 128 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,635 [http-nio-7080-exec-8] INFO TsapiAddress - API CALL END: Address.getConnections() for com.avaya.jtapi.tsapi.impl.LucentV11AddressImpl@5c8a553
2021-07-01 05:29:19,635 [http-nio-7080-exec-8] INFO TsapiSession - Sent InvokeID 129 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,681 [GetEventThread] INFO TsapiSession - Received monitorCrossRefID 5 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,682 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTAEstablishedEvent]@472a0786. EVENT Q SIZE = 1 MAX Q SIZE = 2 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,682 [GetEventThread] INFO TsapiSession - Received monitorCrossRefID 5 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,682 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTAEstablishedEvent]@472a0786 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,682 [DistributeCSTAEvent] INFO TSEventHandler - CSTA Unsolicited Event: CSTAEvent[CSTAEstablishedEvent]@472a0786
2021-07-01 05:29:19,682 [DistributeCSTAEvent] INFO TSEventHandler - Free memory: 90418824
2021-07-01 05:29:19,682 [DistributeCSTAEvent] INFO TSEventHandler - Total memory: 134152192
2021-07-01 05:29:19,683 [DistributeCSTAEvent] INFO TSEventHandler - Handling CSTA_ESTABLISHED for TSProvider[#2]@5a701768
2021-07-01 05:29:19,683 [DistributeCSTAEvent] INFO TSDevice - Constructing device TSDevice[490030]@6428db75 with name 490030 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,683 [DistributeCSTAEvent] INFO TsapiSession - Sent InvokeID 130 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,683 [DistributeCSTAEvent] INFO TSDevice - isTerminal() for TSDevice[490030]@6428db75
2021-07-01 05:29:19,683 [DistributeCSTAEvent] INFO TSDevice - TSDevice[490030]@6428db75 waiting for initialization to complete
2021-07-01 05:29:19,688 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTAConferencedEvent]@2e4ed5c8. EVENT Q SIZE = 1 MAX Q SIZE = 2 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,688 [GetEventThread] INFO TsapiSession - Received invokeID 129 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,688 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTAUniversalFailureConfEvent]@7b239246. EVENT Q SIZE = 2 MAX Q SIZE = 2 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,733 [GetEventThread] INFO TsapiSession - Received invokeID 130 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,733 [GetEventThread] INFO TSInvokeID - Handling INVOKE ID 130 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,734 [GetEventThread] INFO TSDevice - Initialization complete for TSDevice TSDevice[490030]@6428db75 - making values available - for TSProvider[#2]@5a701768
2021-07-01 05:29:19,734 [GetEventThread] INFO TSInvokeID - DONE handling INVOKE ID 130 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,734 [DistributeCSTAEvent] INFO TSConnection - Constructing Lucent termConn TSConnection[termConn:(13165,490030)]@485bb003 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,734 [DistributeCSTAEvent] INFO TSProviderImpl - NOTICE: connHash.put() replaced null with TSConnection[termConn:(13165,490030)]@485bb003 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,734 [DistributeCSTAEvent] INFO TSConnection - Constructing Lucent conn TSConnection[conn:(-,490030)]@142ebf86 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TSEventHandler - DONE handling CSTA_ESTABLISHED for TSProvider[#2]@5a701768
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTAConferencedEvent]@2e4ed5c8 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TSEventHandler - CSTA Unsolicited Event: CSTAEvent[CSTAConferencedEvent]@2e4ed5c8
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TSEventHandler - Free memory: 90102328
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TSEventHandler - Total memory: 134152192
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TSEventHandler - Handling CSTA_CONFERENCED for TSProvider[#2]@5a701768
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TSEventHandler - DONE handling CSTA_CONFERENCED for TSProvider[#2]@5a701768
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTAUniversalFailureConfEvent]@7b239246 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TSInvokeID - Handling INVOKE ID 129 for TSProvider[#2]@5a701768
2021-07-01 05:29:19,736 [DistributeCSTAEvent] INFO TSInvokeID - DONE handling INVOKE ID 129 for TSProvider[#2]@5a701768


Any idea what is the root cause and the solution ?
WilsonYu
Joined: Nov 6, 2013
Messages: 3950
Offline
This shows the extension is getting disconnected while the app is trying to put the call on hold:

01/07/2021 05:29:19:636 DEBUG - CTICallObserver.callChangedEvent:491105: got Connection Disconnected Event 107
01/07/2021 05:29:19:636 DEBUG - CTICallObserver.notifyApplicationObserver: Notifying 491105 about call event
01/07/2021 05:29:19:737 ERROR - Hold.doHold 491105: error:holdCall failure
maspandel
Joined: Apr 22, 2020
Messages: 40
Offline
Hi, thanks for the reply. The only problem I can see on the tsapi_trace.txt is

2021-07-01 05:29:19,688 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTAUniversalFailureConfEvent]@7b239246. EVENT Q SIZE = 2 MAX Q SIZE = 2 for TSProvider[#2]@5a701768

this happened a few moments before the error message on the trace.log.

Neither error number nor error description is visible. I used debug level=4 on the trace. Do I have to increase the level to fetch additional info ?
WilsonYu
Joined: Nov 6, 2013
Messages: 3950
Offline
What you need to investigate or confirm is why the call gets disconnected in the first place. That is the cause of the failure putting the call on hold.
Go to:   
Mobile view