cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
2208
Views
3
Helpful
6
Replies

CiscoMediaTerminal example

KasMay
Level 1
Level 1

Hi,

I have created CTIPort in CUCM and able to get it in JTAPI code. Can some one tell me when and where to invoke setRTPParams ?.  Though i listen in CiscoMediaOpenLogicalChannelEv nothing happens.  CTI Port is in registered state.

Thanks in Advance.

6 Replies 6

pmnkumar
Level 1
Level 1

When you make a call from/to CTIPort and if the call is answered, you will see CiscoMediaOpenLogicalChannelEv if CTIPort is dynamically registered( i.e no IP addresss/Port is specified in register request).

You will not see this event if IPaddress and Port are specified in the CTIPort register request.

Make sure the call is answered.

Thanks very much Mohan.

I don't see any CiscoMediaOpenLogicalChannelEv  even though ctiport is registered dynamically. it says Registered only.

My aim is to use CTI Port to send RTP stream to IVR call from cipc. can I achieve IVR RSM using this feature?. what else I am missing like CTI port config and . I am experimenting this.     

Sincerely appreciate your response.

Are you seeing any CiscoRTP* events in your application when the call is answered?

Application should add a CiscoTerminalObserver on all the terminals to see these events. Can you attach JTAPI log from application start up time to the time the call is answered?

Thanks Mohan.

Here is the log.

How to configure / use CTIPort as monitor initiator instead of a supervisor Device and how the cvp application listen to this?. - still I miss this one.

This one I ran using my local desktop and not from cvp.

2018/01/11 15:19:26.778 EST,INFO,Provider is now in service

2018/01/11 15:19:26.778 EST,INFO,Found 10 addresses

2018/01/11 15:19:27.793 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:27.794 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.794 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.794 EST,INFO,Call Active: 33

2018/01/11 15:19:27.794 EST,INFO,Call event: 101, State: ACTIVE, Cause: CAUSE_NEW_CALL, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:27.794 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.794 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.795 EST,INFO,Call Active: 33

2018/01/11 15:19:27.795 EST,INFO,Call event: 106, State: ACTIVE, Cause: CAUSE_SNAPSHOT, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:27.795 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.796 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.796 EST,INFO,Call Active: 33

2018/01/11 15:19:27.796 EST,INFO,Call event: 106, State: ACTIVE, Cause: CAUSE_SNAPSHOT, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:27.796 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.796 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.796 EST,INFO,Call Active: 33

2018/01/11 15:19:27.796 EST,INFO,Call event: 105, State: ACTIVE, Cause: CAUSE_SNAPSHOT, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:27.797 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.797 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.797 EST,INFO,Call Active: 33

2018/01/11 15:19:27.797 EST,INFO,Call event: 206, State: ACTIVE, Cause: CAUSE_SNAPSHOT, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:27.797 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.797 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.798 EST,INFO,Call Active: 33

2018/01/11 15:19:27.798 EST,INFO,Call event: 116, State: ACTIVE, Cause: 0, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:27.798 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.798 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.798 EST,INFO,Call Active: 33

2018/01/11 15:19:27.798 EST,INFO,Call event: 115, State: ACTIVE, Cause: CAUSE_SNAPSHOT, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:27.798 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.799 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.799 EST,INFO,Call Active: 33

2018/01/11 15:19:27.799 EST,INFO,Call event: 219, State: ACTIVE, Cause: CAUSE_SNAPSHOT, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:27.799 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:27.799 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.799 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.799 EST,INFO,Call Active: 33

2018/01/11 15:19:27.800 EST,INFO,Call event: 105, State: ACTIVE, Cause: CAUSE_SNAPSHOT, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:27.800 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:27.800 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:27.800 EST,INFO,Call Active: 33

2018/01/11 15:19:27.800 EST,INFO,Call event: 206, State: ACTIVE, Cause: CAUSE_SNAPSHOT, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:28.166 EST,INFO,JtapiCallControlChannel (getTermConnByState): deviceId: 5140001102      callCtlState: 98  connectionCallId: -1

2018/01/11 15:19:28.171 EST,INFO,JtapiCallControlChannel (getTermConnByState): Address =5140001102

