49.7. CTISIP diagnostics, statistics, logs and debug

49.7.1. CTISIP diagnostics

To display the diagnostics of the CTISIP resource the following commands are used:

d d ctisip

Shows diagnostic information, such as the state of the resource, the currently used channels, the remote SIP username, the local/remote IP address-port of the SIP channel for incoming packets, the calling and the called number.

d de ctisip

Shows diagnostic information in an extended format: the same information listed for d d ctisip command and the state of Unattended/Attended Call Transfer, the local/remote IP address-port of the SIP channel for outgoing packets, the local/remote IP address-port for RTP and RTCP channel.

[12:34:34] ABILIS_CPX:d d ctisip

RES:CtiSip --------------------------------------------------------------------
       Session_Initiation_Protocol
       STATE:ACTIVE    UDP-STATE:CLOSED    USED-CHAN:0
       - SIP Channels diagnostics ---------------------------------------------
       CH: SIPState     Prot TcpLsn SIP-LocAdd-Port-In    SIP-RemAdd-Port-In
           Q931State                CG                    CD
           User                             CallId
       ------------------------------------------------------------------------
       0   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       1   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       2   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       3   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       4   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       5   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       6   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       7   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       8   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       9   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
[12:34:34] ABILIS_CPX:d de ctisip

RES:CtiSip --------------------------------------------------------------------
       Session_Initiation_Protocol
       STATE:ACTIVE    UDP-STATE:CLOSED    USED-CHAN:0
       - SIP Channels diagnostics ---------------------------------------------
       CH: SIPState     Prot TcpLsn SIPLocAdd-Port-In     SIPRemAdd-Port-In
                                    SIPLocAdd-Port-Out    SIPRemAdd-Port-Out
           Q931State                CG                    CD
           User                             CallId
           Q932State    UCTState        ACTState          CPOState
           RTPState                 RTPLocAdd-LocPort     RTPRemAdd-RemPort
           RTCPState                RTCPLocAdd-LocPort    RTCPRemAdd-RemPort
       ------------------------------------------------------------------------
       0   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       1   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       2   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       3   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       4   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       5   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       6   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       7   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       8   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------
       9   DISCONNECTED
           00-DISCONNECTED
       ------------------------------------------------------------------------

49.7.2. CTISIP statistics

To display the statistics of the CTISIP resource the following commands are used:

d s ctisip

Shows statistic information, such as the number of incoming/outgoing accepted/rejected calls, the number of incoming/outgoing succesful/failed registrations, the number of incoming/outgoing reinvites, the number of successful incoming/outgoing transfers, the number of expired retransmission timer in incoming/outgoing calls.

d se ctisip

Shows statistic information in an extended format: the same information listed for d se ctisip command are shown for each session.

[12:34:34] ABILIS_CPX:d s ctisip

RES:CtiSip --------------------------------------------------------------------
       Session_Initiation_Protocol                                             
       --- Cleared 0 days 19:38:23 ago, on 28/05/2015 at 19:48:21 -------------
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       SUCC-CALL  |          0|          0|FAIL-CALL  |          0|          0|
       SUCC-REG   |          0|       1843|FAIL-REG   |          0|          0|
       SUCC-SUB   |          0|           |FAIL-SUB   |          0|           |
       SUCC-NOT   |           |          0|FAIL-NOT   |           |          0|
       SUCC-TRAN  |          0|          0|FAIL-TRAN  |          0|          0|
       NOCHAN-CALL|          0|          0|NOCHAN-REG |          0|          0|
       NOCHAN-SUB |          0|           |NOCHAN-NOT |           |          0|
       EXP-RETRY  |          0|          0|DTMF       |          0|          0|
       HOLD       |          0|          0|UN-HOLD    |          0|          0|
       UNKNOWN    |          0|       1228|REINVITES  |          0|          0|
       LOST-NOBUF |          0|          0|OUTSEQ     |          0|           |
       DENIED-IP  |          0|           |BANNED-IP  |          0|           |
       ------------------------------------------------------------------------
[12:34:34] ABILIS_CPX:d se ctisip

