Hi experts,
we are building a custom CTI application using JTAPI (ecsjtapia 7.1.0.8), that performs a Call.connect(origTerminal, origAddress, destination) API call in order to make a new call from an agent extension to a VDN number. According to the detailed TSAPI logs, even though we invoke Call.connect with same parameters, the underlying CSTA message sequence might differ on sending either CSTAMonitorDevice or CSTAMonitorCallsViaDevice request to AES. When JTAPI decides to use CSTAMonitorDevice the call fails with CSTA error 12, when CSTAMonitorCallsViaDevice is invoked the call is successfully made. The destination in both cases is 153989 (VDN).
Can you help understanding how the SDK makes the decision between CSTAMonitorDevice/CSTAMonitorCallsViaDevice? See the logs below for reference for both successful and failing scenarios.
Thanks, Sandor
Successful Call.connect (using CSTAMonitorCallsViaDevice internally)
--------------------
2019-12-10 15:09:35.770 [pool-17-thread-1] INFO com.avaya.jtapi.tsapi.impl.core.TSCall - Constructing call TSCall[0(nonCallID=0)]@7d085402 with ID 0 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.771 [pool-17-thread-1] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Sent InvokeID 546 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.771 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAQueryDeviceInfo ::=
2019-12-10 15:09:35.771 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.771 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - device "153989"
2019-12-10 15:09:35.771 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.816 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Received invokeID 546 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAQueryDeviceInfoConfEvent ::=
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - device "153989"
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - deviceType 19 < DT_ACD_GROUP >
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - deviceClass 0x80000000 < DC_VOICE >
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - TsapiPrivate ::=
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - vendor "ECS"
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - data 2 0 62 0 30 8 a 1 0 a 1 a 16 0
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - tsType 38
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - LucentV5QueryDeviceInfoConfEvent ::=
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - {
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - extensionClass 0 < EC_VDN >
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - associatedClass 10 < EC_OTHER >
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - associatedDevice <null>
2019-12-10 15:09:35.816 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - }
2019-12-10 15:09:35.816 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - Handling INVOKE ID 546 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.816 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - DONE handling INVOKE ID 546 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.817 [pool-17-thread-1] INFO com.avaya.jtapi.tsapi.impl.core.TSCall - recordVDNDomainEntry: -- entering VDN domain for Address 153989 - wasEverMonitoredByCallsViaDevice=false refVDN=null
2019-12-10 15:09:35.817 [pool-17-thread-1] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Sent InvokeID 547 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAMonitorCallsViaDevice ::=
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - deviceID "153989"
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - monitorFilter
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - call 0x0 < >
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - feature 0x0 < >
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - agent 0x0 < >
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - maintenance 0x0 < >
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - privateFilter 0
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.817 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.867 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Received invokeID 547 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAMonitorConfEvent ::=
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - monitorCrossRefID 16
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - monitorFilter
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - call 0x480000 < CF_ORIGINATED CF_SERVICE_INITIATED >
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - feature 0xf0000000 < FF_CALL_INFORMATION FF_DO_NOT_DISTURB FF_FORWARDING FF_MESSAGE_WAITING >
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - agent 0xfc000000 < AF_LOGGED_ON AF_LOGGED_OFF AF_NOT_READY AF_READY AF_WORK_NOT_READY AF_WORK_READY >
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - maintenance 0xc0000000 < MF_BACK_IN_SERVICE MF_OUT_OF_SERVICE >
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - privateFilter 0
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - TsapiPrivate ::=
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - vendor "ECS"
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - data 2 0 5d 0 30 4 3 2 4 30
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - tsType 114
2019-12-10 15:09:35.867 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.867 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - Handling INVOKE ID 547 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.867 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - DONE handling INVOKE ID 547 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.867 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - LucentMonitorConfEvent ::=
2019-12-10 15:09:35.867 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - {
2019-12-10 15:09:35.867 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - usedFilter 0x30000000 < ATT_ENDPOINT_REGISTERED_FILTER ATT_ENDPOINT_UNREGISTERED_FILTER >
2019-12-10 15:09:35.867 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - }
2019-12-10 15:09:35.867 [pool-17-thread-1] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Sent InvokeID 548 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.867 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAMakeCall ::=
2019-12-10 15:09:35.867 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.867 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - callingDevice "248604"
2019-12-10 15:09:35.867 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - calledDevice "153989"
2019-12-10 15:09:35.867 [pool-17-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.919 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Received invokeID 548 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAMakeCallConfEvent ::=
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - newCall
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - callID 9779
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - deviceID "248604"
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - devIDType 0 < STATIC_ID >
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - TsapiPrivate ::=
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - vendor "ECS"
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - data 2 0 55 0 30 16 16 14 30 30 30 30 31 30 39 37 37 39 31 35 37 36 30 30 38 35 37 35
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - tsType 24
2019-12-10 15:09:35.920 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:09:35.920 [GetEventThread] INFO c.avaya.jtapi.tsapi.tsapiInterface.TsapiEventQueue - Putting event CSTAEvent[CSTAMakeCallConfEvent]@3da597c. EVENT Q SIZE = 1 MAX Q SIZE = 3 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.920 [DistributeCSTAEvent] INFO c.avaya.jtapi.tsapi.tsapiInterface.TsapiEventQueue - Getting event CSTAEvent[CSTAMakeCallConfEvent]@3da597c for TSProvider[#1]@3d500794
2019-12-10 15:09:35.920 [DistributeCSTAEvent] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - LucentMakeCallConfEvent ::=
2019-12-10 15:09:35.920 [DistributeCSTAEvent] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - {
2019-12-10 15:09:35.920 [DistributeCSTAEvent] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - ucid "00001097791576008575"
2019-12-10 15:09:35.920 [DistributeCSTAEvent] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - }
2019-12-10 15:09:35.920 [DistributeCSTAEvent] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - Handling INVOKE ID 548 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.920 [DistributeCSTAEvent] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - DONE handling INVOKE ID 548 for TSProvider[#1]@3d500794
2019-12-10 15:09:35.920 [pool-17-thread-1] INFO com.avaya.jtapi.tsapi.impl.core.TSCall - TSCall.connect: finished makeCall for Call ID 9779
2019-12-10 15:09:35.920 [pool-17-thread-1] INFO com.avaya.jtapi.tsapi.impl.core.TSCall - TSCall.connect: about to call connectFinish() for callID 9779
2019-12-10 15:09:35.920 [pool-17-thread-1] INFO com.avaya.jtapi.tsapi.impl.core.TSCall - TSCall.connect: about to wait for 2 connections for callID 9779
Failing Call.connect (using CSTAMonitorDevice internally)
-----------------------------------------------------------
2019-12-10 15:10:24.262 [pool-26-thread-1] INFO com.avaya.jtapi.tsapi.impl.core.TSCall - Constructing call TSCall[0(nonCallID=10)]@b54f9a6 with ID 0 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.262 [pool-26-thread-1] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Sent InvokeID 517 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.262 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAQueryDeviceInfo ::=
2019-12-10 15:10:24.262 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:10:24.262 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - device "153989"
2019-12-10 15:10:24.262 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:10:24.308 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Received invokeID 517 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAQueryDeviceInfoConfEvent ::=
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - device "153989"
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - deviceType 19 < DT_ACD_GROUP >
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - deviceClass 0x80000000 < DC_VOICE >
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - TsapiPrivate ::=
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - vendor "ECS"
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - data 2 0 62 0 30 8 a 1 0 a 1 a 16 0
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - tsType 38
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - LucentV5QueryDeviceInfoConfEvent ::=
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - {
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - extensionClass 0 < EC_VDN >
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - associatedClass 10 < EC_OTHER >
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - associatedDevice <null>
2019-12-10 15:10:24.308 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.csta1.LucentPrivateData - }
2019-12-10 15:10:24.308 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - Handling INVOKE ID 517 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.308 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - DONE handling INVOKE ID 517 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.308 [pool-26-thread-1] INFO com.avaya.jtapi.tsapi.impl.core.TSCall - recordVDNDomainEntry: -- entering VDN domain for Address 153989 - wasEverMonitoredByCallsViaDevice=false refVDN=null
2019-12-10 15:10:24.308 [pool-26-thread-1] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Sent InvokeID 518 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.308 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAMonitorDevice ::=
2019-12-10 15:10:24.308 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:10:24.308 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - deviceID "153989"
2019-12-10 15:10:24.308 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - monitorFilter
2019-12-10 15:10:24.309 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:10:24.309 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - call 0x0 < >
2019-12-10 15:10:24.309 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - feature 0x0 < >
2019-12-10 15:10:24.309 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - agent 0x0 < >
2019-12-10 15:10:24.309 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - maintenance 0x0 < >
2019-12-10 15:10:24.309 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - privateFilter 0
2019-12-10 15:10:24.309 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:10:24.309 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:10:24.411 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Received invokeID 518 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.411 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAUniversalFailureConfEvent ::=
2019-12-10 15:10:24.411 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:10:24.411 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - error 12 < INVALID_CSTA_DEVICE_IDENTIFIER >
2019-12-10 15:10:24.411 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:10:24.411 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - Handling INVOKE ID 518 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.411 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - DONE handling INVOKE ID 518 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.411 [pool-26-thread-1] ERROR com.avaya.jtapi.tsapi.impl.core.TSDevice - MonitorDevice request failed - retrying
com.avaya.jtapi.tsapi.TsapiPlatformException: CSTA Error: 12
at com.avaya.jtapi.tsapi.tsapiInterface.TSErrorMap.throwCSTAException(TSErrorMap.java:100)
at com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession.send(TsapiSession.java:577)
at com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession.send(TsapiSession.java:453)
at com.avaya.jtapi.tsapi.tsapiInterface.Tsapi.monitorDevice(Tsapi.java:1138)
at com.avaya.jtapi.tsapi.impl.core.TSDevice.setMonitor(TSDevice.java:2696)
at com.avaya.jtapi.tsapi.impl.core.TSDevice.setMonitor(TSDevice.java:2618)
at com.avaya.jtapi.tsapi.impl.core.TSCall.connect(TSCall.java:733)
at com.avaya.jtapi.tsapi.impl.TsapiCall.connect(TsapiCall.java:198)
at com.avaya.ept.ccc.call.controller.rest.api.services.cti.listener.conference.ConferenceManager.createConsultCall(ConferenceManager.java:58)
at com.avaya.ept.ccc.call.controller.rest.api.services.cti.listener.call.CallEventHandlerImpl.processHeld(CallEventHandlerImpl.java:196)
at com.avaya.ept.ccc.call.controller.rest.api.services.cti.listener.call.CallListener.lambda$null$9(CallListener.java:81)
at com.avaya.ept.datagrid.base.DatagridDistributedLock.lambda$execute$1(DatagridDistributedLock.java:34)
at com.avaya.ept.datagrid.hazelcast.HazelcastDistributedLock.get(HazelcastDistributedLock.java:79)
at com.avaya.ept.datagrid.base.DatagridDistributedLock.execute(DatagridDistributedLock.java:33)
at com.avaya.ept.datagrid.base.DatagridDistributedLock.execute(DatagridDistributedLock.java:29)
at com.avaya.ept.datagrid.base.DatagridDistributedLock.execute(DatagridDistributedLock.java:25)
at com.avaya.ept.ccc.call.controller.rest.api.services.cti.listener.call.CallListener.lambda$null$10(CallListener.java:80)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
2019-12-10 15:10:24.412 [pool-26-thread-1] INFO com.avaya.jtapi.tsapi.impl.core.TSDevice - MonitorDevice request failed - retrying
2019-12-10 15:10:24.412 [pool-26-thread-1] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Sent InvokeID 519 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAMonitorDevice ::=
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - deviceID "153989"
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - monitorFilter
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - call 0x0 < >
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - feature 0x0 < >
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - agent 0x0 < >
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - maintenance 0x0 < >
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - privateFilter 0
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:10:24.412 [pool-26-thread-1] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:10:24.515 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - Received invokeID 519 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.515 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - CSTAUniversalFailureConfEvent ::=
2019-12-10 15:10:24.515 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - {
2019-12-10 15:10:24.515 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - error 12 < INVALID_CSTA_DEVICE_IDENTIFIER >
2019-12-10 15:10:24.515 [GetEventThread] DEBUG com.avaya.jtapi.tsapi.tsapiInterface.TsapiSession - }
2019-12-10 15:10:24.515 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - Handling INVOKE ID 519 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.515 [GetEventThread] INFO com.avaya.jtapi.tsapi.tsapiInterface.TSInvokeID - DONE handling INVOKE ID 519 for TSProvider[#1]@5325ac62
2019-12-10 15:10:24.516 [pool-26-thread-1] ERROR c.a.e.c.c.c.r.a.s.c.l.conference.ConferenceManager - [00001099911576008592/248604] Create consult call failed
com.avaya.jtapi.tsapi.TsapiResourceUnavailableException: failure to monitor device
at com.avaya.jtapi.tsapi.impl.core.TSDevice.setMonitor(TSDevice.java:2722)
at com.avaya.jtapi.tsapi.impl.core.TSDevice.setMonitor(TSDevice.java:2618)
at com.avaya.jtapi.tsapi.impl.core.TSCall.connect(TSCall.java:733)
at com.avaya.jtapi.tsapi.impl.TsapiCall.connect(TsapiCall.java:198)
at com.avaya.ept.ccc.call.controller.rest.api.services.cti.listener.conference.ConferenceManager.createConsultCall(ConferenceManager.java:58)
at com.avaya.ept.ccc.call.controller.rest.api.services.cti.listener.call.CallEventHandlerImpl.processHeld(CallEventHandlerImpl.java:196)
at com.avaya.ept.ccc.call.controller.rest.api.services.cti.listener.call.CallListener.lambda$null$9(CallListener.java:81)
at com.avaya.ept.datagrid.base.DatagridDistributedLock.lambda$execute$1(DatagridDistributedLock.java:34)
at com.avaya.ept.datagrid.hazelcast.HazelcastDistributedLock.get(HazelcastDistributedLock.java:79)
at com.avaya.ept.datagrid.base.DatagridDistributedLock.execute(DatagridDistributedLock.java:33)
at com.avaya.ept.datagrid.base.DatagridDistributedLock.execute(DatagridDistributedLock.java:29)
at com.avaya.ept.datagrid.base.DatagridDistributedLock.execute(DatagridDistributedLock.java:25)
at com.avaya.ept.ccc.call.controller.rest.api.services.cti.listener.call.CallListener.lambda$null$10(CallListener.java:80)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