2018/01/11 15:19:28.171 EST,INFO,CallManager Id --> 1

2018/01/11 15:19:28.172 EST,INFO,JtapiCallControlChannel (getTermConnByState): for j=0 >> ringingCallId is16818626 >> CallControlState():98>> expected=98

2018/01/11 15:19:28.172 EST,INFO,JtapiCallControlChannel (getTermConnByState): callCtlState matches and connectionCallId is -1

2018/01/11 15:19:31.186 EST,INFO,Agent Address: 5140001102  IsObservable: true

2018/01/11 15:19:31.187 EST,INFO,Got Agent Call

2018/01/11 15:19:31.187 EST,INFO,Agent Device Active

2018/01/11 15:19:31.187 EST,INFO,getCiscoCall() callId: 648070/1  noOfCalls: 1

2018/01/11 15:19:31.187 EST,INFO,Call object same

2018/01/11 15:19:31.187 EST,INFO,JtapiCallControlChannel (getTermConnByState): deviceId: 5140001102      callCtlState: 98  connectionCallId: -1

2018/01/11 15:19:31.187 EST,INFO,JtapiCallControlChannel (getTermConnByState): Address =5140001102

2018/01/11 15:19:31.187 EST,INFO,CallManager Id --> 1

2018/01/11 15:19:31.188 EST,INFO,JtapiCallControlChannel (getTermConnByState): for j=0 >> ringingCallId is16818626 >> CallControlState():98>> expected=98

2018/01/11 15:19:31.188 EST,INFO,JtapiCallControlChannel (getTermConnByState): callCtlState matches and connectionCallId is -1

2018/01/11 15:19:31.188 EST,INFO,getTerminal()5140001102

2018/01/11 15:19:31.188 EST,INFO,getTerminal()5140001105

2018/01/11 15:19:31.256 EST,INFO,registerTerminal() - MediaTerminal is jtapitesting

2018/01/11 15:19:31.610 EST,INFO,MediaTerminal isRegistered true

2018/01/11 15:19:31.670 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.670 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.671 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.671 EST,INFO,Call Active: 33

2018/01/11 15:19:31.671 EST,INFO,Call event: 101, State: ACTIVE, Cause: CAUSE_NEW_CALL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.671 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.671 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.671 EST,INFO,Call Active: 33

2018/01/11 15:19:31.671 EST,INFO,Call event: 106, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.671 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.671 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.671 EST,INFO,Call Active: 33

2018/01/11 15:19:31.672 EST,INFO,Call event: 105, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.672 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.672 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.672 EST,INFO,Call Active: 33

2018/01/11 15:19:31.672 EST,INFO,Call event: 208, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.672 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.672 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.672 EST,INFO,Call Active: 33

2018/01/11 15:19:31.672 EST,INFO,Call event: 116, State: ACTIVE, Cause: 0, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.672 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.673 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.673 EST,INFO,Call Active: 33

2018/01/11 15:19:31.673 EST,INFO,Call event: 115, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.673 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.673 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.673 EST,INFO,Call Active: 33

2018/01/11 15:19:31.673 EST,INFO,Call event: 219, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.724 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.725 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.725 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.725 EST,INFO,Call Active: 33

2018/01/11 15:19:31.725 EST,INFO,Call event: 204, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.743 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.743 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.743 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.743 EST,INFO,Call Active: 33

2018/01/11 15:19:31.743 EST,INFO,Call event: 116, State: ACTIVE, Cause: 0, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.744 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.744 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.744 EST,INFO,Call Active: 33

2018/01/11 15:19:31.744 EST,INFO,Call event: 118, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.744 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.744 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.744 EST,INFO,Call Active: 33

2018/01/11 15:19:31.744 EST,INFO,Call event: 217, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.928 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.929 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.930 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.930 EST,INFO,Call Active: 33

2018/01/11 15:19:31.930 EST,INFO,Call event: 206, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.957 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.958 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:31.958 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:31.958 EST,INFO,Call Active: 33

2018/01/11 15:19:31.958 EST,INFO,Call event: 1073762310, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:31.959 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.959 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:19:31.959 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:19:31.959 EST,INFO,Call Active: 33