RES:CtiSip --------------------------------------------------------------------
       Session_Initiation_Protocol                                             
       --- Cleared 0 days 19:38:44 ago, on 28/05/2015 at 19:48:20 -------------
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       SUCC-CALL  |          0|          0|FAIL-CALL  |          0|          0|
       SUCC-REG   |          0|       1843|FAIL-REG   |          0|          0|
       SUCC-SUB   |          0|           |FAIL-SUB   |          0|           |
       SUCC-NOT   |           |          0|FAIL-NOT   |           |          0|
       SUCC-TRAN  |          0|          0|FAIL-TRAN  |          0|          0|
       NOCHAN-CALL|          0|          0|NOCHAN-REG |          0|          0|
       NOCHAN-SUB |          0|           |NOCHAN-NOT |           |          0|
       EXP-RETRY  |          0|          0|DTMF       |          0|          0|
       HOLD       |          0|          0|UN-HOLD    |          0|          0|
       UNKNOWN    |          0|       1228|REINVITES  |          0|          0|
       LOST-NOBUF |          0|          0|OUTSEQ     |          0|           |
       DENIED-IP  |          0|           |BANNED-IP  |          0|           |
       ------------------------------------------------------------------------
       CH:0   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------
       CH:1   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------
       CH:2   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------
       CH:3   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------
       CH:4   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------
       CH:5   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------
       CH:6   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------
       CH:7   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------
       CH:8   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------
       CH:9   CallId:
              CG:                           CD:
       -----------|---INPUT---|--OUTPUT---|-----------|---INPUT---|--OUTPUT---|
       RTP-PCK    |          0|          0|T38-PCK    |          0|          0|
       RTP-NOBUF  |          0|          0|T38-NOBUF  |          0|          0|
       RTP-LOST   |          0|          0|T38-LOST   |          0|           |
       RTP-OUTSEQ |          0|           |T38-OUTSEQ |          0|           |
       RTP-MALFORM|          0|           |T38-MALFORM|          0|           |
       RTCP-PCK   |          0|          0|SIP-OUTSEQ |          0|           |
       ------------------------------------------------------------------------

49.7.3. CTISIP logs

To show the log of CTISIP type the following command:

[15:54:16] ABILIS_CPX:d ctisip log

CURRENT EVENTS LOG MASK: ERR REG INFO 

19/07 15:57:23 [ 10] Reg Info in IN channel with 192.168.20.107:42968
19/07 15:57:23 [ 10] Opened new SIP REG channel
19/07 15:57:23 [ 10] Declared user: test
19/07 15:57:23 [ 10] Freeing the channel
19/07 15:57:28 [ 10] Reg Info in IN channel with 192.168.20.107:63330
19/07 15:57:28 [ 10] Opened new SIP REG channel
19/07 15:57:28 [ 10] Declared user: test3
19/07 15:57:28 [ 10] Freeing the channel
19/07 15:59:34 [ 10] Reg Info in IN channel with 192.168.20.107:42968
19/07 15:59:34 [ 10] Opened new SIP REG channel
19/07 15:59:34 [ 10] Declared user: test
19/07 15:59:34 [ 10] Freeing the channel
19/07 15:59:38 [ 10] Reg Info in IN channel with 192.168.20.107:63330
19/07 15:59:38 [ 10] Opened new SIP REG channel
19/07 15:59:38 [ 10] Declared user: test3
19/07 15:59:38 [ 10] Freeing the channel
19/07 16:00:15 [ 10] Reg Info in IN channel with 192.168.20.107:42968
19/07 16:00:15 [ 10] Opened new SIP REG channel
19/07 16:00:15 [ 10] Declared user: test
19/07 16:00:15 [ 10] Freeing the channel
19/07 16:00:16 [ 10] Reg Info in IN channel with 192.168.20.107:63330
19/07 16:00:16 [ 10] Opened new SIP REG channel
19/07 16:00:16 [ 10] Declared user: test3
19/07 16:00:16 [ 10] Freeing the channel

To set the filter event use, type the command: s ctisip log event:<filter>

[15:55:22] ABILIS_CPX:s ctisip log event: ?

S CTISIP LOG EVENT:val                Set CTISIP log events filter

EVENT:         CTISIP log events filter [ERR, REG, INFO] or [DFT, FULL], where:
               - ERR, REG, INFO filters can be joined using "," operator;
               - DFT: means ERR events;
               - FULL: means every type of event.

[15:55:46] ABILIS_CPX:s ctisip log event:full

Debug log mask was set to:ERR REG INFO 

To clear the log, type the following command:

[15:55:25] ABILIS_CPX:c ctisip log

Now session log is empty

49.7.4. Debug of the CTISIP resource

Below debug commands are described.

[Note]Note

To use these commands you need to have administrator or super user rights.

Type the following command to display the syntax of the command:

[12:34:34] ABILIS_CPX:debug res:ctisip lsn:0

RES:CtiSip --------------------------------------------------------------------
       Session_Initiation_Protocol                                             
       BufferLength:64512  Date/Time:29/05/2015 15:22:27 TraceTime:70443851

