you must use a 1st party device ID as the calling party - a local station on CM that you have done a getDeviceID request on through AE Services. You are using a 3rd party device ID address.

917195551212:swlink1::0

Good catch. I had monitored 8001 but didn't set the device Id in the dashboard. Here's the log with 8001 set.

<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 228 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaUnmarshallerNode FINEST - Unmarshalled xml=<?xml version="1.0" encoding="utf-8"?>
<MakeCall xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3">
<callingDevice typeOfNumber="other" mediaClass="notKnown">8001:swlink1:172.30.200.102:1</callingDevice>
<calledDirectoryNumber typeOfNumber="other" mediaClass="notKnown">917192911662:swlink1::0</calledDi
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 228 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaUnmarshallerNode FINEST - rectoryNumber>
<callCharacteristics>
<priorityCall>false</priorityCall>
</callCharacteristics>
</MakeCall> to object=session[null] ch.ecma.csta.binding.MakeCall@1774847b
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 228 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.SubscriptionManager FINER - adding EventSubscriber to channel=async[/172.30.200.110:4721/172.20.5.250:56813]:16
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 228 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.SubscriptionManager FINER - return from adding EventSubscriber to channel=async[/172.30.200.110:4721/172.20.5.250:56813]:16 took 0ms
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 228 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.SubscriptionManager FINER - after tryCatch adding EventSubscriber to channel=async[/172.30.200.110:4721/172.20.5.250:56813]:16 took 0ms
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 228 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaRouterNode FINER - Adding: Subscription Manager count= 7 request=session[null] ch.ecma.csta.binding.MakeCall@1774847b channel=async[/172.30.200.110:4721/172.20.5.250:56813]:16
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 228 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaRouterNode FINER - Returned from processing an AsynchronousRequest session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 231 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.station.h323.q931.DisplayPhoneDivider FINE - [swlink1:172.30.200.102:8001:1] hookswitchEvent: false
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 231 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.station.h323.q931.H323PhysicalDevice FINE - [swlink1:172.30.200.102:8001:1] OffHook
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 231 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.h323.Q931Messages FINE - encodeNonStandardDataMessage: {8B,1F,40,0A,60,86,48,01,86,FC,0B,01,06,02,05,35,08,F0,F4,5C,0C,5B,AE,27,D7,0C,8C,A5,F7,00,00,00,15}
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.h323.MsgStream FINE - Q.931 Message
{
Call Reference Value: 1
Message Type: INFORMATION
h323_uu_pdu:
{
h323_message_body:
{
empty:
}

h245Tunneling: false
nonStandardControl:
[{
nonStandardIdentifier:
{ 2.16.840.1.113778.4.7.1.2 }
data: 8B 1F 40 0A 60 86 48 01 86 FC 0B 01 06 02 05 35 08 F0 F4 5C 0C 5B AE 27 D7 0C 8C A5 F7 00 00 00 15
}

<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.h323.MsgStream FINE - ]
}

}