2018/01/11 15:19:31.960 EST,INFO,Call event: 1073762313, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001102[ state: CONNECTED] 2408551132[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:19:31.974 EST,INFO,MONITORING IN PROGRESS FROM 5140001105 supervisorAddress: 5140001105

2018/01/11 15:19:31.975 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.975 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.975 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.975 EST,INFO,Call Active: 33

2018/01/11 15:19:31.975 EST,INFO,Call event: 106, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] 5140001102[ state: ALERTING] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.975 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.976 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.976 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.976 EST,INFO,Call Active: 33

2018/01/11 15:19:31.976 EST,INFO,Call event: 109, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] 5140001102[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.976 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.976 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.976 EST,INFO,Call Active: 33

2018/01/11 15:19:31.977 EST,INFO,Call event: 211, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] 5140001102[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.977 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.977 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.977 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.977 EST,INFO,Call Active: 33

2018/01/11 15:19:31.977 EST,INFO,Call event: 104, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] 5140001102[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.977 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.978 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.978 EST,INFO,Call Active: 33

2018/01/11 15:19:31.978 EST,INFO,Call event: 203, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] 5140001102[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.978 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.978 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.978 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.978 EST,INFO,Call Active: 33

2018/01/11 15:19:31.979 EST,INFO,Call event: 105, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] 5140001102[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.979 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.979 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.979 EST,INFO,Call Active: 33

2018/01/11 15:19:31.979 EST,INFO,Call event: 206, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] 5140001102[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.980 EST,INFO,Terminal SEP5140001105 Event 1073745923:

2018/01/11 15:19:31.981 EST,INFO,Build in Bridge is enabled for terminalSEP5140001105

2018/01/11 15:19:31.981 EST,INFO,Got a callChangedEvent

2018/01/11 15:19:31.981 EST,INFO,Terminal: SEP5140001105

2018/01/11 15:19:31.981 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41411)->ACTIVE

2018/01/11 15:19:31.981 EST,INFO,Call Active: 33

2018/01/11 15:19:31.982 EST,INFO,Call event: 1073762314, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001105[ state: CONNECTED] 5140001102[ state: CONNECTED] , callid: 41411/1, conf call? false

2018/01/11 15:19:31.984 EST,INFO,Cisco Terminal Connection-SEP5140001105

2018/01/11 15:19:31.984 EST,INFO, CallEv in supTerminal triggered

2018/01/11 15:19:31.985 EST,INFO, CallObserver EventList-->101

2018/01/11 15:19:31.985 EST,INFO, CallObserver EventList-->106

2018/01/11 15:19:31.985 EST,INFO, CallObserver EventList-->105

2018/01/11 15:19:31.985 EST,INFO, CallObserver EventList-->116

2018/01/11 15:19:31.985 EST,INFO, CallObserver EventList-->115

2018/01/11 15:19:31.986 EST,INFO,Call is NOT in silent monitoring state

2018/01/11 15:19:31.986 EST,INFO, CallObserver EventList-->116

2018/01/11 15:19:31.986 EST,INFO, CallObserver EventList-->118

2018/01/11 15:19:31.986 EST,INFO, CallObserver EventList-->106

2018/01/11 15:19:31.986 EST,INFO, CallObserver EventList-->105

2018/01/11 15:19:32.041 EST,INFO,Terminal SEP5140001105 Event 1073758209:

2018/01/11 15:19:32.041 EST,INFO,CiscoRTPHandle : 10.38.244.135-RTPHandle-25832266

2018/01/11 15:19:32.041 EST,INFO,RTP input started: 192.168.255.87:24584, callID: 16818627

2018/01/11 15:20:01.987 EST,INFO,sleep complete

2018/01/11 15:20:02.123 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.123 EST,INFO, CallEv in supTerminal triggered

2018/01/11 15:20:02.124 EST,INFO, CallObserver EventList-->117

2018/01/11 15:20:02.124 EST,INFO,Terminal SEP5140001105 Event 1073758210:

2018/01/11 15:20:02.124 EST,INFO, CallEv in supTerminal triggered

2018/01/11 15:20:02.124 EST,INFO,RTP input is stoped: callID: no id

2018/01/11 15:20:02.124 EST,INFO, CallObserver EventList-->103

