Author Message
Sushil
Joined: Sep 13, 2020
Messages: 29
Offline
Hi,
Upon agent login, JTAPI is throwing no conf event exception. Its very random. One reason could be that if there is no response from AES within 1 minute then JTAPI throws no conf event but apart from this is there any other reasons?
Below is the log snippet for reference.
JTAPI Client Version 7.1.3
Agent ID 2660
Station ID 2573

2020-11-17 08:00:24,545 [https-jsse-nio-8080-exec-14] TRACE LucentV11ProviderImpl - --> getTerminal[String name]: com.avaya.jtapi.tsapi.impl.LucentV11ProviderImpl@244f1051
2020-11-17 08:00:24,545 [https-jsse-nio-8080-exec-14] TRACE LucentV5TerminalExImpl - com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029 constructed.
2020-11-17 08:00:24,545 [https-jsse-nio-8080-exec-14] TRACE LucentV11ProviderImpl - <-- getTerminal[String name]: com.avaya.jtapi.tsapi.impl.LucentV11ProviderImpl@244f1051
2020-11-17 08:00:24,545 [https-jsse-nio-8080-exec-14] TRACE LucentV5TerminalExImpl - --> getTerminalConnections[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029
2020-11-17 08:00:24,545 [https-jsse-nio-8080-exec-14] INFO TsapiSession - Sent InvokeID 2895 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,545 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - CSTASnapshotDevice ::=
2020-11-17 08:00:24,545 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - {
2020-11-17 08:00:24,545 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - snapshotObject "2573"
2020-11-17 08:00:24,545 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - }
2020-11-17 08:00:24,587 [GetEventThread] INFO TsapiSession - Received invokeID 2895 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - CSTASnapshotDeviceConfEvent ::=
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - snapshotData
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - data 2 0 48 0 30 0
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - tsType 123
2020-11-17 08:00:24,588 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,588 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTASnapshotDeviceConfEvent]@18ffb801. EVENT Q SIZE = 1 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,588 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTASnapshotDeviceConfEvent]@18ffb801 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,588 [DistributeCSTAEvent] INFO LucentPrivateData - data PDU 72 not decoded
2020-11-17 08:00:24,588 [DistributeCSTAEvent] INFO TSInvokeID - Handling INVOKE ID 2895 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,588 [DistributeCSTAEvent] INFO TSInvokeID - DONE handling INVOKE ID 2895 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,588 [https-jsse-nio-8080-exec-14] INFO LucentPrivateData - data PDU 72 not decoded
2020-11-17 08:00:24,588 [https-jsse-nio-8080-exec-14] TRACE LucentV5TerminalExImpl - <-- getTerminalConnections[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029
2020-11-17 08:00:24,588 [https-jsse-nio-8080-exec-14] TRACE LucentV5TerminalExImpl - --> getAgents[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029
2020-11-17 08:00:24,588 [https-jsse-nio-8080-exec-14] INFO TsapiSession - Sent InvokeID 2896 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,588 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - CSTAQueryAgentState ::=
2020-11-17 08:00:24,588 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - {
2020-11-17 08:00:24,588 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - device "2573"
2020-11-17 08:00:24,588 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - }
2020-11-17 08:00:24,638 [GetEventThread] INFO TsapiSession - Received invokeID 2896 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,638 [GetEventThread] DEBUG TsapiSession - CSTAQueryAgentStateConfEvent ::=
2020-11-17 08:00:24,638 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,638 [GetEventThread] DEBUG TsapiSession - agentState 1 < AG_NULL >
2020-11-17 08:00:24,638 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,638 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTAQueryAgentStateConfEvent]@77ef5ea1. EVENT Q SIZE = 1 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,638 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTAQueryAgentStateConfEvent]@77ef5ea1 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,638 [DistributeCSTAEvent] INFO TSInvokeID - Handling INVOKE ID 2896 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,638 [DistributeCSTAEvent] INFO TSInvokeID - DONE handling INVOKE ID 2896 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,638 [https-jsse-nio-8080-exec-14] INFO TsapiSession - Sent InvokeID 2897 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,638 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - CSTAQueryDeviceInfo ::=
2020-11-17 08:00:24,638 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - {
2020-11-17 08:00:24,638 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - device "2573"
2020-11-17 08:00:24,638 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - }
2020-11-17 08:00:24,688 [GetEventThread] INFO TsapiSession - Received invokeID 2897 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - CSTAQueryDeviceInfoConfEvent ::=
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - device "2573"
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - deviceType 0 < DT_STATION >
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - deviceClass 0x80000000 < DC_VOICE >
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - data 2 0 62 0 30 8 a 1 6 a 1 a 16 0
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - tsType 38
2020-11-17 08:00:24,688 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,688 [GetEventThread] DEBUG LucentPrivateData - LucentV5QueryDeviceInfoConfEvent ::=
2020-11-17 08:00:24,688 [GetEventThread] DEBUG LucentPrivateData - {
2020-11-17 08:00:24,688 [GetEventThread] DEBUG LucentPrivateData - extensionClass 6 < EC_PROPRIETARY >
2020-11-17 08:00:24,688 [GetEventThread] DEBUG LucentPrivateData - associatedClass 10 < EC_OTHER >
2020-11-17 08:00:24,688 [GetEventThread] DEBUG LucentPrivateData - associatedDevice <null>
2020-11-17 08:00:24,689 [GetEventThread] DEBUG LucentPrivateData - }
2020-11-17 08:00:24,689 [GetEventThread] INFO TSInvokeID - Handling INVOKE ID 2897 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,689 [GetEventThread] INFO TSInvokeID - DONE handling INVOKE ID 2897 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentV5TerminalExImpl - <-- getAgents[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentV5TerminalExImpl - --> getAddresses[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentV11AddressImpl - com.avaya.jtapi.tsapi.impl.LucentV11AddressImpl@79d96029 constructed.
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentV5TerminalExImpl - <-- getAddresses[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentV11ProviderImpl - --> getAddress[String number]: com.avaya.jtapi.tsapi.impl.LucentV11ProviderImpl@244f1051
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentACDAddressImpl - com.avaya.jtapi.tsapi.impl.LucentACDAddressImpl@660dcf00 constructed.
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentV11ProviderImpl - <-- getAddress[String number]: com.avaya.jtapi.tsapi.impl.LucentV11ProviderImpl@244f1051
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentV5TerminalExImpl - --> addAgent[LucentAddress agentAddress, ACDAddress acdAddress, int initialState, int workMode, int reasonCode, String agentID, String password]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentV11AddressImpl - --> getTSDevice[]: com.avaya.jtapi.tsapi.impl.LucentV11AddressImpl@79d96029
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentV11AddressImpl - <-- getTSDevice[]: com.avaya.jtapi.tsapi.impl.LucentV11AddressImpl@79d96029
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentACDAddressImpl - --> getTSDevice[]: com.avaya.jtapi.tsapi.impl.LucentACDAddressImpl@660dcf00
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] TRACE LucentACDAddressImpl - <-- getTSDevice[]: com.avaya.jtapi.tsapi.impl.LucentACDAddressImpl@660dcf00
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG LucentPrivateData - LucentV5SetAgentState ::=
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG LucentPrivateData - {
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG LucentPrivateData - workMode 1 < WM_AUX_WORK >
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG LucentPrivateData - reasonCode 0
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG LucentPrivateData - }
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] INFO TsapiSession - Sent InvokeID 2898 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - CSTASetAgentState ::=
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - {
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - device "2573"
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - agentMode 0 < AM_LOG_IN >
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - agentID "2660"
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - agentGroup "2023"
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - agentPassword <null>
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - }
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - {
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - data 2 0 57 0 30 6 a 1 1 2 1 0
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - tsType 89
2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - }
2020-11-17 08:00:24,738 [GetEventThread] INFO TsapiSession - Received invokeID 2898 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - CSTASetAgentStateConfEvent ::=
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - NULL
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - data 2 0 67 0 30 3 1 1 0
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - tsType 50
2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,739 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTASetAgentStateConfEvent]@3160f118. EVENT Q SIZE = 1 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,739 [GetEventThread] INFO TsapiSession - Received monitorCrossRefID 31 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - CSTALoggedOnEvent ::=
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - monitorCrossRefID 31
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - agentDevice
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - deviceID "2573"
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - deviceIDType 55 < EXPLICIT_PRIVATE_LOCAL_NUMBER >
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:00:24,739 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTASetAgentStateConfEvent]@3160f118 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - agentID "2660"
2020-11-17 08:00:24,739 [DistributeCSTAEvent] DEBUG LucentPrivateData - LucentSetAgentStateConfEvent ::=
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - agentGroup "2004"
2020-11-17 08:00:24,739 [DistributeCSTAEvent] DEBUG LucentPrivateData - {
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - password <null>
2020-11-17 08:00:24,739 [DistributeCSTAEvent] DEBUG LucentPrivateData - isPending FALSE
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,739 [DistributeCSTAEvent] DEBUG LucentPrivateData - }
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:00:24,739 [DistributeCSTAEvent] INFO TSInvokeID - Handling INVOKE ID 2898 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:24,739 [DistributeCSTAEvent] INFO TSInvokeID - DONE handling INVOKE ID 2898 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - data 2 0 30 0 30 3 a 1 1
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - tsType 72
2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:24,739 [https-jsse-nio-8080-exec-14] INFO TSAgent - constructing TSAgent with agentKey=TSAgentKey(2573,2023,2660) for TSProvider[#1]@244f1051
2020-11-17 08:00:24,739 [https-jsse-nio-8080-exec-14] INFO TSAgent - ParentAgent Object TSAgentKey(2573,null,2660) being created for provider TSProvider[#1]@244f1051
2020-11-17 08:00:24,739 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTALoggedOnEvent]@5714ed66. EVENT Q SIZE = 1 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTALoggedOnEvent]@5714ed66 for TSProvider[#1]@244f1051
2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] DEBUG TsapiAddressMonitor - Getting TsapiAddressMonitor lock to deliver events for listener com.interactcrm.telephony.observer.core.impl.TelephonyACDAddressListener@600b0ea7
2020-11-17 08:00:24,740 [DistributeCSTAEvent] DEBUG LucentPrivateData - LucentLoggedOnEvent ::=
2020-11-17 08:00:24,740 [DistributeCSTAEvent] DEBUG LucentPrivateData - {
2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] TRACE LucentV7AgentImpl - com.avaya.jtapi.tsapi.impl.LucentV7AgentImpl@1c60c21d constructed.
2020-11-17 08:00:24,740 [DistributeCSTAEvent] DEBUG LucentPrivateData - workMode 1 < WM_AUX_WORK >
2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] TRACE LucentACDAddressImpl - com.avaya.jtapi.tsapi.impl.LucentACDAddressImpl@660dcf00 constructed.
2020-11-17 08:00:24,740 [DistributeCSTAEvent] DEBUG LucentPrivateData - }
2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSEventHandler - CSTA Unsolicited Event: CSTAEvent[CSTALoggedOnEvent]@5714ed66
2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSEventHandler - Free memory: 525973736
2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] DEBUG TsapiAddressMonitor - ADDRESSNOTREADYEVENT for TSAgent[TSAgentKey(2573,2023,2660)]@1c60c21d for listener com.interactcrm.telephony.observer.core.impl.TelephonyACDAddressListener@600b0ea7
2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSEventHandler - Total memory: 919601152
2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] TRACE LucentV7AgentImpl - com.avaya.jtapi.tsapi.impl.LucentV7AgentImpl@1c60c21d constructed.
2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSEventHandler - Handling CSTA_LOGGED_ON for TSProvider[#1]@244f1051
2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] TRACE LucentACDAddressImpl - com.avaya.jtapi.tsapi.impl.LucentACDAddressImpl@660dcf00 constructed.
2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSAgent - constructing TSAgent with agentKey=TSAgentKey(2573,2004,2660) for TSProvider[#1]@244f1051
2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSAgent - ParentAgent Object TSAgentKey(2573,null,2660) being created for provider TSProvider[#1]@244f1051
2020-11-17 08:00:24,741 [pool-1-thread-19] TRACE TsapiAddressMonitor - --> run[]: com.avaya.jtapi.tsapi.impl.monitor.TsapiAddressMonitor@5469183f
2020-11-17 08:00:24,741 [pool-1-thread-19] DEBUG TsapiAddressMonitor - Got syncObject for AddressListener - com.interactcrm.telephony.observer.core.impl.TelephonyACDAddressListener@600b0ea7
2020-11-17 08:00:24,741 [pool-1-thread-19] DEBUG TsapiAddressMonitor - Got this for AddressListener - com.interactcrm.telephony.observer.core.impl.TelephonyACDAddressListener@600b0ea7
2020-11-17 08:00:24,741 [pool-1-thread-19] DEBUG TsapiAddressMonitor - calling acdAddressNotReady in com.interactcrm.telephony.observer.core.impl.TelephonyACDAddressListener@600b0ea7
2020-11-17 08:00:24,741 [pool-1-thread-19] DEBUG TsapiAddressMonitor - returned from acdAddressNotReady in com.interactcrm.telephony.observer.core.impl.TelephonyACDAddressListener@600b0ea7
2020-11-17 08:00:24,741 [pool-1-thread-19] TRACE TsapiAddressMonitor - <-- run[]: com.avaya.jtapi.tsapi.impl.monitor.TsapiAddressMonitor@5469183f
2020-11-17 08:00:26,846 [GetEventThread] INFO TsapiSession - Received monitorCrossRefID 31 for TSProvider[#1]@244f1051
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - CSTALoggedOnEvent ::=
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - monitorCrossRefID 31
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - agentDevice
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - deviceID "6569"
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - deviceIDType 55 < EXPLICIT_PRIVATE_LOCAL_NUMBER >
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - agentID "2633"
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - agentGroup "2004"
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - password <null>
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - data 2 0 30 0 30 3 a 1 1
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - tsType 72
2020-11-17 08:00:26,846 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:26,846 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTALoggedOnEvent]@32ce6835. EVENT Q SIZE = 1 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
2020-11-17 08:00:34,139 [https-jsse-nio-8080-exec-1] TRACE LucentV11ProviderImpl - --> getTerminal[String name]: com.avaya.jtapi.tsapi.impl.LucentV11ProviderImpl@244f1051
2020-11-17 08:00:34,139 [https-jsse-nio-8080-exec-1] INFO TSDevice - Constructing device TSDevice[6616]@1a401fd4 with name 6616 for TSProvider[#1]@244f1051
2020-11-17 08:00:34,139 [https-jsse-nio-8080-exec-1] INFO TsapiSession - Sent InvokeID 2899 for TSProvider[#1]@244f1051
2020-11-17 08:00:34,139 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - CSTAQueryDeviceInfo ::=
2020-11-17 08:00:34,139 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - {
2020-11-17 08:00:34,139 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - device "6616"
2020-11-17 08:00:34,139 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - }
2020-11-17 08:00:34,139 [https-jsse-nio-8080-exec-1] INFO TSDevice - isTerminal() for TSDevice[6616]@1a401fd4
2020-11-17 08:00:34,139 [https-jsse-nio-8080-exec-1] INFO TSDevice - TSDevice[6616]@1a401fd4 waiting for initialization to complete
2020-11-17 08:00:34,217 [GetEventThread] INFO TsapiSession - Received invokeID 2899 for TSProvider[#1]@244f1051
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - CSTAQueryDeviceInfoConfEvent ::=
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - device "6616"
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - deviceType 0 < DT_STATION >
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - deviceClass 0x80000000 < DC_VOICE >
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - data 2 0 62 0 30 8 a 1 6 a 1 a 16 0
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - tsType 38
2020-11-17 08:00:34,217 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:34,217 [GetEventThread] DEBUG LucentPrivateData - LucentV5QueryDeviceInfoConfEvent ::=
2020-11-17 08:00:34,217 [GetEventThread] DEBUG LucentPrivateData - {
2020-11-17 08:00:34,217 [GetEventThread] DEBUG LucentPrivateData - extensionClass 6 < EC_PROPRIETARY >
2020-11-17 08:00:34,217 [GetEventThread] DEBUG LucentPrivateData - associatedClass 10 < EC_OTHER >
2020-11-17 08:00:34,217 [GetEventThread] DEBUG LucentPrivateData - associatedDevice <null>
2020-11-17 08:00:34,217 [GetEventThread] DEBUG LucentPrivateData - }
2020-11-17 08:00:34,217 [GetEventThread] INFO TSInvokeID - Handling INVOKE ID 2899 for TSProvider[#1]@244f1051
2020-11-17 08:00:34,217 [GetEventThread] INFO TSDevice - Initialization complete for TSDevice TSDevice[6616]@1a401fd4 - making values available - for TSProvider[#1]@244f1051
2020-11-17 08:00:34,217 [GetEventThread] INFO TSInvokeID - DONE handling INVOKE ID 2899 for TSProvider[#1]@244f1051
2020-11-17 08:00:34,217 [https-jsse-nio-8080-exec-1] TRACE LucentV5TerminalExImpl - com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@1a401fd4 constructed.
2020-11-17 08:00:34,217 [https-jsse-nio-8080-exec-1] TRACE LucentV11ProviderImpl - <-- getTerminal[String name]: com.avaya.jtapi.tsapi.impl.LucentV11ProviderImpl@244f1051
2020-11-17 08:00:34,217 [https-jsse-nio-8080-exec-1] TRACE LucentV5TerminalExImpl - --> getTerminalConnections[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@1a401fd4
2020-11-17 08:00:34,217 [https-jsse-nio-8080-exec-1] INFO TsapiSession - Sent InvokeID 2900 for TSProvider[#1]@244f1051
2020-11-17 08:00:34,218 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - CSTASnapshotDevice ::=
2020-11-17 08:00:34,218 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - {
2020-11-17 08:00:34,218 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - snapshotObject "6616"
2020-11-17 08:00:34,218 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - }
2020-11-17 08:00:34,267 [GetEventThread] INFO TsapiSession - Received invokeID 2900 for TSProvider[#1]@244f1051
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - CSTASnapshotDeviceConfEvent ::=
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - snapshotData
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - data 2 0 48 0 30 0
2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - tsType 123
2020-11-17 08:00:34,268 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:00:34,268 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTASnapshotDeviceConfEvent]@3f59329b. EVENT Q SIZE = 2 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
2020-11-17 08:01:07,615 [GetEventThread] INFO TsapiSession - Received monitorCrossRefID 27 for TSProvider[#1]@244f1051
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - CSTADeliveredEvent ::=
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - monitorCrossRefID 27
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - connection
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - callID 13562
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - deviceID "4052"
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - devIDType 0 < STATIC_ID >
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - alertingDevice
2020-11-17 08:01:07,615 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceID "4052"
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceIDType 55 < EXPLICIT_PRIVATE_LOCAL_NUMBER >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - callingDevice
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceID "6185809312"
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceIDType 50 < EXPLICIT_PRIVATE_UNKNOWN >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - calledDevice
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceID "5681"
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceIDType 55 < EXPLICIT_PRIVATE_LOCAL_NUMBER >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - lastRedirectionDevice
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceID "2178"
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceIDType 55 < EXPLICIT_PRIVATE_LOCAL_NUMBER >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - localConnectionInfo 2 < CS_ALERTING >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - cause 28 < EC_REDIRECTED >
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - data 2 0 9f 0 30 81 eb a 1 2 16 2 39 31 16 2 36 33 16 4 32 30 30 32 30 13 a 1 ff a 1 0 2 1 0 2 1 0 2 1 0 16 0 30 0 30 f a 1 5 a 1 1 16 1 31 16 4 32 31 37 38 30 5 a 1 ff 4 0 a 1 0 30 66 a 1 0 30 8 16 0 a 1 1e a 1 2 30 8 16 0 a 1 1e a 1 2 16 0 16 0 30 13 a 1 ff a 1 0 2 1 0 2 1 0 2 1 0 16 0 30 0 30 a a 1 ff a 1 0 16 0 16 0 30 5 a 1 ff 4 0 16 14 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 6 1 1 0 2 1 0 1 1 0 30 0 30 c 16 4 35 36 38 31 a 1 37 a 1 0 16 14 30 30 30 30 31 31 33 35 36 32 31 36 30 35 36 32 31 36 30 38 30 6 1 1 0 2 1 0 1 1 0 30 0 30 c 16 4 32 31 37 38 a 1 37 a 1 0 a 1 0
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - tsType 57
2020-11-17 08:01:07,616 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:07,616 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTADeliveredEvent]@907ec59. EVENT Q SIZE = 3 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
2020-11-17 08:01:13,330 [GetEventThread] INFO TsapiSession - Received monitorCrossRefID 27 for TSProvider[#1]@244f1051
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - CSTAEstablishedEvent ::=
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - monitorCrossRefID 27
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - establishedConnection
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - callID 13562
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - deviceID "4052"
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - devIDType 0 < STATIC_ID >
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - answeringDevice
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - deviceID "4052"
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - deviceIDType 55 < EXPLICIT_PRIVATE_LOCAL_NUMBER >
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:01:13,330 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - callingDevice
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - deviceID "6185809312"
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - deviceIDType 50 < EXPLICIT_PRIVATE_UNKNOWN >
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - calledDevice
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - deviceID "5681"
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - deviceIDType 55 < EXPLICIT_PRIVATE_LOCAL_NUMBER >
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - lastRedirectionDevice
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - deviceID "2178"
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - deviceIDType 55 < EXPLICIT_PRIVATE_LOCAL_NUMBER >
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - localConnectionInfo 3 < CS_CONNECT >
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - cause 22 < EC_NEW_CALL >
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - data 2 0 a0 0 30 81 e8 16 2 39 31 16 2 36 33 16 4 32 30 30 32 30 13 a 1 ff a 1 0 2 1 0 2 1 0 2 1 0 16 0 30 0 30 f a 1 5 a 1 1 16 1 31 16 4 32 31 37 38 30 5 a 1 ff 4 0 a 1 0 30 66 a 1 0 30 8 16 0 a 1 1e a 1 2 30 8 16 0 a 1 1e a 1 2 16 0 16 0 30 13 a 1 ff a 1 0 2 1 0 2 1 0 2 1 0 16 0 30 0 30 a a 1 ff a 1 0 16 0 16 0 30 5 a 1 ff 4 0 16 14 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 6 1 1 0 2 1 0 1 1 0 30 0 30 c 16 4 35 36 38 31 a 1 37 a 1 0 16 14 30 30 30 30 31 31 33 35 36 32 31 36 30 35 36 32 31 36 30 38 30 6 1 1 0 2 1 0 1 1 0 30 0 30 c 16 4 32 31 37 38 a 1 37 a 1 0 a 1 0
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - tsType 59
2020-11-17 08:01:13,331 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:13,331 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTAEstablishedEvent]@79885fc2. EVENT Q SIZE = 4 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
2020-11-17 08:01:25,670 [https-jsse-nio-8080-exec-2] TRACE LucentV11ProviderImpl - --> getTerminal[String name]: com.avaya.jtapi.tsapi.impl.LucentV11ProviderImpl@244f1051
2020-11-17 08:01:25,670 [https-jsse-nio-8080-exec-2] TRACE LucentV5TerminalExImpl - com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029 constructed.
2020-11-17 08:01:25,670 [https-jsse-nio-8080-exec-2] TRACE LucentV11ProviderImpl - <-- getTerminal[String name]: com.avaya.jtapi.tsapi.impl.LucentV11ProviderImpl@244f1051
2020-11-17 08:01:25,670 [https-jsse-nio-8080-exec-2] TRACE LucentV5TerminalExImpl - --> getTerminalConnections[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@79d96029
2020-11-17 08:01:25,670 [https-jsse-nio-8080-exec-2] INFO TsapiSession - Sent InvokeID 2901 for TSProvider[#1]@244f1051
2020-11-17 08:01:25,670 [https-jsse-nio-8080-exec-2] DEBUG TsapiSession - CSTASnapshotDevice ::=
2020-11-17 08:01:25,670 [https-jsse-nio-8080-exec-2] DEBUG TsapiSession - {
2020-11-17 08:01:25,671 [https-jsse-nio-8080-exec-2] DEBUG TsapiSession - snapshotObject "2573"
2020-11-17 08:01:25,671 [https-jsse-nio-8080-exec-2] DEBUG TsapiSession - }
2020-11-17 08:01:25,761 [GetEventThread] INFO TsapiSession - Received invokeID 2901 for TSProvider[#1]@244f1051
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - CSTASnapshotDeviceConfEvent ::=
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - snapshotData
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - {
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - data 2 0 48 0 30 0
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - tsType 123
2020-11-17 08:01:25,761 [GetEventThread] DEBUG TsapiSession - }
2020-11-17 08:01:25,761 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTASnapshotDeviceConfEvent]@38c61f06. EVENT Q SIZE = 5 MAX Q SIZE = 5 for TSProvider[#1]@244f1051
2020-11-17 08:01:34,218 [https-jsse-nio-8080-exec-1] ERROR TSDevice - no conf event
com.avaya.jtapi.tsapi.TsapiPlatformException: no conf event
at com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession.send(TsapiSession.java:572)
at com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession.send(TsapiSession.java:476)
at com.avaya.jtapi.tsapi.tsapiInterface.Tsapi.snapshotDevice(Tsapi.java:1505)
at com.avaya.jtapi.tsapi.impl.core.TSDevice.doCallSnapshot(TSDevice.java:2974)
at com.avaya.jtapi.tsapi.impl.core.TSDevice.updateObject(TSDevice.java:2574)
at com.avaya.jtapi.tsapi.impl.core.TSDevice.getTSTerminalConnections(TSDevice.java:384)
at com.avaya.jtapi.tsapi.impl.TsapiTerminal.getTerminalConnections(TsapiTerminal.java:153)
at com.interactcrm.telephony.session.core.impl.EASSession.login(EASSession.java:114)
at com.interactcrm.telephony.application.core.impl.ApplicationImpl.login(ApplicationImpl.java:132)
at com.interactcrm.telephony.connector.TelephonyConnector.login(TelephonyConnector.java:72)
at com.interactcrm.telephony.connector.TelephonyServer.login(TelephonyServer.java:140)
at com.interactcrm.mis.server.voiceserver.TelephonyCommunication.voiceLogin(TelephonyCommunication.java:287)
at com.interactcrm.mis.server.agent.Agent.voiceLogin(Agent.java:67)
at com.interactcrm.mis.server.agent.agentcontroller.impl.AgentController.voiceLogin(AgentController.java:148)
at com.interactcrm.mis.server.agent.agentcontroller.impl.AgentController.channelLogin(AgentController.java:100)
at com.interactcrm.mis.server.agent.agentcontroller.impl.AgentController.agentLogin(AgentController.java:378)
at com.interactcrm.mis.server.agent.agentcontroller.impl.AgentController.login(AgentController.java:348)
at com.interactcrm.mis.client.sdk.core.impl.ApplicationImpl.login(ApplicationImpl.java:242)
at com.interactcrm.mis.client.sdk.core.impl.ApplicationImpl.login(ApplicationImpl.java:174)
at com.interactcrm.sdk.LoginApp.doLogin(LoginApp.java:592)
at com.interactcrm.sdk.LoginApp.login2(LoginApp.java:709)
at com.interactcrm.absEvents.AbsLogin.doLogin(AbsLogin.java:168)
at com.interactcrm.absEvents.AbsLogin.login(AbsLogin.java:95)
at com.interactcrm.authentication.ProcessAuthentication.process(ProcessAuthentication.java:117)
at com.interactcrm.servlet.UserActions.processRequest(UserActions.java:93)
at com.interactcrm.servlet.UserActions.doPost(UserActions.java:136)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:648)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:230)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:192)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at com.interactcrm.sessionhandler.filter.SessionHandlerFilter.doFilter(SessionHandlerFilter.java:257)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:192)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:198)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:108)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:472)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79)
at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:620)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:349)
at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:784)
at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66)
at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:802)
at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1410)
at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:745)
2020-11-17 08:01:34,218 [https-jsse-nio-8080-exec-1] TRACE LucentV5TerminalExImpl - <-- getTerminalConnections[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@1a401fd4
2020-11-17 08:01:34,218 [https-jsse-nio-8080-exec-1] TRACE LucentV5TerminalExImpl - --> getAgents[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@1a401fd4
2020-11-17 08:01:34,218 [https-jsse-nio-8080-exec-1] INFO TsapiSession - Sent InvokeID 2902 for TSProvider[#1]@244f1051
MartinFlynn
Joined: Nov 30, 2009
Messages: 1922
Online
If you look through the trace for the string "EVENT Q SIZE", you will see that for the first few events/responses, it remains at 1. I read this as meaning that each event/response is delivered to, and processed by, the application before the next one arrives.

This changes at 08:00:34,268 when a CSTASnapshotDeviceConfEvent is received and the number increases with each event/response until it reaches 5 at 08:01:25,761. The exception is thrown soon afterwards.

So, it looks like the application has not been receiving and processing events during this time.

If we look at the events/responses just before the queue starts to build up, there are two CSTALoggedOnEvent events. One for agent 2660 and one for 2633. So it looks like one of these (probably the first one) is causing the application to get blocked.

The JTAPI client delivers events/responses to the application using JTAPI event thread. If this thread is blocked, the client is not able to deliver subsequent events to the application and a backlog will occur. So, I suspect that the application has received an Agent Logged On event and performs some processing in the callback which is causing this thread to become blocked.

Where an application performs significant work (or makes JTAPI API calls) after receiving an event/response, it must not do so from the callback (using the JTAPI event thread). Instead, it must place the incoming event/response onto an internal queue and process it using another thread.

Martin
Sushil
Joined: Sep 13, 2020
Messages: 29
Offline
Hi Martin,
I again gone through the logs and below are my observations:
Threads:

  • 1. https-jsse-nio-8080-exec-n JTAPI client application's (web/tomcat) thread sends the request to JTAPI.


  • 2. GetEventThread receives the event from AES to JTAPI.


  • 3. DistributeCSTAEvent sends the received AES response to JTAPI client application.


  • If above statements are correct then,
    JTAPI client application's (web/tomcat) thread sent the request to JTAPI with InvokeID 2898

    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] INFO TsapiSession - Sent InvokeID 2898 for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - CSTASetAgentState ::=
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - {
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - device "2573"
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - agentMode 0 < AM_LOG_IN >
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - agentID "2660"
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - agentGroup "2023"
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - agentPassword <null>
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - }
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - TsapiPrivate ::=
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - {
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - vendor "ECS"
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - data 2 0 57 0 30 6 a 1 1 2 1 0
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - tsType 89
    2020-11-17 08:00:24,689 [https-jsse-nio-8080-exec-14] DEBUG TsapiSession - }

    JTAPI library received a response from AES for InvokeID 2898

    2020-11-17 08:00:24,738 [GetEventThread] INFO TsapiSession - Received invokeID 2898 for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - CSTASetAgentStateConfEvent ::=
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - {
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - NULL
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - }
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - {
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - data 2 0 67 0 30 3 1 1 0
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - tsType 50
    2020-11-17 08:00:24,738 [GetEventThread] DEBUG TsapiSession - }
    2020-11-17 08:00:24,739 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTASetAgentStateConfEvent]@3160f118. EVENT Q SIZE = 1 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,739 [GetEventThread] INFO TsapiSession - Received monitorCrossRefID 31 for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - CSTALoggedOnEvent ::=
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - {
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - monitorCrossRefID 31
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - agentDevice
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - {
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - deviceID "2573"
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - deviceIDType 55 < EXPLICIT_PRIVATE_LOCAL_NUMBER >
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - deviceIDStatus 0 < ID_PROVIDED >
    2020-11-17 08:00:24,739 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTASetAgentStateConfEvent]@3160f118 for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - }
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - agentID "2660"
    2020-11-17 08:00:24,739 [DistributeCSTAEvent] DEBUG LucentPrivateData - LucentSetAgentStateConfEvent ::=
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - agentGroup "2004"
    2020-11-17 08:00:24,739 [DistributeCSTAEvent] DEBUG LucentPrivateData - {
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - password <null>
    2020-11-17 08:00:24,739 [DistributeCSTAEvent] DEBUG LucentPrivateData - isPending FALSE
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - }
    2020-11-17 08:00:24,739 [DistributeCSTAEvent] DEBUG LucentPrivateData - }
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=


    JTAPI library sent response to its client application (web/tomcat) for InvokeID 2898

    2020-11-17 08:00:24,739 [DistributeCSTAEvent] INFO TSInvokeID - Handling INVOKE ID 2898 for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - {
    2020-11-17 08:00:24,739 [DistributeCSTAEvent] INFO TSInvokeID - DONE handling INVOKE ID 2898 for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - data 2 0 30 0 30 3 a 1 1
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - tsType 72
    2020-11-17 08:00:24,739 [GetEventThread] DEBUG TsapiSession - }
    2020-11-17 08:00:24,739 [https-jsse-nio-8080-exec-14] INFO TSAgent - constructing TSAgent with agentKey=TSAgentKey(2573,2023,2660) for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,739 [https-jsse-nio-8080-exec-14] INFO TSAgent - ParentAgent Object TSAgentKey(2573,null,2660) being created for provider TSProvider[#1]@244f1051
    2020-11-17 08:00:24,739 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTALoggedOnEvent]@5714ed66. EVENT Q SIZE = 1 MAX Q SIZE = 4 for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TsapiEventQueue - Getting event CSTAEvent[CSTALoggedOnEvent]@5714ed66 for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] DEBUG TsapiAddressMonitor - Getting TsapiAddressMonitor lock to deliver events for listener com.interactcrm.telephony.observer.core.impl.TelephonyACDAddressListener@600b0ea7
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] DEBUG LucentPrivateData - LucentLoggedOnEvent ::=
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] DEBUG LucentPrivateData - {
    2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] TRACE LucentV7AgentImpl - com.avaya.jtapi.tsapi.impl.LucentV7AgentImpl@1c60c21d constructed.
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] DEBUG LucentPrivateData - workMode 1 < WM_AUX_WORK >
    2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] TRACE LucentACDAddressImpl - com.avaya.jtapi.tsapi.impl.LucentACDAddressImpl@660dcf00 constructed.
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] DEBUG LucentPrivateData - }
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSEventHandler - CSTA Unsolicited Event: CSTAEvent[CSTALoggedOnEvent]@5714ed66
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSEventHandler - Free memory: 525973736
    2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] DEBUG TsapiAddressMonitor - ADDRESSNOTREADYEVENT for TSAgent[TSAgentKey(2573,2023,2660)]@1c60c21d for listener com.interactcrm.telephony.observer.core.impl.TelephonyACDAddressListener@600b0ea7
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSEventHandler - Total memory: 919601152
    2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] TRACE LucentV7AgentImpl - com.avaya.jtapi.tsapi.impl.LucentV7AgentImpl@1c60c21d constructed.
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSEventHandler - Handling CSTA_LOGGED_ON for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,740 [https-jsse-nio-8080-exec-14] TRACE LucentACDAddressImpl - com.avaya.jtapi.tsapi.impl.LucentACDAddressImpl@660dcf00 constructed.
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSAgent - constructing TSAgent with agentKey=TSAgentKey(2573,2004,2660) for TSProvider[#1]@244f1051
    2020-11-17 08:00:24,740 [DistributeCSTAEvent] INFO TSAgent - ParentAgent Object TSAgentKey(2573,null,2660) being created for provider TSProvider[#1]@244f1051


    But after above last statement there is no processing statement for [DistributeCSTAEvent].

    After ~10 seconds JTAPI client application made a below request:

    2020-11-17 08:00:34,217 [https-jsse-nio-8080-exec-1] TRACE LucentV5TerminalExImpl - com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@1a401fd4 constructed.
    2020-11-17 08:00:34,217 [https-jsse-nio-8080-exec-1] TRACE LucentV11ProviderImpl - <-- getTerminal[String name]: com.avaya.jtapi.tsapi.impl.LucentV11ProviderImpl@244f1051
    2020-11-17 08:00:34,217 [https-jsse-nio-8080-exec-1] TRACE LucentV5TerminalExImpl - --> getTerminalConnections[]: com.avaya.jtapi.tsapi.impl.LucentV5TerminalExImpl@1a401fd4


    It sent a request with InvokeID 2900

    2020-11-17 08:00:34,217 [https-jsse-nio-8080-exec-1] INFO TsapiSession - Sent InvokeID 2900 for TSProvider[#1]@244f1051
    2020-11-17 08:00:34,218 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - CSTASnapshotDevice ::=
    2020-11-17 08:00:34,218 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - {
    2020-11-17 08:00:34,218 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - snapshotObject "6616"
    2020-11-17 08:00:34,218 [https-jsse-nio-8080-exec-1] DEBUG TsapiSession - }

    GetEventThread received a response from AES for InvokeID 2900

    2020-11-17 08:00:34,267 [GetEventThread] INFO TsapiSession - Received invokeID 2900 for TSProvider[#1]@244f1051
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - CSTASnapshotDeviceConfEvent ::=
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - {
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - snapshotData
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - {
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - }
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - }
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - TsapiPrivate ::=
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - {
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - vendor "ECS"
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - data 2 0 48 0 30 0
    2020-11-17 08:00:34,267 [GetEventThread] DEBUG TsapiSession - tsType 123
    2020-11-17 08:00:34,268 [GetEventThread] DEBUG TsapiSession - }
    2020-11-17 08:00:34,268 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTASnapshotDeviceConfEvent]@3f59329b. EVENT Q SIZE = 2 MAX Q SIZE = 4 for TSProvider[#1]@244f1051

    But there is no statement where DistributeCSTAEvent processed the response and after exact ONE min JTAPI thrown no conf event exception.

    After exact ONE minute JTAPI thrown below exception for thread https-jsse-nio-8080-exec-1 (who initiated the request)

    2020-11-17 08:01:25,761 [GetEventThread] INFO TsapiEventQueue - Putting event CSTAEvent[CSTASnapshotDeviceConfEvent]@38c61f06. EVENT Q SIZE = 5 MAX Q SIZE = 5 for TSProvider[#1]@244f1051
    2020-11-17 08:01:34,218 [https-jsse-nio-8080-exec-1] ERROR TSDevice - no conf event
    com.avaya.jtapi.tsapi.TsapiPlatformException: no conf event
    at com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession.send(TsapiSession.java:572)
    at com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession.send(TsapiSession.java:476)
    at com.avaya.jtapi.tsapi.tsapiInterface.Tsapi.snapshotDevice(Tsapi.java:1505)
    at com.avaya.jtapi.tsapi.impl.core.TSDevice.doCallSnapshot(TSDevice.java:2974)
    at com.avaya.jtapi.tsapi.impl.core.TSDevice.updateObject(TSDevice.java:2574)
    at com.avaya.jtapi.tsapi.impl.core.TSDevice.getTSTerminalConnections(TSDevice.java:384)
    at com.avaya.jtapi.tsapi.impl.TsapiTerminal.getTerminalConnections(TsapiTerminal.java:153)
    at com.interactcrm.telephony.session.core.impl.EASSession.login(EASSession.java:114)
    at com.interactcrm.telephony.application.core.impl.ApplicationImpl.login(ApplicationImpl.java:132)
    at com.interactcrm.telephony.connector.TelephonyConnector.login(TelephonyConnector.java:72)
    at com.interactcrm.telephony.connector.TelephonyServer.login(TelephonyServer.java:140)
    at com.interactcrm.mis.server.voiceserver.TelephonyCommunication.voiceLogin(TelephonyCommunication.java:287)
    at com.interactcrm.mis.server.agent.Agent.voiceLogin(Agent.java:67)
    at com.interactcrm.mis.server.agent.agentcontroller.impl.AgentController.voiceLogin(AgentController.java:148)
    at com.interactcrm.mis.server.agent.agentcontroller.impl.AgentController.channelLogin(AgentController.java:100)
    at com.interactcrm.mis.server.agent.agentcontroller.impl.AgentController.agentLogin(AgentController.java:378)
    at com.interactcrm.mis.server.agent.agentcontroller.impl.AgentController.login(AgentController.java:348)
    at com.interactcrm.mis.client.sdk.core.impl.ApplicationImpl.login(ApplicationImpl.java:242)
    at com.interactcrm.mis.client.sdk.core.impl.ApplicationImpl.login(ApplicationImpl.java:174)
    at com.interactcrm.sdk.LoginApp.doLogin(LoginApp.java:592)
    at com.interactcrm.sdk.LoginApp.login2(LoginApp.java:709)
    at com.interactcrm.absEvents.AbsLogin.doLogin(AbsLogin.java:168)
    at com.interactcrm.absEvents.AbsLogin.login(AbsLogin.java:95)
    at com.interactcrm.authentication.ProcessAuthentication.process(ProcessAuthentication.java:117)
    at com.interactcrm.servlet.UserActions.processRequest(UserActions.java:93)
    at com.interactcrm.servlet.UserActions.doPost(UserActions.java:136)


    Also we can see from thread dump, DistributeCSTAEvent thread went into BLOCKED state (when it went its not sure)

    [b]2020-11-17 08:13:20,973 [JTAPI ThreadDump thread#1] INFO ThreadDump - DistributeCSTAEvent: Daemon prio=5 tid=49 state=BLOCKED Alive [/b]
    2020-11-17 08:13:20,973 [JTAPI ThreadDump thread#1] INFO ThreadDump - at com.avaya.jtapi.tsapi.impl.core.ParentAgent.addSkill(ParentAgent.java:119)
    2020-11-17 08:13:20,973 [JTAPI ThreadDump thread#1] INFO ThreadDump - at com.avaya.jtapi.tsapi.impl.core.TSAgent.updateParentAgent(TSAgent.java:764)
    2020-11-17 08:13:20,973 [JTAPI ThreadDump thread#1] INFO ThreadDump - at com.avaya.jtapi.tsapi.impl.core.TSAgent.finishConstruction(TSAgent.java:628)
    2020-11-17 08:13:20,973 [JTAPI ThreadDump thread#1] INFO ThreadDump - at com.avaya.jtapi.tsapi.impl.core.TSProviderImpl.createAgent(TSProviderImpl.java:2305)
    2020-11-17 08:13:20,973 [JTAPI ThreadDump thread#1] INFO ThreadDump - at com.avaya.jtapi.tsapi.impl.core.TSEventHandler.doAgentEvents(TSEventHandler.java:2151)
    2020-11-17 08:13:20,973 [JTAPI ThreadDump thread#1] INFO ThreadDump - at com.avaya.jtapi.tsapi.impl.core.TSEventHandler.cstaUnsolicited(TSEventHandler.java:392)
    2020-11-17 08:13:20,973 [JTAPI ThreadDump thread#1] INFO ThreadDump - at com.avaya.jtapi.tsapi.tsapiInterface.TsapiEventDistributor.handleEvent(TsapiEventDistributor.java:113)
    2020-11-17 08:13:20,973 [JTAPI ThreadDump thread#1] INFO ThreadDump - at com.avaya.jtapi.tsapi.tsapiInterface.TsapiEventQueue.run(TsapiEventQueue.java:104)


    Could you please give some input/suggestion on above description.
    Please find attached JTAPI Trace log FYR.
    Filename vimtrace.txt.zip [Disk] Download
    MartinFlynn
    Joined: Nov 30, 2009
    Messages: 1922
    Online
    Hi Sushil,

    I have done as much analysis as I can in the forum. If you need further support, you should open a Technical Support Ticket and one of my colleagues will try to help.

    Martin
    Go to:   
    Mobile view