<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.station.h323.q931.DisplayPhoneDivider FINE - [swlink1:172.30.200.102:8001:1] serviceLinkUpEvent: true
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.terminal.h323.TerminalH323 FINE - [swlink1:172.30.200.102:8001:1] ServiceLinkUpEvent received: true
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.terminal.h323.TerminalH323 FINE - [swlink1:172.30.200.102:8001:1] RingerEvent received: 0
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.terminal.h323.TerminalH323 FINE - [swlink1:172.30.200.102:8001:1] DisplayUpdate received: [a= ]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - Handling a class com.avaya.csta.binding.ServiceLinkStatusEvent, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber executing, time in pendingEventSubscribeTasks queue=0ms
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [Begin] payload=class com.avaya.csta.binding.ServiceLinkStatusEvent, id=9999, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshaller: NO_MAPPING, protocolVersion: http://www.ecma-international.org/standards/ecma-323/csta/ed3/privF
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 232 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINER - Packaging a session[null] com.avaya.csta.binding.ServiceLinkStatusEvent@559dcc80
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 233 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshalled session[null] com.avaya.csta.binding.ServiceLinkStatusEvent@559dcc80 to <?xml version="1.0" encoding="UTF-8"?>
<ServiceLinkStatusEvent xmlns="http://www.avaya.com/csta"><ns1:monitorCrossRefID xmlns:ns1="http://www.ecma-international.org/standards/ecma-323/csta/ed3">518</ns1:monitorCrossRefID><device><ns2:deviceIdentifier xmlns:ns2="http://www.ecma-international.org/standards/ecma-323/csta/ed3" typeOfNumber="other" mediaClass="voice" bi
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 233 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - tRate="constant">8001:swlink1:172.30.200.102:1</ns2:deviceIdentifier></device><serviceLinkUp>true</serviceLinkUp></ServiceLinkStatusEvent>
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 233 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINER - Writing ByteBuffer=java.nio.HeapByteBuffer[pos=0 lim=513 cap=513], session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 233 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINEST - Wrote 513 of 513 bytes to the Channel, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 233 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - ClearableQueuedExecutor-1: Duration of marshalling = 1msec and null publishing = 0msecs, session[null] com.avaya.csta.binding.ServiceLinkStatusEvent@559dcc80
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 233 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [End]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 233 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber finished executing, took 1ms from time added to pendingEventSubscribeTasks queue to finish
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 234 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.terminal.h323.TerminalH323 FINE - [swlink1:172.30.200.102:8001:1] DisplayUpdate received: [ ]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 234 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.terminal.h323.TerminalH323 FINE - [swlink1:172.30.200.102:8001:1] DisplayUpdate received: [a= ]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 273 1 com.avaya.aes | :DistributeCSTAEvent: com.avaya.mvcs.proxy.SubscriptionManager FINER - AsyncEventSubscriber SubscriptionEvent created for: ch.ecma.csta.binding.MakeCallResponse object=<generictuple><data><Unique Id>16</Unique Id><Command History List>[]</Command History List><Payload>session[session 4EFBADAD16CE84E3D9567666259EDEC1-61] ch.ecma.csta.binding.MakeCallResponse@b9e8100</Payload></data></generictuple>
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 273 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - Handling a class com.avaya.workflow.MessageContextTuple, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]] invokeID=16
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 273 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - AsyncEventSubscriber executing: ch.ecma.csta.binding.MakeCallResponse time in pendingAsyncEventSubscribeTasks queue=0ms
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 273 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [Begin] payload=class ch.ecma.csta.binding.MakeCallResponse, id=16, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 273 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshaller: NO_MAPPING, protocolVersion: http://www.ecma-international.org/standards/ecma-323/csta/ed3/privF
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 273 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINER - Packaging a session[session 4EFBADAD16CE84E3D9567666259EDEC1-61] ch.ecma.csta.binding.MakeCallResponse@b9e8100
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 273 1 com.avaya.aes | :DistributeCSTAEvent: com.avaya.mvcs.proxy.ClearableQueuedExecutor FINEST - DistributeCSTAEvent queued tasks=2 after adding com.avaya.mvcs.proxy.SubscriptionManager$EventSubscriber$SubscriptionEvent@697b0e4f
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 273 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshalled session[session 4EFBADAD16CE84E3D9567666259EDEC1-61] ch.ecma.csta.binding.MakeCallResponse@b9e8100 to <?xml version="1.0" encoding="UTF-8"?>
<MakeCallResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><callingDevice><callID>15296</callID><deviceID typeOfNumber="other" mediaClass="notKnown" bitRate="constant">8001:SWLINK1::0</deviceID></callingDevice></MakeCallResponse>
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 273 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINER - Writing ByteBuffer=java.nio.HeapByteBuffer[pos=0 lim=305 cap=305], session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINEST - Wrote 305 of 305 bytes to the Channel, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - ClearableQueuedExecutor-1: Duration of marshalling = 0msec and null publishing = 1msecs, session[session 4EFBADAD16CE84E3D9567666259EDEC1-61] ch.ecma.csta.binding.MakeCallResponse@b9e8100
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [End]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - AsyncEventSubscriber finished executing: ch.ecma.csta.binding.MakeCallResponse, took 1ms from time added to pendingAsyncEventSubscribeTasks queue to finish
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - Removing EventSubscriber from channel=async[/172.30.200.110:4721/172.20.5.250:56813]:16
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - return from removing EventSubscriber from channel=async[/172.30.200.110:4721/172.20.5.250:56813]:16 took 0ms
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - after tryCatch removing EventSubscriber to channel=async[/172.30.200.110:4721/172.20.5.250:56813]:16 took 0ms
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - Removing: Subscription Manager count= 6 channel=async[/172.30.200.110:4721/172.20.5.250:56813]:16 for request=session[null] ch.ecma.csta.binding.MakeCall@1774847b
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - Handling a class ch.ecma.csta.binding.ServiceInitiatedEvent, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber executing, time in pendingEventSubscribeTasks queue=1ms
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [Begin] payload=class ch.ecma.csta.binding.ServiceInitiatedEvent, id=9999, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshaller: NO_MAPPING, protocolVersion: http://www.ecma-international.org/standards/ecma-323/csta/ed3/privF
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINER - Packaging a session[null] ch.ecma.csta.binding.ServiceInitiatedEvent@2b0bd7fc
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshalled session[null] ch.ecma.csta.binding.ServiceInitiatedEvent@2b0bd7fc to <?xml version="1.0" encoding="UTF-8"?>
<ServiceInitiatedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><monitorCrossRefID>520</monitorCrossRefID><initiatedConnection><callID>15296</callID><deviceID typeOfNumber="other" mediaClass="notKnown" bitRate="constant">8001:SWLINK1::0</deviceID></initiatedConnection><initiatingDevice><deviceIdentifi
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - er typeOfNumber="other" mediaClass="notKnown" bitRate="constant">8001:swlink1:172.30.200.102:1</deviceIdentifier></initiatingDevice><localConnectionInfo>initiated</localConnectionInfo><cause>normal</cause></ServiceInitiatedEvent>
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINER - Writing ByteBuffer=java.nio.HeapByteBuffer[pos=0 lim=607 cap=607], session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINEST - Wrote 607 of 607 bytes to the Channel, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - ClearableQueuedExecutor-1: Duration of marshalling = 0msec and null publishing = 0msecs, session[null] ch.ecma.csta.binding.ServiceInitiatedEvent@2b0bd7fc
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [End]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber finished executing, took 1ms from time added to pendingEventSubscribeTasks queue to finish
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - Handling a class ch.ecma.csta.binding.FailedEvent, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber executing, time in pendingEventSubscribeTasks queue=1ms
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [Begin] payload=class ch.ecma.csta.binding.FailedEvent, id=9999, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshaller: NO_MAPPING, protocolVersion: http://www.ecma-international.org/standards/ecma-323/csta/ed3/privF
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 274 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINER - Packaging a session[null] ch.ecma.csta.binding.FailedEvent@320af1ac
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 275 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshalled session[null] ch.ecma.csta.binding.FailedEvent@320af1ac to <?xml version="1.0" encoding="UTF-8"?>
<FailedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><monitorCrossRefID>520</monitorCrossRefID><failedConnection><callID>15296</callID><deviceID typeOfNumber="other" mediaClass="notKnown" bitRate="constant">917192911662:SWLINK1::0</deviceID></failedConnection><failingDevice><deviceIdentifier typeOfNumber="impl
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 275 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - icitPublic" mediaClass="notKnown" bitRate="constant">917192911662:SWLINK1::0</deviceIdentifier></failingDevice><callingDevice><deviceIdentifier typeOfNumber="other" mediaClass="notKnown" bitRate="constant">8001:swlink1:172.30.200.102:1</deviceIdentifier></callingDevice><calledDevice><deviceIdentifier typeOfNumber="implicitPublic" mediaClass="notKnown" bitRate="constant">91719291:SWLINK1::0</deviceIdentifier></calledDevice><lastRedirectionDevice><
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 275 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - notKnown/></lastRedirectionDevice><localConnectionInfo>fail</localConnectionInfo><cause>destNotObtainable</cause><deviceHistory><DeviceHistoryListItem><oldDeviceID><numberDialed typeOfNumber="other" mediaClass="notKnown" bitRate="constant">91719291:SWLINK1::0</numberDialed></oldDeviceID><eventCause>destNotObtainable</eventCause><oldConnectionID><callID>15296</callID><deviceID typeOfNumber="other" mediaClass="notKnown" bitRate="constant">91719291:
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 275 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - SWLINK1::0</deviceID></oldConnectionID></DeviceHistoryListItem></deviceHistory></FailedEvent>
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 275 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINER - Writing ByteBuffer=java.nio.HeapByteBuffer[pos=0 lim=1381 cap=1381], session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 275 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINEST - Wrote 1381 of 1381 bytes to the Channel, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 275 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - ClearableQueuedExecutor-1: Duration of marshalling = 1msec and null publishing = 0msecs, session[null] ch.ecma.csta.binding.FailedEvent@320af1ac
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 275 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [End]
<134>May 10 02:01:46 wiavaes01 DmccMain[180590] +07:00 2023 275 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber finished executing, took 2ms from time added to pendingEventSubscribeTasks queue to finish
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 240 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.station.h323.q931.DisplayPhoneDivider FINE - [swlink1:172.30.200.102:8001:1] hookswitchEvent: true
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 240 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.station.h323.q931.H323PhysicalDevice FINE - [swlink1:172.30.200.102:8001:1] OnHook
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 240 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.h323.Q931Messages FINE - encodeNonStandardDataMessage: {8B,1F,40,0A,60,86,48,01,86,FC,0B,01,06,02,05,F8,51,2F,B3,F1,0C,5B,AE,27,D7,0C,8C,A5,F7,00,00,00,18}
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 240 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.h323.MsgStream FINE - Q.931 Message
{
Call Reference Value: 1
Message Type: INFORMATION
h323_uu_pdu:
{
h323_message_body:
{
empty:
}

h245Tunneling: false
nonStandardControl:
[{
nonStandardIdentifier:
{ 2.16.840.1.113778.4.7.1.2 }
data: 8B 1F 40 0A 60 86 48 01 86 FC 0B 01 06 02 05 F8 51 2F B3 F1 0C 5B AE 27 D7 0C 8C A5 F7 00 00 00 18
}

<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 240 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.h323.MsgStream FINE - ]
}

}

