Here are the results doing a makeCall from the dashboard:
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 585 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.proxy.CstaUnmarshallerNode FINEST - Unmarshaller: NO_MAPPING, protocolVersion:
http://www.ecma-international.org/standards/ecma-323/csta/ed3/privF
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 587 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaUnmarshallerNode FINEST - Actual unmarshall time is : 2ms : thread=Thread[CstaUnMarshalerNode-2,5,main]
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 587 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaUnmarshallerNode FINEST - Unmarshalled xml=<?xml version="1.0" encoding="utf-8"?>
<GetThirdPartyDeviceId xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns="http://www.avaya.com/csta">
<switchName>swlink1</switchName>
<extension>917195551212</extension>
</GetThirdPartyDeviceId> to object=session[null] com.avaya.csta.binding.GetThirdPartyDeviceId@5bc38f88
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 588 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [Begin] payload=class com.avaya.csta.binding.GetThirdPartyDeviceIdResponse, id=16, session=session[session E294C8933FC954F05DF55665A24C17AD-60,[/172.30.200.110:4721/172.20.5.250:60770]]
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 588 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshaller: NO_MAPPING, protocolVersion:
http://www.ecma-international.org/standards/ecma-323/csta/ed3/privF
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 588 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaMarshallerNode FINER - Packaging a session[null] com.avaya.csta.binding.GetThirdPartyDeviceIdResponse@6701e8c5
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 589 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshalled session[null] com.avaya.csta.binding.GetThirdPartyDeviceIdResponse@6701e8c5 to <?xml version="1.0" encoding="UTF-8"?>
<GetThirdPartyDeviceIdResponse xmlns="http://www.avaya.com/csta"><device typeOfNumber="other" mediaClass="notKnown" bitRate="constant">917195551212:swlink1::0</device></GetThirdPartyDeviceIdResponse>
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 589 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINER - Writing ByteBuffer=java.nio.HeapByteBuffer[pos=0 lim=246 cap=246], session=session[session E294C8933FC954F05DF55665A24C17AD-60,[/172.30.200.110:4721/172.20.5.250:60770]]
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 589 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINEST - Wrote 246 of 246 bytes to the Channel, session=session[session E294C8933FC954F05DF55665A24C17AD-60,[/172.30.200.110:4721/172.20.5.250:60770]]
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 589 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - CstaUnMarshalerNode-2: Duration of marshalling = 1msec and null publishing = 0msecs, session[null] com.avaya.csta.binding.GetThirdPartyDeviceIdResponse@6701e8c5
<134>May 10 00:56:39 wiavaes01 DmccMain[180590] +07:00 2023 589 1 com.avaya.aes | :CstaUnMarshalerNode-2: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [End]
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 776 1 com.avaya.aes | :NIO-ChannelServicer Thread: com.avaya.mvcs.proxy.CstaUnmarshallerNode FINEST - Unmarshaller: NO_MAPPING, protocolVersion:
http://www.ecma-international.org/standards/ecma-323/csta/ed3/privF
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 777 1 com.avaya.aes | :CstaUnMarshalerNode-4: com.avaya.mvcs.proxy.CstaUnmarshallerNode FINEST - Actual unmarshall time is : 1ms : thread=Thread[CstaUnMarshalerNode-4,5,main]
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 777 1 com.avaya.aes | :CstaUnMarshalerNode-4: 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">917195551212:swlink1::0</callingDevice>
<calledDirectoryNumber typeOfNumber="other" mediaClass="notKnown">917195551212:swlink1::0</calledDirector
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 777 1 com.avaya.aes | :CstaUnMarshalerNode-4: com.avaya.mvcs.proxy.CstaUnmarshallerNode FINEST - yNumber>
<callCharacteristics>
<priorityCall>false</priorityCall>
</callCharacteristics>
</MakeCall> to object=session[null] ch.ecma.csta.binding.MakeCall@6e92dae0
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 777 1 com.avaya.aes | :CstaUnMarshalerNode-4: com.avaya.mvcs.proxy.SubscriptionManager FINER - adding EventSubscriber to channel=async[/172.30.200.110:4721/172.20.5.250:60770]:17
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 777 1 com.avaya.aes | :CstaUnMarshalerNode-4: com.avaya.mvcs.proxy.SubscriptionManager FINER - return from adding EventSubscriber to channel=async[/172.30.200.110:4721/172.20.5.250:60770]:17 took 0ms
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 777 1 com.avaya.aes | :CstaUnMarshalerNode-4: com.avaya.mvcs.proxy.SubscriptionManager FINER - after tryCatch adding EventSubscriber to channel=async[/172.30.200.110:4721/172.20.5.250:60770]:17 took 0ms
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 777 1 com.avaya.aes | :CstaUnMarshalerNode-4: com.avaya.mvcs.proxy.CstaRouterNode FINER - Adding: Subscription Manager count= 7 request=session[null] ch.ecma.csta.binding.MakeCall@6e92dae0 channel=async[/172.30.200.110:4721/172.20.5.250:60770]:17
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 777 1 com.avaya.aes | :CstaUnMarshalerNode-4: com.avaya.mvcs.proxy.CstaRouterNode FINER - Returned from processing an AsynchronousRequest session=session[session E294C8933FC954F05DF55665A24C17AD-60,[/172.30.200.110:4721/172.20.5.250:60770]]
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 836 1 com.avaya.aes | :DistributeCSTAEvent: com.avaya.mvcs.proxy.SubscriptionManager FINER - AsyncEventSubscriber SubscriptionEvent created for: ch.ecma.csta.errors.InvalidCallingDeviceIDException object=<generictuple><data><Unique Id>17</Unique Id><Command History List>[]</Command History List><Payload>ch.ecma.csta.errors.InvalidCallingDeviceIDException: The specified calling device ID was invalid: error code 12</Payload></data></generictuple>
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 836 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - Handling a class com.avaya.workflow.MessageContextTuple, session=session[session E294C8933FC954F05DF55665A24C17AD-60,[/172.30.200.110:4721/172.20.5.250:60770]] invokeID=17
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 836 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - AsyncEventSubscriber executing: ch.ecma.csta.errors.InvalidCallingDeviceIDException time in pendingAsyncEventSubscribeTasks queue=0ms
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 836 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [Begin] payload=class ch.ecma.csta.errors.InvalidCallingDeviceIDException, id=17, session=session[session E294C8933FC954F05DF55665A24C17AD-60,[/172.30.200.110:4721/172.20.5.250:60770]]
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 836 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 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 836 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINER - Packaging a ch.ecma.csta.errors.InvalidCallingDeviceIDException: The specified calling device ID was invalid: error code 12
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - Marshalled session[null] ch.ecma.csta.binding.CSTAErrorCode@7de17a76 to <?xml version="1.0" encoding="UTF-8"?>
<CSTAErrorCode xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><operation>invalidCallingDeviceID</operation></CSTAErrorCode>
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINER - Writing ByteBuffer=java.nio.HeapByteBuffer[pos=0 lim=193 cap=193], session=session[session E294C8933FC954F05DF55665A24C17AD-60,[/172.30.200.110:4721/172.20.5.250:60770]]
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.ByteBufferToChannelNode FINEST - Wrote 193 of 193 bytes to the Channel, session=session[session E294C8933FC954F05DF55665A24C17AD-60,[/172.30.200.110:4721/172.20.5.250:60770]]
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 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.CSTAErrorCode@7de17a76
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.CstaMarshallerNode FINEST - [End]
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 1 com.avaya.aes | :ClearableQueuedExecutor-1: com.avaya.mvcs.proxy.SubscriptionManager FINER - AsyncEventSubscriber finished executing: ch.ecma.csta.errors.InvalidCallingDeviceIDException, took 1ms from time added to pendingAsyncEventSubscribeTasks queue to finish
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 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:60770]:17
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 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:60770]:17 took 1ms
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 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:60770]:17 took 1ms
<134>May 10 00:56:52 wiavaes01 DmccMain[180590] +07:00 2023 838 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:60770]:17 for request=session[null] ch.ecma.csta.binding.MakeCall@6e92dae0