Usage:

   LSN:0                   - This help
   LSN:1                   - Extra channels diags
   LSN:2                   - Advanced channels diags
   LSN:3                   - Show debug log
   LSN:3 CMD:CLR           - Clear debug log
   LSN:3 CMD:SET:[DFT | CLR | FULL | [level,...]] - Set log event mask
                       No parameters - Show current events log mask
                       DFT    - Log default events (ERR events only)
                       CLR    - Clear log event mask
                       FULL   - Log all events
                       levels:
                       ERR    - Log only error events
                       REG    - Registration events
                       INFO   - Informative events
   LSN:4                   - Show registrations
   LSN:4  CMD:<user>       - Show registration of the specified user
   LSN:5                   - Show subscriptions
   LSN:5  CMD:<user>       - Show subscriptions of the specified user
   LSN:10 CMD:<user>       - Make remote registration with the specified user
   LSN:11 CMD:<user>       - De-register the specified user
   LSN:12 CMD:<user>       - Remove subscriptions of the specified user
   LSN:20 CMD:<NumPackets>  - Simulates loss of NumPackets packets from CTIR
   LSN:21 CMD:<NumPackets>  - Simulates loss of NumPackets packets to CTIR
   LSN:22                  - Print Call&Ring table
   LSN:23                  - Print Capability table
   LSN:24 CMD:<user>       - Remove a specified user  from capability table
   LSN:24 CMD:ALL          - Remove all users from capability table
   LSN:30                  - Show extra UDP receivers dignostics

Type the below command to view the event logs connected with the users registration:

[12:37:17] ABILIS_CPX:debug res:ctisip lsn:3

RES:CtiSip --------------------------------------------------------------------
       Session_Initiation_Protocol                                             
       BufferLength:64512  Date/Time:29/05/2015 15:23:20 TraceTime:70496487

DD/MM/YYYY HH:MM:SS - CHANNEL DESCRIPTION
29/05/2015 09:33:26 - [   30] Error in OUT channel with 62.62.62.62:5060
29/05/2015 09:33:26 - [   30] AUTH timer timeout
29/05/2015 11:08:09 - [   30] Error in OUT channel with 62.62.62.62:5060
29/05/2015 11:08:09 - [   30] AUTH timer timeout
29/05/2015 15:34:56 - [   30] Error in OUT channel with 62.62.62.62:5060
29/05/2015 15:34:56 - [   30] AUTH timer timeout
29/05/2015 15:40:44 - [   30] Error in OUT channel with 62.62.62.62:5060
29/05/2015 15:40:44 - [   30] AUTH timer timeout
29/05/2015 17:46:24 - [   30] Error in OUT channel with 62.62.62.62:5060
29/05/2015 17:46:24 - [   30] AUTH timer timeout

This table shows data, time, identification of the event and the description of the error.

Possible errors could be:

  • Unknown user;

  • Codec not supported;

  • Retransmission out of time (happens when you're cut off).

The most common usage is:

  • debug res:ctisip lsn:3: shows log;

  • debug res:ctisip lsn:3 cmd:clr: clear log;

  • debug res:ctisip lsn:3 cmd:set:err: set log mask to log only errors events;

  • debug res:ctisip lsn:3 cmd:set:err,reg: set log mask to log only errors and registration events;

  • debug res:ctisip lsn:3 cmd:set:full: set log mask to log any event.

The following command allows to show the registration table (remote peers registered with Abilis and remote peers Abilis is registered too).

[17:03:12] ABILIS_CPX:debug res:ctisip lsn:4

RES:CtiSip --------------------------------------------------------------------
       Session_Initiation_Protocol                                             
       BufferLength:64512  Date/Time:29/05/2015 15:24:37 TraceTime:70573545

User                             Host            Prot Port REG    LIFETIME  AGE
-------------------------------------------------------------------------------
sip_proxy                        062.062.062.062 UDP  5060 REMOTE      120   21
zoiper                           192.168.000.024 UDP  5060 LOCAL        60   35
-------------------------------------------------------------------------------

Meaning of the parameters:

User

Username registered in the user table (d user).

Host

IP address of the user.

Prot

Transport protocol.

Port

UDP port of the user.

REG

Registration of the user [REMOTE: Abilis is registered to a remote peer; LOCAL: a remote peer is registered to Abilis].

LIFETIME

How often (in seconds) the registration is requested.

AGE

Time (in seconds) since last valid registration.

In the previous example, Abilis is registered to the remote peer sip_proxy (IP address: 62.62.62.62) which needs Abilis registration each 120 seconds; Abilis registered itself 21 seconds ago.

The user zoiper (IP address: 192.168.0.24) is registered to Abilis; it registered itself 35 seconds ago.