<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 240 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.terminal.h323.TerminalH323 FINE - [swlink1:172.30.200.102:8001:1] DisplayUpdate received: [ ]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 241 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.station.h323.q931.DisplayPhoneDivider FINE - [swlink1:172.30.200.102:8001:1] serviceLinkUpEvent: false
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 241 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.terminal.h323.TerminalH323 FINE - [swlink1:172.30.200.102:8001:1] ServiceLinkUpEvent received: false
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 241 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - Handling a class com.avaya.csta.binding.ServiceLinkStatusEvent, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 241 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber executing, time in pendingEventSubscribeTasks queue=0ms
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 241 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [Begin] payload=class com.avaya.csta.binding.ServiceLinkStatusEvent, id=9999, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 241 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshaller: NO_MAPPING, protocolVersion: http://www.ecma-international.org/standards/ecma-323/csta/ed3/privF
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 241 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINER - Packaging a session[null] com.avaya.csta.binding.ServiceLinkStatusEvent@6a485d32
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 241 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshalled session[null] com.avaya.csta.binding.ServiceLinkStatusEvent@6a485d32 to <?xml version="1.0" encoding="UTF-8"?>
<ServiceLinkStatusEvent xmlns="http://www.avaya.com/csta"><ns1:monitorCrossRefID xmlns:ns1="http://www.ecma-international.org/standards/ecma-323/csta/ed3">518</ns1:monitorCrossRefID><device><ns2:deviceIdentifier xmlns:ns2="http://www.ecma-international.org/standards/ecma-323/csta/ed3" typeOfNumber="other" mediaClass="voice" bi
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 241 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - tRate="constant">8001:swlink1:172.30.200.102:1</ns2:deviceIdentifier></device><serviceLinkUp>false</serviceLinkUp></ServiceLinkStatusEvent>
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 242 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINER - Writing ByteBuffer=java.nio.HeapByteBuffer[pos=0 lim=514 cap=514], session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 242 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINEST - Wrote 514 of 514 bytes to the Channel, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 242 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - ClearableQueuedExecutor-1: Duration of marshalling = 0msec and null publishing = 1msecs, session[null] com.avaya.csta.binding.ServiceLinkStatusEvent@6a485d32
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 242 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [End]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 242 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber finished executing, took 1ms from time added to pendingEventSubscribeTasks queue to finish
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 248 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - Handling a class ch.ecma.csta.binding.ConnectionClearedEvent, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 248 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber executing, time in pendingEventSubscribeTasks queue=0ms
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 248 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [Begin] payload=class ch.ecma.csta.binding.ConnectionClearedEvent, id=9999, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 248 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshaller: NO_MAPPING, protocolVersion: http://www.ecma-international.org/standards/ecma-323/csta/ed3/privF
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 248 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINER - Packaging a session[null] ch.ecma.csta.binding.ConnectionClearedEvent@518a6537
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 249 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshalled session[null] ch.ecma.csta.binding.ConnectionClearedEvent@518a6537 to <?xml version="1.0" encoding="UTF-8"?>
<ConnectionClearedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><monitorCrossRefID>520</monitorCrossRefID><droppedConnection><callID>15296</callID><deviceID typeOfNumber="other" mediaClass="notKnown" bitRate="constant">8001:SWLINK1::0</deviceID></droppedConnection><releasingDevice><deviceIdentifier
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 249 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - typeOfNumber="other" mediaClass="notKnown" bitRate="constant">8001:swlink1:172.30.200.102:1</deviceIdentifier></releasingDevice><localConnectionInfo>queued</localConnectionInfo><cause>normalClearing</cause><deviceHistory><DeviceHistoryListItem><oldDeviceID><numberDialed typeOfNumber="other" mediaClass="notKnown" bitRate="constant">8001:SWLINK1::0</numberDialed></oldDeviceID><eventCause>normal</eventCause><oldConnectionID><callID>15296</callID><de
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 249 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - viceID typeOfNumber="other" mediaClass="notKnown" bitRate="constant">8001:SWLINK1::0</deviceID></oldConnectionID></DeviceHistoryListItem></deviceHistory></ConnectionClearedEvent>
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 249 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINER - Writing ByteBuffer=java.nio.HeapByteBuffer[pos=0 lim=1005 cap=1005], session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 249 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINEST - Wrote 1005 of 1005 bytes to the Channel, session=session[session 4EFBADAD16CE84E3D9567666259EDEC1-61,[/172.30.200.110:4721/172.20.5.250:56813]]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 249 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - ClearableQueuedExecutor-1: Duration of marshalling = 1msec and null publishing = 0msecs, session[null] ch.ecma.csta.binding.ConnectionClearedEvent@518a6537
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 249 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [End]
<134>May 10 02:02:31 wiavaes01 DmccMain[180590] +07:00 2023 249 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - EventSubscriber finished executing, took 1ms from time added to pendingEventSubscribeTasks queue to finish
<134>May 10 02:03:36 wiavaes01 DmccMain[180590] +07:00 2023 092 1 com.avaya.aes | :RMI TCP Connection(133574)-172.30.200.110: com.avaya.mvap.mbeans.wtisvc.WtiService INFO - [ENTER]WTI Java Service Wrapper Controller
<134>May 10 02:03:36 wiavaes01 DmccMain[180590] +07:00 2023 097 1 com.avaya.aes | :RMI TCP Connection(133574)-172.30.200.110: com.avaya.mvap.mbeans.wtisvc.WtiService INFO - [RETURN]
<134>May 10 02:03:40 wiavaes01 DmccMain[180590] +07:00 2023 081 1 com.avaya.aes | :RMI TCP Connection(133574)-172.30.200.110: com.avaya.mvap.mbeans.wtisvc.WtiService INFO - [ENTER]WTI Java Service Wrapper Controller
<134>May 10 02:03:40 wiavaes01 DmccMain[180590] +07:00 2023 084 1 com.avaya.aes | :RMI TCP Connection(133574)-172.30.200.110: com.avaya.mvap.mbeans.wtisvc.WtiService INFO - [RETURN]