2018/01/11 15:20:02.124 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.124 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.125 EST,INFO,Terminal SEP5140001105 Event 121:

2018/01/11 15:20:02.125 EST,INFO, CallEv in supTerminal triggered

2018/01/11 15:20:02.126 EST,INFO, CallObserver EventList-->103

2018/01/11 15:20:02.125 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.126 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.126 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.127 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.127 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.127 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.127 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.127 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.128 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.128 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.128 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.128 EST,INFO,Terminal: SYS-TRK-AUS-CVP

2018/01/11 15:20:02.128 EST,INFO,Call Object: (P1-jtapiuser) GCID=(1,41410)->ACTIVE

2018/01/11 15:20:02.128 EST,INFO,Call Active: 33

2018/01/11 15:20:02.128 EST,INFO,Call event: 103, State: ACTIVE, Cause: CAUSE_NORMAL, Addr: 5140001102[ state: CONNECTED] 1234561123[ state: CONNECTED] , callid: 41410/1, conf call? false

2018/01/11 15:20:02.128 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.129 EST,INFO,Got a callChangedEvent

2018/01/11 15:20:02.537 EST,INFO,monitoring  completed

I think all the trace levels are not turned on.  Take a look below for a snippet of typical Cisco JTAPI log when all trace levels are enabled. You can use Cisco JTAPI preferences application installed along with Cisco JTAPI to turn on all the levels and restart the app.

Monitor initiator is typically the supervisor. Details: https://www.cisco.com/c/en/us/td/docs/voice_ip_comm/cucm/admin/11_0_1/featureConfig/CUCM_BK_FE5123E0_00_cucm-feature-con…

3114: Dec 05 09:14:27.349 CST %JTAPI-CTI-7-UNK:(P1:xACD){Line:Gen_Lab_RP_2:4932:AL-BHM-01000200-Device-PT:(2,98)|Call:[GCID=(5268849/2),CID=37406651]} CallStateChanged (V2) [state=IDLE cause=NOERROR destType=EXTERNAL destCM=2 fwdDest=4912:PT-INTERNAL SecStat=1 unCg=916017180841 unCd=4932 unOrigCd=4932 unLRP=4911 cg=916017180841 cgN=ed=4932 edN=Lab Route Point DN 2 orig=4932 origN=Lab Route Point DN 2 lrp=4911 lrpN= origin=INBOUNDEXTERNAL reason=REDIRECT activeTone=0 devN=Gen_Lab_RP_2 RIU=false Priv=false Select=0 CgPI=True CgNPI=True CdPI=True CdNPI=True OrigPI=True OrigNPI=True LRPPI=True LRPNPI=True UcodeCgPN= Locale=1 UcodeCdPN=Lab Route Point DN 2 Locale=1 CgIP=10.3.1.50 CgIPv6=null LineIdDn= Parti= globCg=916017180841 CdNType=0 CgPNType=0 Pre=true Priority=1 UniqueIdentifier=0000000000506571023AC7BB00000000 cgHuntDN=: cdHuntDN=: cgPat=2 cdpat=2 cgVideoCap=0 cgTelepresInfo=0 cgNoScreens=-1 cdVideoCap=0 cdTelepresInfo=0 cdNoScreens=-1]

3115: Dec 05 09:14:27.349 CST %JTAPI-JTAPIIMPL-7-UNK:(P1-X) getCallManager() Found existing CallManager

3116: Dec 05 09:14:27.350 CST %JTAPI-JTAPIIMPL-7-UNK:{(P1-X) GCID=(2,5268849)->ACTIVE} CallManager.processCallStateChange:Handling STATE_IDLE for 4932:AL-BHM-01000200-Device-PT:1

3117: Dec 05 09:14:27.350 CST %JTAPI-JTAPIIMPL-7-UNK: ProviderImpl.findExternalAddress creating new address for 4912 type 1

3118: Dec 05 09:14:27.350 CST %JTAPI-JTAPI-7-UNK:Adding reference to external addresses 4912 in partition PT-INTERNAL addrType 1 in hashtable

3119: Dec 05 09:14:27.350 CST %JTAPI-CTI-7-UNK:{ALL EXTERNAL ADDRESSES|Call:(P1-X) GCID=(2,5268849)->ACTIVE} ExternalCallStateChanged [ state=IDLE cause=100 processEvent= reason =6 ]

3120: Dec 05 09:14:27.350 CST %JTAPI-JTAPIIMPL-7-UNK:{(P1-X) GCID=(2,5268849)->ACTIVE} Handling External STATE_IDLE for 916017180841::2

3121: Dec 05 09:14:27.350 CST %JTAPI-JTAPI-7-UNK:(P1-X) 5268849/2 ConnDisconnectedEv 916017180841::2 [#37] Cause:100 CallCtlCause:0 CiscoCause:0 FeatReason:6

3122: Dec 05 09:14:27.350 CST %JTAPI-JTAPI-7-UNK:(P1-X) 5268849/2 CallCtlConnDisconnectedEv 916017180841::2 [#38] Cause:100 CallCtlCause:210 CiscoCause:0 FeatReason:6

3123: Dec 05 09:14:27.351 CST %JTAPI-JTAPIIMPL-7-UNK:(P1-xxxx) GCID=(2,5268849)->ACTIVECallManager.deliverEvents: deliver 2 events with METACODE 131

3124: Dec 05 09:14:27.351 CST %JTAPI-JTAPI-7-UNK:[5268849/2]CallImpl.deliverEvents(): for all 1 observers

3125: Dec 05 09:14:27.351 CST %JTAPI-JTAPIIMPL-7-UNK:[com.x.ciscocm.CcmCallObserver@1ff50057]ObserverProxy.queueEvents: queuing asynchronously

3126: Dec 05 09:14:27.351 CST %JTAPI-MISC-7-UNK:ObserverThread(com.x.ciscocm.CcmCallObserver@1ff50057): queuing com.cisco.jtapi.JtapiCallEventSet

3127: Dec 05 09:14:27.351 CST %JTAPI-JTAPIIMPL-7-UNK:ObserverThread(com.x.ciscocm.CcmCallObserver@1ff50057): delivering JCES[2]

3128: Dec 05 09:14:27.351 CST %JTAPI-JTAPI-7-UNK:(P1-X) 5268849/2 ConnDisconnectedEv 4932:AL-BHM-01000200-Device-PT:1 [#39] Cause:100 CallCtlCause:0 CiscoCause:0

Hi Mohan,

sorry it took sometime to get this scenario. Here some JTAPI logs. Thanks very much.

354: May 31 17:55:11.879 EDT %JTAPI-JTAPIIMPL-7-UNK:Address [RSMCTIPort:5140001130:ALL-P-GLOBAL-DEVICES:(0,0)] out of service

355: May 31 17:55:11.879 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser) [5140001130:ALL-P-GLOBAL-DEVICES] CiscoAddrOutOfServiceEv [#44] Cause:100 CallCtlCause:0 CiscoCause:0 FeatReason:12

356: May 31 17:55:11.889 EDT %JTAPI-CTIIMPL-7-UNK:(P1-10.38.244.135) EventThread handling event com.cisco.cti.protocol.DeviceRegisteredEventWithLineInfo[25]

357: May 31 17:55:11.889 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-jtapiuser)DeviceRegistered with ICCNDevice - name = (P1-jtapiuser) RSMCTIPort(1,14304), Unicode cap = 1, Locale = 1, Proto = 1, EMLoginUsername = , RegistrationType = 2

358: May 31 17:55:11.929 EDT %JTAPI-CTIIMPL-7-UNK:(P1-10.38.244.135) EventThread handling event com.cisco.cti.protocol.DeviceInServiceEvent[26]

359: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:RSMCTIPort: Lines opened

360: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-jtapiuser) Terminal "RSMCTIPort" in service

361: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:RSMCTIPort deviceOpened(): Terminal: name=RSMCTIPort, isOpen=true. Device: Type=72, RegistrationType=2, isRegistered=true, isRegisteredExtendByThisApp=false, Protocol=1, isOpen=true

362: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser) [RSMCTIPort] CiscoTermInServiceEv [#45] Cause:100 CallCtlCause:0 CiscoCause:0 FeatReason:12

363: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.queueEvents: queuing asynchronously

364: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:[5140001130:ALL-P-GLOBAL-DEVICES:1]Request: addObserver(test.CTIPort@7bacb9c3)

365: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread(test.CTIPort@7bacb9c3): delivering JTES[1]

366: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.deliverEvents()

367: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:Address [RSMCTIPort:5140001130:ALL-P-GLOBAL-DEVICES:(1,14320)] out of service

368: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]delivering to terminalChangedEvent