1) does the SST work if you replace the pstn number with a on-PBX number e.g. 8002?
2) If you enable TSAPI tracing, and access the g3trace logfile, does it show activity when the SST is processed? If so what?

I am starting to wonder if SST supports calling out to a off-PBX number or not. I started looking for evidence of that but have not run across that statement (yet?).

one other thought is to do a SAT "list trace station xxxx" where xxxx is the station that you are invoking the transfer against in an attempt to learn more about the source of the error.

I was able to find a thread that said SST to an external number works. Sorry but my test bed is disassembled due to a ongoing move.

Given the MakeCall() seems to have worked, I am a bit perplexed. What are you using for the inbound call? a local station to station call or something else (inbound trunk)? I suggest trying with the first call being station to station, but I would anticipate a different type of error would be thrown.

Ignore the below. I was responding to a post from earlier

Your CALLING and CALLED numbers are both the same - 917195551212:swlink1::0. That is never good.

Is 917195551212 an extension on CM? If not and it's actually the number to dial for an external phone then this would explain the error you are seeing.

Martin

"one other thought is to do a SAT "list trace station xxxx" where xxxx is the station that you are invoking the transfer against in an attempt to learn more about the source of the error."
Where do I do that from? I have the Site Administration software but when I do that in the GEDI, it tells me "Command not supported by GEDI".