369: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser) [5140001130:ALL-P-GLOBAL-DEVICES] CiscoAddrOutOfServiceEv [#46] Cause:100 CallCtlCause:0 CiscoCause:0 FeatReason:12

370: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.queueEvents: queuing asynchronously

371: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:[5140001130:ALL-P-GLOBAL-DEVICES:1]Request: addCallObserver(test.CTIPort@7bacb9c3)

372: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:RSMCTIPort deviceInService(): This terminal: name=RSMCTIPort, isOpen=true. This device: Type=72, RegistrationType=2, isRegistered=true, isRegisteredExtendByThisApp=false, Protocol=1, isOpen=true

373: May 31 17:55:11.929 EDT %JTAPI-CTIIMPL-7-UNK:(P1-10.38.244.135) EventThread handling event com.cisco.cti.protocol.LineInServiceEvent[27]

374: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser)[ObserverThread(test.CTIPort@7bacb9c3)][RSMCTIPort]Request: getObservers()

375: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:Address [RSMCTIPort:5140001130:ALL-P-GLOBAL-DEVICES:(1,14320)]  in service

376: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser)[ObserverThread(test.CTIPort@7bacb9c3)][RSMCTIPort]Request: getCallObservers()

377: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser) [5140001130:ALL-P-GLOBAL-DEVICES] CiscoAddrInServiceEv [#47] Cause:100 CallCtlCause:0 CiscoCause:0 FeatReason:12

378: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:[5140001130:ALL-P-GLOBAL-DEVICES:1]Request: getObservers

379: May 31 17:55:11.929 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.queueEvents: queuing asynchronously

380: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser)[ObserverThread(test.CTIPort@7bacb9c3)][5140001130:ALL-P-GLOBAL-DEVICES:1]Request: getCallObservers()

381: May 31 17:55:11.929 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser)[ObserverThread(test.CTIPort@7bacb9c3)][5140001130:ALL-P-GLOBAL-DEVICES:1]Request: getCallObservers()

382: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.deliverEvents() completed

383: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread(test.CTIPort@7bacb9c3): delivering JAES[1]

384: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.deliverEvents()

385: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]delivering to addressChangedEvent

386: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.deliverEvents() completed

387: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread(test.CTIPort@7bacb9c3): delivering JAES[1]

388: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.deliverEvents()

389: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]delivering to addressChangedEvent

390: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:Request:getAutoAcceptStatus: Terminal=RSMCTIPort

391: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:Request:getAutoAcceptStatus: Address=RSMCTIPort:5140001130:ALL-P-GLOBAL-DEVICES:(1,14320)

392: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:[5140001130:ALL-P-GLOBAL-DEVICES:1]Request: setAutoAcceptStatus 1Terminal=RSMCTIPort

393: May 31 17:55:11.939 EDT %JTAPI-JTAPIIMPL-7-UNK:Request:setAutoAcceptStatus: Address=RSMCTIPort:5140001130:ALL-P-GLOBAL-DEVICES:(1,14320)

394: May 31 17:55:11.979 EDT %JTAPI-CTIIMPL-7-UNK:(P1-10.38.244.135) EventThread handling event com.cisco.cti.protocol.LineAutoAcceptStatusEvent[28]

395: May 31 17:55:11.979 EDT %JTAPI-JTAPIIMPL-7-UNK:Address [RSMCTIPort:5140001130:ALL-P-GLOBAL-DEVICES:(1,14320)] AutoAcceptStatusChanged: Status =1

396: May 31 17:55:11.979 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.deliverEvents() completed

397: May 31 17:55:11.979 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser) [5140001130:ALL-P-GLOBAL-DEVICES] CiscoAddrAutoAcceptStatusChangedEv [#48] Cause:100 CallCtlCause:0 CiscoCause:0 FeatReason:12

398: May 31 17:55:11.979 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.queueEvents: queuing asynchronously

399: May 31 17:55:11.979 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread(test.CTIPort@7bacb9c3): delivering JAES[1]

400: May 31 17:55:11.979 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.deliverEvents()

401: May 31 17:55:11.979 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]delivering to addressChangedEvent