"What are you using for the inbound call? a local station to station call or something else (inbound trunk)?"
It's an inbound trunk going to an analog extension (1001). A piece of hardware then old school hook-flashes the call to an extension (no physical phone associated with it) 8001. I programmatically answer 8001 then after I get the Established event, try to SST to an external number.

"Your CALLING and CALLED numbers are both the same - 917195551212:swlink1::0. That is never good."
I think that happened once when I was trying to test through your Dashboard software and I didn't set the IDs correctly.

"Is 917195551212 an extension on CM? If not and it's actually the number to dial for an external phone then this would explain the error you are seeing."
917195551212 is not an extension on CM. It's an external number. I'm trying to SST an existing call on extension 8001 to 917195551212.

1) can you use a multifunction station and test this - please. Analog stations are problematic with CTI on many counts. At least we would have a baseline of working/not working.

2) also instead of using a call from a trunk to the analog station, start with a call from another internal station and see if you can transfer that. There is a trunk-to-trunk call blocking feature in CM. If that is preventing the SST operation, depending on the trunk type you may be able to overcome it. Trunks require answer and disconnect supervision before CM will allow a trunk to trunk transfer to occur (otherwise you have the potential to lock up the trunks and they will never disconnect when the calls terminate - you will have to intervein at the SAT and busy out and release the trunk to clear them). Answer and disconnect supervision is sometimes on the Trunk Group form - depending on the trunk type. Also simplify the call flow as much as possible and troubleshoot from there. skip the transfer from the analog station to 8001 (call 8001 directly). Always walk before running when debugging.

3) ASA had a mode which was sort of a raw cutthrough to the SAT where you could use services like list trace. I forget how to access it. ASA has been out of support since release 6.0. You should be able to login to CM linux prompt using ssh and type the command "sat" to access the SAT. you can also SSH to the IP of CM and port 5023 (unless someone has set your system up differently) and login to the SAT directly. Doing so requires that you understand the commands for manipulating the forms. We use a terminal type of "y2ktt" and esc+e to enter esc+p for previous page esc+n for next page esc-x for cancel, arrow, tab keys to move around in a form.

I need three extensions for this. 8001 calls 8002, 8002 SSTs to 8003. I cannot RegisterTerminal on 8003. I created another forum post for why I can't RegisterTerminal on 8003.

https://www.devconnectprogram.com/forums/posts/list/0/26011.page#p166550