402: May 31 17:55:11.979 EDT %JTAPI-JTAPIIMPL-7-UNK:[test.CTIPort@7bacb9c3]ObserverProxy.deliverEvents() completed

403: May 31 17:55:12.429 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser)[main][(P1-jtapiuser) GCID=(1,44850)->IDLE]Request: startMonitor ([SEP5140001100/[5140001100:ALL-P-GLOBAL-DEVICES:1/(P1-jtapiuser) GCID=(1,44849)->ACTIVE]->ESTABLISHED]->TALKING)

404: May 31 17:55:12.429 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser)[main][(P1-jtapiuser) GCID=(1,44850)->IDLE]Request: startMonitor (RSMCTIPort, 5140001130 , 18670808, 5140001100, SEP5140001100, 1 3

405: May 31 17:55:12.479 EDT %JTAPI-CTIIMPL-7-UNK:(P1-10.38.244.135) EventThread handling event com.cisco.cti.protocol.NewCallEvent[29]

406: May 31 17:55:12.479 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-jtapiuser) getCallManager() Found existing CallManager

407: May 31 17:55:12.479 EDT %JTAPI-JTAPIIMPL-7-UNK:CallInfo for = (P1-jtapiuser) GCID=(1,44850)->IDLE CurIng :5140001130:ALL-P-GLOBAL-DEVICES:1 Cured :null LRP :null ing :5140001130:ALL-P-GLOBAL-DEVICES:1 ed :null ModEd :null Moding :5140001130:ALL-P-GLOBAL-DEVICES:1

408: May 31 17:55:12.479 EDT %JTAPI-JTAPI-7-UNK:[(P1-jtapiuser) GCID=(1,44850)->IDLE]InvalidPartyExceptionImpl caught: Invalid monitor destination.

409: May 31 17:55:12.479 EDT %JTAPI-JTAPI-7-UNK:[[5140001130:ALL-P-GLOBAL-DEVICES:1/(P1-jtapiuser) GCID=(1,44850)->INVALID]->IDLE]creating internal connection for 5140001130:ALL-P-GLOBAL-DEVICES:1

410: May 31 17:55:12.479 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser) 44850/1 ConnCreatedEv 5140001130:ALL-P-GLOBAL-DEVICES:1 [#49] Cause:100 CallCtlCause:0 CiscoCause:0 FeatReason:12

411: May 31 17:55:12.479 EDT %JTAPI-JTAPI-7-UNK:{ CcnCall=Call:[GCID=(44850/1),CID=18670809] Connection=[5140001130:ALL-P-GLOBAL-DEVICES:1/(P1-jtapiuser) GCID=(1,44850)->INVALID]->IDLE: creating new Connection for CCNCall }

412: May 31 17:55:12.479 EDT %JTAPI-JTAPIIMPL-7-UNK:{(P1-jtapiuser) GCID=(1,44850)->INVALID} Initializing to DIALTONE for 5140001130:ALL-P-GLOBAL-DEVICES:1

413: May 31 17:55:12.479 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser) 44850/1 ConnConnectedEv 5140001130:ALL-P-GLOBAL-DEVICES:1 [#50] Cause:100 CallCtlCause:0 CiscoCause:0 FeatReason:12

414: May 31 17:55:12.479 EDT %JTAPI-JTAPI-7-UNK:(P1-jtapiuser) 44850/1 CallCtlConnInitiatedEv 5140001130:ALL-P-GLOBAL-DEVICES:1 [#51] Cause:100 CallCtlCause:100 CiscoCause:0 FeatReason:12

415: May 31 17:55:12.479 EDT %JTAPI-JTAPIIMPL-7-UNK:Adding CCNCall Call:[GCID=(44850/1),CID=18670809] to TermConn:[RSMCTIPort/[5140001130:ALL-P-GLOBAL-DEVICES:1/(P1-jtapiuser) GCID=(1,44850)->INVALID]->INITIATED]->IDLE

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: