Table Of Contents

debug voip avlist

debug voip ccapi

debug voip ccapi error

debug voip ccapi inout

debug voip confmsp

debug voip dcapi

debug voip dialpeer

debug voip dsm

debug voip dsmp

debug voip dspapi

debug voip dump-file-acct

debug voip eddri

debug voip enum

debug voip event-log

debug voip fastpath

debug voip fileacct

debug voip h221

debug voip h324

debug voip hpi

debug voip ipipgw

debug voip ivr

debug voip ivr all

debug voip ivr applib

debug voip ivr callsetup

debug voip ivr digitcollect

debug voip ivr dynamic

debug voip ivr error

debug voip ivr redirect

debug voip ivr script

debug voip ivr settlement

debug voip ivr states

debug voip ivr supplementary-service

debug voip ivr tclcommands

debug voip profile fax

debug voip profile help

debug voip profile modem

debug voip profile voice

debug voip rawmsg

debug voip rtcp

debug voip rtp

debug voip settlement all

debug voip settlement enter

debug voip settlement error

debug voip settlement exit

debug voip settlement misc

debug voip settlement network

debug voip settlement security

debug voip settlement ssl

debug voip settlement transaction

debug voip tsp

debug voip vtsp

debug voip xcodemsp

debug vpdn

debug vpdn pppoe-data

debug vpdn pppoe-error

debug vpdn pppoe-events

debug vpdn pppoe-packet

debug vpdn redundancy

debug vpm all

debug vpm dsp

debug vpm error

debug vpm port

debug vpm signal

debug vpm signaling

debug voip avlist

To troubleshoot the attribute value list (AVLIST) contents, use the debug voip avlist command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip avlist [all | default | detail | error [call [informational] | software [informational]] | inout]

no debug voip avlist

Syntax Description

all	(Optional) Displays all AVLIST debugging messages.
default	(Optional) Displays AVLIST error and inout information. This option also runs if no keywords are added.
detail	(Optional) Displays AVLIST background messages.
error	(Optional) Displays AVLIST error messages.
call	(Optional) Displays call processing errors.
informational	(Optional) Displays minor errors and major errors. Without the informational keyword, only major errors are displayed.
software	(Optional) Displays software errors.
inout	(Optional) Displays AVLIST in/out functions.

Defaults

Debugging is not enabled.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.3(8)T	This command was introduced.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

The debug voip avlist command does not support call debug filtering.

Examples

Output is primarily used by TAC.

debug voip ccapi

To troubleshoot the call control application programming interface (CCAPI) contents, use the debug voip ccapi command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip ccapi [all | default | detail | error [call [informational] | software [informational]] | individual range | inout | function | protoheaders | service]

no debug voip ccapi

Syntax Description

all	(Optional) Displays all CCAPI debugging messages.
default	(Optional) Displays CCAPI error and inout information. This option also runs if no keywords are added.
detail	(Optional) Displays CCAPI background messages.
error	(Optional) Displays CCAPI error messages. The debug voip ccapi error command traces the error logs in the call control API. Error logs are generated during normal call processing, if there are insufficient resources, or if there are problems in the underlying network-specific code, the higher call session application, or the call control API itself. This debug command shows error events or unexpected behavior in system software. Usually no events will be generated.
call	(Optional) Displays call processing errors.
informational	(Optional) Displays minor errors and major errors. Without the informational keyword, only major errors are displayed.
software	(Optional) Displays software errors.
individual	(Optional) Enables individual CCAPI debug outputs.
range	For the individual keyword, the range is an integer value from 1 to 146. For specific range values, see Table 372.
inout	(Optional) Displays CCAPI in/out functions. The debug voip ccapi inout command traces the execution path through the call control API, which serves as the interface between the call session application and the underlying network-specific software. You can use the output from this command to understand how calls are being handled by the router. This command shows how a call flows through the system. Using this debug level, you can see the call setup and teardown operations performed on both the telephony and network call legs.
function	(Optional) Displays CCAPI function tracing.
protoheaders	(Optional) Displays CCAPI protocol headers passing information.
service	(Optional) Logs debug messages that are not call related.

Table 372 CCAPI Individual Debug Values 

Value	CCAPI Debug Function
1	CC_IDMSG_API_DISPLAY_IES
2	CC_IDMSG_SETUP_IND_COMM_2
3	CC_IDMSG_SETUP_IND_COMM_3
4	CC_IDMSG_SETUP_IND_COMM_4
5	CC_IDMSG_ALERT_IND_5
6	CC_IDMSG_ALERT_IND_6
7	CC_IDMSG_CONNECT_IND_7
8	CC_IDMSG_CONNECT_IND_8
9	CC_IDMSG_RECONNECT_IND_9
10	CC_IDMSG_DISCONNECTED_IND_10
11	CC_IDMSG_DISCONNECTED_IND_11
12	CC_IDMSG_DISCONNECTED_IND_12
13	CC_IDMSG_DISCONNECT_DONE_IND_13
14	CC_IDMSG_DISCONNECT_DONE_IND_14
15	CC_IDMSG_DISCONNECT_DONE_IND_15
16	CC_IDMSG_PRE_DISC_CAUSE_16
17	CC_IDMSG_PRE_DISC_CAUSE_17
18	CC_IDMSG_DIGIT_BEGIN_IND_18
19	CC_IDMSG_DIGIT_END_IND_19
20	CC_IDMSG_DIGIT_END_IND_20
21	CC_IDMSG_DIGIT_END_NO_TERM_21
22	CC_IDMSG_TONE_IND_22
23	CC_IDMSG_FEATURE_IND_23
24	CC_IDMSG_MODIFY_DONE_IND_24
25	CC_IDMSG_MODIFY_MODE_DONE_IND_25
26	CC_IDMSG_INBAND_MSG_RCVD_IND_26
27	CC_IDMSG_INBAND_MSG_DONE_IND_27
28	CC_IDMSG_UPD_CALL_INFO_IND_28
29	CC_IDMSG_GEN_NTK_ALERT_EVENT_29
30	CC_IDMSG_VOICE_MODE_EVENT_30
31	CC_IDMSG_VOICE_MODE_EVENT_31
32	CC_IDMSG_DIALING_COMPLETE_IND_32
33	CC_IDMSG_DIGITS_DONE_IND_33
34	CC_IDMSG_DIGITS_DONE_IND_34
35	CC_IDMSG_VBD_XMIT_DONE_IND_35
36	CC_IDMSG_FWD_SETUP_IND_36
37	CC_IDMSG_RSVP_DONE_IND_37
38	CC_IDMSG_AUDIT_RSP_IND_38
39	CC_IDMSG_XFR_STATUS_IND_39
40	CC_IDMSG_XFR_STATUS_IND_40
41	CC_IDMSG_XFR_DONE_IND_41
42	CC_IDMSG_XFR_DONE_IND_42
43	CC_IDMSG_XFR_DONE_IND_43
44	CC_IDMSG_TGT_CID_ACTIVE_RCD_44
45	CC_IDMSG_MODIFY_MEDIA_IND_45
46	CC_IDMSG_MODIFY_MEDIA_ACK_IND_46
47	CC_IDMSG_MODIFY_MEDIA_REJ_IND_47
48	CC_IDMSG_MODEM_CALL_START_IND_48
49	CC_IDMSG_MODEM_CALL_DONE_IND_49
50	CC_IDMSG_ACCT_STATUS_IND_50
51	CC_IDMSG_NW_STATUS_IND_51
52	CC_IDMSG_DESTINFO_IND_52
53	CC_IDMSG_LOOPBACK_DONE_IND_53
54	CC_IDMSG_RT_PACKET_STATS_IND_54
55	CC_IDMSG_CUT_PROGRESS_IND_55
56	CC_IDMSG_CUT_PROGRESS_IND_56
57	CC_IDMSG_PROCEEDING_IND_57
58	CC_IDMSG_FACILITY_IND_58
59	CC_IDMSG_INFO_IND_59
60	CC_IDMSG_PROGRESS_IND_60
61	CC_IDMSG_USERINFO_IND_61
62	CC_IDMSG_DISC_PROG_IND_62
63	CC_IDMSG_DISC_PROG_IND_63
64	CC_IDMSG_PING_DONE_IND_64
65	CC_IDMSG_COT_TEST_DONE_IND_65
66	CC_IDMSG_PROCESS_DONE_IND_66
67	CC_IDMSG_ASSOCIATED_IND_67
68	CC_IDMSG_SUSPEND_IND_68
69	CC_IDMSG_SUSPEND_ACK_IND_69
70	CC_IDMSG_SUSPEND_REJ_IND_70
71	CC_IDMSG_RESUME_IND_71
72	CC_IDMSG_RESUME_ACK_IND_72
73	CC_IDMSG_RESUME_REJ_IND_73
74	CC_IDMSG_IF_SETUP_REQ_PRIV_74
75	CC_IDMSG_IF_SETUP_REQ_PRIV_75
76	CC_IDMSG_IF_ALLOCATE_DSP_76
77	CC_IDMSG_CONNECT_77
78	CC_IDMSG_CONNECT_78
79	CC_IDMSG_PING_79
80	CC_IDMSG_DISCONNECT_80
81	CC_IDMSG_DISCONNECT_81
82	CC_IDMSG_DISCONNECT_82
83	CC_IDMSG_ALERT_83
84	CC_IDMSG_ALERT_84
85	CC_IDMSG_CUT_PROGRESS_85
86	CC_IDMSG_CUT_PROGRESS_86
87	CC_IDMSG_CUT_PROGRESS_87
88	CC_IDMSG_DISC_PROG_88
89	CC_IDMSG_DISC_PROG_89
90	CC_IDMSG_SET_PEER_90
91	CC_IDMSG_SET_PEER_91
92	CC_IDMSG_PROCEEDING_92
93	CC_IDMSG_SETUP_REQ_93
94	CC_IDMSG_SETUP_REQ_94
95	CC_IDMSG_SETUP_REQ_95
96	CC_IDMSG_SETUP_REQ_96
97	CC_IDMSG_SETUP_REQ_97
98	CC_IDMSG_SETUP_REQ_98
99	CC_IDMSG_SETUP_REQ_99
100	CC_IDMSG_SETUP_REQ_100
101	CC_IDMSG_SETUP_REQ_101
102	CC_IDMSG_SETUP_ACK_102
103	CC_IDMSG_FACILITY_103
104	CC_IDMSG_TRANSFER_REQ_104
105	CC_IDMSG_GET_CONSULT_ID_105
106	CC_IDMSG_FORWARD_TO_106
107	CC_IDMSG_INFO_107
108	CC_IDMSG_NOTIFY_108
109	CC_IDMSG_PROGRESS_109
110	CC_IDMSG_PRE_DISC_110
111	CC_IDMSG_PRE_DISC_111
112	CC_IDMSG_USER_INFO_112
113	CC_IDMSG_MODIFY_113
114	CC_IDMSG_DIGIT_114
115	CC_IDMSG_DIGIT_DIAL_115
116	CC_IDMSG_DIGIT_DIAL_STOP_116
117	CC_IDMSG_FEATURE_117
118	CC_IDMSG_FEATURE_ENABLE_118
119	CC_IDMSG_ASSOCIATE_STREAM_119
120	CC_IDMSG_ASSOCIATE_STREAM_120
121	CC_IDMSG_DISASSOCIATE_STREAM_121
122	CC_IDMSG_DISASSOCIATE_STREAM_122
123	CC_IDMSG_GENERATE_TONE_INFO_123
124	CC_IDMSG_SET_DIGIT_TIMEOUTS_124
125	CC_IDMSG_SET_DIGIT_TIMEOUTS_125
126	CC_IDMSG_SUSPEND_126
127	CC_IDMSG_SUSPEND_ACK_127
128	CC_IDMSG_SUSPEND_REJ_128
129	CC_IDMSG_RESUME_129
130	CC_IDMSG_RESUME_ACK_130
131	CC_IDMSG_RESUME_REJ_131
132	CC_IDMSG_UPDATE_REDIRECT_NUM_132
133	CC_IDMSG_BABBLER_AUDIT_133
134	CC_IDMSG_CONFERENCE_CREATE_134
135	CC_IDMSG_CONFERENCE_CREATE_135
136	CC_IDMSG_CONFERENCE_CREATE_136
137	CC_IDMSG_CONFERENCE_DESTROY_137
138	CC_IDMSG_CONFERENCE_DESTROY_138
139	CC_IDMSG_CONFERENCE_DESTROY_139
140	CC_IDMSG_LOOPBACK_140
141	CC_IDMSG_COT_TEST_141
142	CC_IDMSG_HANDOFF_142
143	CC_IDMSG_APP_RETURN_143
144	CC_IDMSG_T38_FAX_START_144
145	CC_IDMSG_T38_FAX_DONE_145
146	CC_IDMSG_CALL_PREEMPT_IND_146

Command Default

Debugging is not enabled.

Command Modes

Privileged EXEC

Command History

Release	Modification
11.3(6)NA2	This command was introduced.
12.2(11)T	This command was implemented on the following platforms: Cisco 2600 series, Cisco 3620, Cisco 3660, Cisco AS5350, Cisco AS5400, Cisco AS5850, Cisco AS5300, Cisco AS5800, and Cisco MC3810.
12.3(8)T	The all, default, detail, call, informational, software, individual, function, protoheaders, and service keywords were added.
12.4(4)XC	The range for the individual keyword was extended to 146, to include logs for call preemption indication information.
12.4(9)T	This command was integrated into Cisco IOS Release 12.4(9)T.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Examples

The following examples show output for variations of the debug voip ccapi command:

•debug voip ccapi detail on the Originating Gateway: Example

•debug voip ccapi detail on the Terminating Gateway: Example

•debug voip ccapi inout on the Originating Gateway: Example

•debug voip ccapi service on the Terminating Gateway: Example

•debug voip ccapi individual 146 on the Originating Gateway: Example

For these examples, the topology shown in Figure 1 is used.

Figure 1 Network Topology for debug voip ccapi Output Examples

debug voip ccapi detail on the Originating Gateway: Example

Router# debug voip ccapi detail 

voip ccapi detail debugging is on

Router#

*Apr 18 20:35:35.779: //-1/ABCE697D8005/CCAPI/cc_api_call_setup_ind_common:

   Interface Type=13, Protocol=0

*Apr 18 20:35:35.779: //-1/ABCE697D8005/CCAPI/ccCheckClipClir:

   Calling Party Number Is User Provided

*Apr 18 20:35:35.779: //11/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

   Total Call Count=0, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)

*Apr 18 20:35:35.779: //11/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

   Total Call Count=1

The following event shows that the CallEntry ID 11 is used for the incoming call leg.

*Apr 18 20:35:35.779: //11/ABCE697D8005/CCAPI/cc_insert_guid_pod_entry:

   Incoming=TRUE, Call Id=11

*Apr 18 20:35:35.779: //11/ABCE697D8005/CCAPI/cc_setupind_registration_lookup:

   Matching Parameters; Called Number=83103, Call Transfer Consult Id=

*Apr 18 20:35:35.779: //11/ABCE697D8005/CCAPI/cc_setupind_registration_lookup:

   No Matching Node

*Apr 18 20:35:35.779: //11/ABCE697D8005/CCAPI/ccCheckClipClir:

   Calling Party Number Is User Provided

*Apr 18 20:35:35.779: //12/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

   Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)

The following event shows that the incoming call leg with CallEntry ID 11 is bound to the outgoing call leg with CallEntry ID 12.

*Apr 18 20:35:35.779: //11/ABCE697D8005/CCAPI/cc_peer_bind:

   Bind=TRUE, Binder Call Id=11, Bindee Call Id=12

The next event shows that CallEntry ID 12 is used for the outgoing call leg.

*Apr 18 20:35:35.779: //12/ABCE697D8005/CCAPI/cc_insert_guid_pod_entry:

   Incoming=FALSE, Call Id=12

*Apr 18 20:35:35.779: //-1/xxxxxxxxxxxx/CCAPI/cc_api_supported_data:

   data_mode=0x10082

The next event shows an IP address for a remote device on the outgoing call leg, which indicates that this is the VoIP call leg.

*Apr 18 20:35:35.779: //12/ABCE697D8005/CCAPI/cc_incr_if_call_volume:

   Remote IP Address=172.16.13.81, Hwidb=FastEthernet0/0

*Apr 18 20:35:35.779: //12/ABCE697D8005/CCAPI/cc_incr_if_call_volume:

   Total Call Count=1, Voip Call Count=1, MMoip Call Count=0

*Apr 18 20:35:35.795: //11/ABCE697D8005/CCAPI/ccCallGetContext:

   Context=0x652C0168, Call Id=11

*Apr 18 20:36:31.419: //11/ABCE697D8005/CCAPI/ccCallDisconnect:

   Start Calling Accounting;

   Call Entry(Incoming=TRUE)

*Apr 18 20:36:31.419: //11/ABCE697D8005/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Disconnect Cause=16)

*Apr 18 20:36:31.419: //11/ABCE697D8005/CCAPI/ccCallDisconnect:

   Call Entry(Disconnect Cause=16)

At this point, the CallEntry ID changes as the call accounting process begins. The accounting data is sent over the outgoing call leg. The GUID, which identifies the unique call, remains the same.

*Apr 18 20:36:31.419: //12/ABCE697D8005/CCAPI/ccCallDisconnect:

   Start Calling Accounting;

   Call Entry(Incoming=FALSE)

*Apr 18 20:36:31.419: //12/ABCE697D8005/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Disconnect Cause=0)

The change of the CallEntry ID indicates that the call is using the incoming call leg, which is the POTS call leg in this case.

*Apr 18 20:36:31.423: //11/ABCE697D8005/CCAPI/ccCallGetVoipFlag:

   Data Bitmask=0x1, Call Id=11

*Apr 18 20:36:31.423: //11/ABCE697D8005/CCAPI/ccCallGetVoipFlag:

   Flag=FALSE

*Apr 18 20:36:31.423: //11/ABCE697D8005/CCAPI/cc_delete_guid_pod_entry:

   Incoming=TRUE

*Apr 18 20:36:31.423: //11/ABCE697D8005/CCAPI/cc_delete_call_entry:

   ccFreeRawMsgInfo=0x63FF8198

*Apr 18 20:36:31.423: //11/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)

*Apr 18 20:36:31.423: //11/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Total Call Count=0

*Apr 18 20:36:31.423: //11/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Deleting profileTable[0x64F44700]

The next line shows the impairment calculation. This is the only CCAPI debug command that shows impairment.

*Apr 18 20:36:31.423: //-1/ABCE697D8005/CCAPI/g113_calculate_impairment:

   (delay=91(ms), loss=0%), Io=0 Iq=0 Idte=0 Idd=2 Ie=10 Itot=12

*Apr 18 20:36:31.423: //12/ABCE697D8005/CCAPI/ccCallGetVoipFlag:

   Data Bitmask=0x1, Call Id=12

*Apr 18 20:36:31.423: //12/ABCE697D8005/CCAPI/ccCallGetVoipFlag:

   Flag=FALSE

*Apr 18 20:36:31.423: //12/ABCE697D8005/CCAPI/cc_decr_if_call_volume:

   Remote IP Address=172.16.13.81, Hwidb=FastEthernet0/0

*Apr 18 20:36:31.423: //12/ABCE697D8005/CCAPI/cc_decr_if_call_volume:

   Total Call Count=0, Voip Call Count=0, MMoip Call Count=0

*Apr 18 20:36:31.423: //12/ABCE697D8005/CCAPI/cc_delete_guid_pod_entry:

   Incoming=FALSE

*Apr 18 20:36:31.423: //12/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Total Call Count=0, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)

*Apr 18 20:36:31.423: //12/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Deleting profileTable[0x652E3310]

*Apr 18 20:36:31.427: //12/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

   Call Entry Is Not Found

debug voip ccapi detail on the Terminating Gateway: Example

Router# debug voip ccapi detail 

voip ccapi detail debugging is on

Router#

*May  1 18:58:26.251: //-1/xxxxxxxxxxxx/CCAPI/cc_api_supported_data:

   data_mode=0x10082

*May  1 18:58:26.255: //8/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

   Call Entry Is Not Found

*May  1 18:58:26.255: //-1/ABCE697D8005/CCAPI/cc_api_call_setup_ind_common:

   Interface Type=0, Protocol=1

*May  1 18:58:26.255: //-1/ABCE697D8005/CCAPI/ccCheckClipClir:

   Calling Party Number Is User Provided

The following line shows the attributes of the calling number:

*May  1 18:58:26.255: //-1/ABCE697D8005/CCAPI/cc_api_call_setup_ind_common:

   After Number Translation Checking:

   Calling Number=4085550111(TON=National, NPI=ISDN, Screening=User, Passed, 
Presentation=Allowed),

   Called Number=3600(TON=Unknown, NPI=Unknown)

*May  1 18:58:26.255: //8/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

   Total Call Count=0, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)

*May  1 18:58:26.255: //8/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

   Total Call Count=1

*May  1 18:58:26.255: //8/ABCE697D8005/CCAPI/cc_insert_guid_pod_entry:

   Incoming=TRUE, Call Id=8

The following line shows the IP address of the originating gateway:

*May  1 18:58:26.255: //8/ABCE697D8005/CCAPI/cc_incr_if_call_volume:

   Remote IP Address=172.16.13.175, Hwidb=FastEthernet0/0

*May  1 18:58:26.255: //8/ABCE697D8005/CCAPI/cc_incr_if_call_volume:

   Total Call Count=1, Voip Call Count=1, MMoip Call Count=0

*May  1 18:58:26.255: //8/ABCE697D8005/CCAPI/cc_setupind_registration_lookup:

   Matching Parameters; Called Number=3600, Call Transfer Consult Id=

*May  1 18:58:26.255: //8/ABCE697D8005/CCAPI/cc_setupind_registration_lookup:

   No Matching Node

*May  1 18:58:26.255: //8/ABCE697D8005/CCAPI/ccCheckClipClir:

   Calling Party Number Is User Provided

*May  1 18:58:26.259: //9/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

   Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)

*May  1 18:58:26.259: //8/ABCE697D8005/CCAPI/cc_peer_bind:

   Bind=TRUE, Binder Call Id=8, Bindee Call Id=9

*May  1 18:58:26.259: //9/ABCE697D8005/CCAPI/cc_insert_guid_pod_entry:

   Incoming=FALSE, Call Id=9

*May  1 18:58:26.259: //9/ABCE697D8005/CCAPI/cc_set_voice_port_value:

   CC_IF_TELEPHONY: Echo=0, Playout=0

*May  1 18:58:26.263: //9/ABCE697D8005/CCAPI/ccCallGetContext:

   Context=0x64B6BB5C, Call Id=9

*May  1 18:59:21.871: //8/ABCE697D8005/CCAPI/ccCallDisconnect:

   Start Calling Accounting;

   Call Entry(Incoming=TRUE)

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Disconnect Cause=16)

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/ccCallDisconnect:

   Call Entry(Disconnect Cause=16)

*May  1 18:59:21.875: //9/ABCE697D8005/CCAPI/ccCallDisconnect:

   Start Calling Accounting;

   Call Entry(Incoming=FALSE)

*May  1 18:59:21.875: //9/ABCE697D8005/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Disconnect Cause=0)

The next line shows the impairment calculation. This is the only CCAPI debug command that shows impairment.

*May  1 18:59:21.875: //-1/ABCE697D8005/CCAPI/g113_calculate_impairment:

   (delay=99(ms), loss=0%), Io=0 Iq=0 Idte=0 Idd=2 Ie=10 Itot=12

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/ccCallGetVoipFlag:

   Data Bitmask=0x1, Call Id=8

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/ccCallGetVoipFlag:

   Flag=FALSE

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/cc_decr_if_call_volume:

   Remote IP Address=172.16.13.175, Hwidb=FastEthernet0/0

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/cc_decr_if_call_volume:

   Total Call Count=0, Voip Call Count=0, MMoip Call Count=0

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/cc_delete_guid_pod_entry:

   Incoming=TRUE

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/cc_delete_call_entry:

   ccFreeRawMsgInfo=0x644EB850

Router#

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)

*May  1 18:59:21.875: //8/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Total Call Count=0

*May  1 18:59:21.879: //8/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Deleting profileTable[0x64B78600]

*May  1 18:59:21.879: //8/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

   Call Entry Is Not Found

*May  1 18:59:21.879: //8/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

   Call Entry Is Not Found

Router#

*May  1 18:59:24.587: //9/ABCE697D8005/CCAPI/ccCallGetVoipFlag:

   Data Bitmask=0x1, Call Id=9

*May  1 18:59:24.587: //9/ABCE697D8005/CCAPI/ccCallGetVoipFlag:

   Flag=FALSE

*May  1 18:59:24.587: //9/ABCE697D8005/CCAPI/cc_api_call_disconnect_done:

   Prefix Is Not Defined From Peer; Peer=3600, Called Number=3600

*May  1 18:59:24.587: //9/ABCE697D8005/CCAPI/cc_delete_guid_pod_entry:

   Incoming=FALSE

*May  1 18:59:24.587: //9/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Total Call Count=0, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)

*May  1 18:59:24.587: //9/ABCE697D8005/CCAPI/cc_delete_call_entry:

   Deleting profileTable[0x6453F228]

debug voip ccapi inout on the Originating Gateway: Example

Router# debug voip ccapi inout 

voip ccapi inout debugging is on

Router#

*Apr 18 20:42:19.347: //-1/9C5A9CA88009/CCAPI/cc_api_display_ie_subfields:

   cc_api_call_setup_ind_common:

   acme-username=

   ----- ccCallInfo IE subfields -----

   acme-ani=4085550111

   acme-anitype=2

   acme-aniplan=1

   acme-anipi=0

   acme-anisi=1

   dest=83103

   acme-desttype=0

   acme-destplan=0

   acme-rdn=

   acme-rdntype=-1

   acme-rdnplan=-1

   acme-rdnpi=-1

   acme-rdnsi=-1

   acme-redirectreason=-1

The following lines show information about the calling and called numbers. The network presentation indicator (NPI) shows the type of transmission. The Incoming Dial-Peer field shows that the incoming dial peer has been matched.

*Apr 18 20:42:19.347: //-1/9C5A9CA88009/CCAPI/cc_api_call_setup_ind_common:

   Interface=0x64F26F10, Call Info(

   Calling Number=4085550111(TON=National, NPI=ISDN, Screening=User, Passed, 
Presentation=Allowed),

   Called Number=83103(TON=Unknown, NPI=Unknown),

   Calling Translated=FALSE, Subsriber Type Str=RegularLine, FinalDestinationFlag=TRUE,

   Incoming Dial-peer=1, Progress Indication=NULL(0), Calling IE Present=TRUE,

   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call 
Id=-1

*Apr 18 20:42:19.347: //-1/9C5A9CA88009/CCAPI/ccCheckClipClir:

   In: Calling Number=4085550111(TON=National, NPI=ISDN, Screening=User, Passed, 
Presentation=Allowed)

*Apr 18 20:42:19.347: //-1/9C5A9CA88009/CCAPI/ccCheckClipClir:

   Out: Calling Number=4085550111(TON=National, NPI=ISDN, Screening=User, Passed, 
Presentation=Allowed)

In the following event, the call leg is established. The CallEntry ID field changes from -1 to 19.

*Apr 18 20:42:19.347: //19/9C5A9CA88009/CCAPI/cc_api_call_setup_ind_common:

   Set Up Event Sent;

   Call Info(Calling Number=4085550111(TON=National, NPI=ISDN, Screening=User, Passed, 
Presentation=Allowed),

   Called Number=83103(TON=Unknown, NPI=Unknown))

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/cc_process_call_setup_ind:

   Event=0x63FF4730

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccCallSetContext:

   Context=0x652A9858

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/cc_process_call_setup_ind:

   >>>>CCAPI handed cid 19 with tag 1 to app "Default"

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccCallProceeding:

   Progress Indication=NULL(0)

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccCallSetupRequest:

   Destination=, Calling IE Present=TRUE, Mode=0,

   Outgoing Dial-peer=3600, Params=0x652AA4A8, Progress Indication=NULL(0)

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccCheckClipClir:

   In: Calling Number=4085550111(TON=National, NPI=ISDN, Screening=User, Passed, 
Presentation=Allowed)

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccCheckClipClir:

   Out: Calling Number=4085550111(TON=National, NPI=ISDN, Screening=User, Passed, 
Presentation=Allowed)

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccCallSetupRequest:

   Destination Pattern=360., Called Number=3600, Digit Strip=FALSE

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccCallSetupRequest:

   Calling Number=4085550111(TON=National, NPI=ISDN, Screening=User, Passed, 
Presentation=Allowed),

   Called Number=3600(TON=Unknown, NPI=Unknown),

   Redirect Number=, Display Info=

   Account Number=, Final Destination Flag=TRUE,

   Guid=9C5A9CA8-5243-11D6-8009-00059A3A15A0, Outgoing Dial-peer=3600

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/cc_api_display_ie_subfields:

   ccCallSetupRequest:

   cisco-username=

   ----- ccCallInfo IE subfields -----

   cisco-ani=4085550111

   cisco-anitype=2

   cisco-aniplan=1

   cisco-anipi=0

   cisco-anisi=1

   dest=3600

   cisco-desttype=0

   cisco-destplan=0

   cisco-rdn=

   cisco-rdntype=-1

   cisco-rdnplan=-1

   cisco-rdnpi=-1

   cisco-rdnsi=-1

   cisco-redirectreason=-1

In the following lines, the outgoing dial peer is matched:

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccIFCallSetupRequestPrivate:

   Interface=0x63EAF24C, Interface Type=1, Destination=, Mode=0x0,

   Call Params(Calling Number=4085550111(TON=National, NPI=ISDN, Screening=User, Passed, 
Presentation=Allowed),

   Called Number=3600(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

   Subsriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=3600, 
Call Count On=FALSE,

   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application 
Call Id=)

*Apr 18 20:42:19.351: //20/9C5A9CA88009/CCAPI/ccIFCallSetupRequestPrivate:

   SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1

*Apr 18 20:42:19.351: //20/9C5A9CA88009/CCAPI/ccCallSetContext:

   Context=0x652AA458

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccSaveDialpeerTag:

   Outgoing Dial-peer=3600

*Apr 18 20:42:19.351: //19/9C5A9CA88009/CCAPI/ccSaveDialpeerTag:

   Outgoing Dial-peer=3600

*Apr 18 20:42:19.367: //20/9C5A9CA88009/CCAPI/cc_api_call_proceeding:

   Interface=0x652F6388, Progress Indication=NULL(0)

The following lines show call progress. The progress and signal indications are shown.

*Apr 18 20:42:19.371: //20/9C5A9CA88009/CCAPI/cc_api_call_cut_progress:

   Interface=0x652F6388, Progress Indication=INBAND(8), Signal Indication=SIGNAL 
RINGBACK(1),

   Cause Value=0

*Apr 18 20:42:19.371: //20/9C5A9CA88009/CCAPI/cc_api_call_cut_progress:

   Call Entry(Responsed=TRUE)

*Apr 18 20:42:19.371: //19/9C5A9CA88009/CCAPI/ccCallCutProgress:

   Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1), Cause Value=0

   Voice Call Send Alert=FALSE, Call Entry(AlertSent=FALSE)

*Apr 18 20:42:19.371: //19/9C5A9CA88009/CCAPI/ccCallCutProgress:

   Call Entry(Responsed=TRUE)

The following lines show the tone generation information:

*Apr 18 20:42:19.371: //19/9C5A9CA88009/CCAPI/ccGenerateToneInfo:

   Stop Tone On Digit=FALSE, Tone=Null,

   Tone Direction=Network, Params=0x0, Call Id=19

*Apr 18 20:42:19.371: //19/9C5A9CA88009/CCAPI/ccConferenceCreate:

   Conference Id=0x652F723C, Call Id1=19, Call Id2=20, Tag=0x0

*Apr 18 20:42:19.371: //20/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:

   Conference Id=0x6, Source Interface=0x63EAF24C, Source Call Id=20,

   Destination Call Id=19, Disposition=0x0, Tag=0x0

*Apr 18 20:42:19.371: //19/9C5A9CA88009/CCAPI/ccConferenceCreate:

   Call Entry(Conference Id=0x6, Destination Call Id=20)

*Apr 18 20:42:19.371: //20/9C5A9CA88009/CCAPI/ccConferenceCreate:

   Call Entry(Conference Id=0x6, Destination Call Id=19)

*Apr 18 20:42:19.371: //19/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:

   Conference Id=0x6, Source Interface=0x64F26F10, Source Call Id=19,

   Destination Call Id=20, Disposition=0x0, Tag=0x0

*Apr 18 20:42:19.371: //19/9C5A9CA88009/CCAPI/cc_generic_bridge_done:

   Conference Id=0x6, Source Interface=0x64F26F10, Source Call Id=19,

   Destination Call Id=20, Disposition=0x0, Tag=0x0

*Apr 18 20:42:19.371: //19/9C5A9CA88009/CCAPI/cc_api_caps_ind:

   Destination Interface=0x63EAF24C, Destination Call Id=20, Source Call Id=19,

   Caps(Codec=0x2887F, Fax Rate=0xBF, Vad=0x3,

   Modem=0x2, Codec Bytes=0, Signal Type=3)

*Apr 18 20:42:19.371: //19/9C5A9CA88009/CCAPI/cc_api_caps_ind:

   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),

   Playout Max=300(ms), Fax Nom=300(ms))

*Apr 18 20:42:19.371: //19/9C5A9CA88009/CCAPI/cc_process_notify_bridge_done:

   Conference Id=0x6, Call Id1=19, Call Id2=20

*Apr 18 20:42:19.375: //20/9C5A9CA88009/CCAPI/cc_api_caps_ind:

   Destination Interface=0x64F26F10, Destination Call Id=19, Source Call Id=20,

   Caps(Codec=0x4, Fax Rate=0x1, Vad=0x2,

   Modem=0x2, Codec Bytes=20, Signal Type=2)

*Apr 18 20:42:19.375: //20/9C5A9CA88009/CCAPI/cc_api_caps_ind:

   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),

   Playout Max=300(ms), Fax Nom=300(ms))

The following lines show codec information:

*Apr 18 20:42:19.375: //20/9C5A9CA88009/CCAPI/cc_api_caps_ack:

   Destination Interface=0x64F26F10, Destination Call Id=19, Source Call Id=20,

   Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_NONE(0x1), Vad=ON(0x2),

   Modem=ON(0x2), Codec Bytes=20, Signal Type=2, Seq Num Start=6872)

*Apr 18 20:42:19.375: //19/9C5A9CA88009/CCAPI/cc_api_caps_ack:

   Destination Interface=0x63EAF24C, Destination Call Id=20, Source Call Id=19,

   Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_NONE(0x1), Vad=ON(0x2),

   Modem=ON(0x2), Codec Bytes=20, Signal Type=2, Seq Num Start=6872)

*Apr 18 20:42:19.375: //19/9C5A9CA88009/CCAPI/cc_api_voice_mode_event:

   Call Id=19

*Apr 18 20:42:19.375: //19/9C5A9CA88009/CCAPI/cc_api_voice_mode_event:

   Call Entry(Context=0x652A9858)

The following lines show progress indication information. In this case, the event shows that the destination is not ISDN.

*Apr 18 20:42:26.855: //20/9C5A9CA88009/CCAPI/cc_api_call_connected:

   Interface=0x652F6388, Data Bitmask=0x0, Progress Indication=DESTINATION IS NON ISDN(2),

   Connection Handle=0

*Apr 18 20:42:26.855: //20/9C5A9CA88009/CCAPI/cc_api_call_connected:

   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)

*Apr 18 20:42:26.855: //19/9C5A9CA88009/CCAPI/ccCallConnect:

   Progress Indication=DESTINATION IS NON ISDN(2), Data Bitmask=0x0

*Apr 18 20:42:26.855: //19/9C5A9CA88009/CCAPI/ccCallConnect:

   Call Entry(Connected=TRUE, Responsed=TRUE)

*Apr 18 20:42:26.855: //20/9C5A9CA88009/CCAPI/ccSaveDialpeerTag:

   Incoming Dial-peer=1

*Apr 18 20:42:26.859: //19/9C5A9CA88009/CCAPI/ccSaveDialpeerTag:

   Outgoing Dial-peer=3600

*Apr 18 20:42:26.859: //20/9C5A9CA88009/CCAPI/ccCallFeature:

   Feature Type=24, Call Id=20

This event shows that the call is disconnected.

*Apr 18 20:43:16.795: //19/9C5A9CA88009/CCAPI/cc_api_call_disconnected:

   Cause Value=16, Interface=0x64F26F10, Call Id=19

*Apr 18 20:43:16.795: //19/9C5A9CA88009/CCAPI/cc_api_call_disconnected:

   Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)

*Apr 18 20:43:16.795: //19/9C5A9CA88009/CCAPI/ccConferenceDestroy:

   Conference Id=0x6, Tag=0x0

*Apr 18 20:43:16.795: //20/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x6, Source Interface=0x63EAF24C, Source Call Id=20,

   Destination Call Id=19, Disposition=0x0, Tag=0x0

*Apr 18 20:43:16.795: //19/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x6, Source Interface=0x64F26F10, Source Call Id=19,

   Destination Call Id=20, Disposition=0x0, Tag=0x0

*Apr 18 20:43:16.795: //19/9C5A9CA88009/CCAPI/cc_generic_bridge_done:

   Conference Id=0x6, Source Interface=0x64F26F10, Source Call Id=19,

   Destination Call Id=20, Disposition=0x0, Tag=0x0

*Apr 18 20:43:16.795: //19/9C5A9CA88009/CCAPI/ccCallDisconnect:

   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)

*Apr 18 20:43:16.795: //19/9C5A9CA88009/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

*Apr 18 20:43:16.795: //19/9C5A9CA88009/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

*Apr 18 20:43:16.795: //20/9C5A9CA88009/CCAPI/ccCallDisconnect:

   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

*Apr 18 20:43:16.795: //20/9C5A9CA88009/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

*Apr 18 20:43:16.795: //20/9C5A9CA88009/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

*Apr 18 20:43:16.803: //20/9C5A9CA88009/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x652F6388, Tag=0x0, Call Id=20,

   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

*Apr 18 20:43:16.803: //20/9C5A9CA88009/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

*Apr 18 20:43:16.803: //19/9C5A9CA88009/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x64F26F10, Tag=0x0, Call Id=19,

   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

*Apr 18 20:43:16.803: //19/9C5A9CA88009/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

debug voip ccapi service on the Terminating Gateway: Example

Router# debug voip ccapi service 

voip ccapi service debugging is on

*May  1 19:08:41.803: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

   Searching Node;

   Called Number=3600, Call Transfer Consult Id=

This debug shows noncall related events. In this case, information about the timer is shown.

*May  1 19:08:48.027: //-1/xxxxxxxxxxxx/CCAPI/cc_handle_periodic_timer:

   Calling The Callback, ccTimerctx=0x63B368C0

*May  1 19:08:48.027: //-1/xxxxxxxxxxxx/CCAPI/ccTimerStart:

   ccTimerctx=0x63B368C0

*May  1 19:10:08.615: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif:

   ExpectFactor=0xA

debug voip ccapi individual 146 on the Originating Gateway: Example

This debug shows the preemption tone timer getting initiated.

*Aug 24 18:28:16.919: //18958/B37648B6AF48/CCAPI/cc_api_call_preempt_ind:

   PreemptionToneTimer=10(s)

debug voip ccapi error

To trace error logs in the call control application programming interface (CCAPI), use the debug voip ccapi error command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip ccapi error

no debug voip ccapi error

Syntax Description

This command has no arguments or keywords.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.2(11)T	This command was implemented on the following platforms: Cisco 2600 series, Cisco 3620, Cisco 3660, Cisco AS5350, Cisco AS5400, Cisco AS5850, Cisco AS5300, Cisco AS5800, and Cisco MC3810.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

The debug voip ccapi error command traces the error logs in the call control API. Error logs are generated during normal call processing, when there are insufficient resources, or when there are problems in the underlying network-specific code, the higher call session application, or the call control API itself.

This debug command shows error events or unexpected behavior in system software. In most cases, no events will be generated.

---

Note We recommend that you log output from the debug voip ccapi error command to a buffer rather than sending the output to the console; otherwise, the size of the output could severely impact the performance of the gateway.

---

Related Commands

Command	Description
debug voip ccapi inout	Traces the execution path through the CCAPI.
voice call debug	Allows configuration of the voice call debug output.

debug voip ccapi inout

To trace the execution path through the call control application programming interface (CCAPI), use the debug voip ccapi inout command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip ccapi inout

no debug voip ccapi inout

Syntax Description

This command has no arguments or keywords.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.2(11)T	This command was implemented on the following platforms: Cisco 2600 series, Cisco 3620, Cisco 3660, Cisco AS5350, Cisco AS5400, Cisco AS5850, Cisco AS5300, Cisco AS5800, and Cisco MC3810.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

The debug voip ccapi inout command traces the execution path through the call control API, which serves as the interface between the call session application and the underlying network-specific software. You can use the output from this command to understand how calls are being handled by the voice gateway.

This command shows how a call flows through the system. Using this debug level, you can see the call setup and teardown operations performed on both the telephony and network call legs.

---

Note We recommend that you log output from the debug voip ccapi inout command to a buffer rather than sending the output to the console; otherwise, the size of the output could severely impact the performance of the gateway.

---

Examples

The following example shows the call setup indicated and accepted by the voice gateway:

Router# debug voip ccapi inout

*Mar  1 15:35:53.588: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: 

usrContainer[0x638C1BF0], magic[FACE0FFF]

*Mar  1 15:35:53.592: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: 

container=0x638C1BF0, tagID=6, dataSize=16, instID=-1,modifier=1

*Mar  1 15:35:53.592: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: 

tdObject[0x638BC1AC], nxtElem[0x0], magic[0xFACE0FFF] tagID[6], dataLen[16], 

modif[1]

*Mar  1 15:35:53.592: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: 

Adding tdObject[0x638BC1AC] instID[-1] into container[0x638C1BF0]

*Mar  1 15:35:53.592: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: 

container=0x638C1BF0, tagID=5, dataSize=276, instID=-1,modifier=1

*Mar  1 15:35:53.592: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: 

tdObject[0x63401148], nxtElem[0x0], magic[0xFACE0FFF] tagID[5], dataLen[276], 

modif[1]

*Mar  1 15:35:53.592: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: 

Adding tdObject[0x63401148] instID[-1] into container[0x638C1BF0]

In the following lines, the call control API (CCAPI) receives the call setup. The called number is 34999, and the calling number is 55555. The calling number matches dial peer 10002.

*Mar  1 15:35:53.592: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:

*Mar  1 15:35:53.592: cc_api_call_setup_ind:

*Mar  1 15:35:53.592:  cisco-username=

*Mar  1 15:35:53.596: ----- ccCallInfo IE subfields -----

*Mar  1 15:35:53.596:  cisco-ani=55555

*Mar  1 15:35:53.596:  cisco-anitype=0

*Mar  1 15:35:53.596:  cisco-aniplan=0

*Mar  1 15:35:53.596:  cisco-anipi=0 

*Mar  1 15:35:53.596:  cisco-anisi=0

*Mar  1 15:35:53.596:  dest=34999

*Mar  1 15:35:53.596:  cisco-desttype=0

*Mar  1 15:35:53.596:  cisco-destplan=0 

*Mar  1 15:35:53.596:  cisco-rdn=

*Mar  1 15:35:53.596:  cisco-rdntype=-1

*Mar  1 15:35:53.596:  cisco-rdnplan=-1 

*Mar  1 15:35:53.596:  cisco-rdnpi=-1

*Mar  1 15:35:53.596:  cisco-rdnsi=-1

*Mar  1 15:35:53.596:  cisco-redirectreason=-1

*Mar  1 15:35:53.596: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: 

(vdbPtr=0x637EC1E0, 
callInfo={called=34999,called_oct3=0x80,calling=55555,calling_oct3=0x80,calling_oct3a=0x0,
calling_xlated=false,subscriber_type_str=RegularLine,fdest=1,

peer_tag=10002, prog_ind=0,callingIE_present 1, src_route_label=, tgt_route_label= 
clid_transparent=0},callID=0x637B4278)

*Mar  1 15:35:53.596: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind:

*Mar  1 15:35:53.596: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 13 , prot 0

*Mar  1 15:35:53.596: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

*Mar  1 15:35:53.596: ccCheckClipClir: calling number is: "55555", calling oct3a is: 0x0

*Mar  1 15:35:53.596: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

*Mar  1 15:35:53.596: Calling Party number is User Provided

*Mar  1 15:35:53.596: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

*Mar  1 15:35:53.596: Leaving ccCheckClipClir

  calling number is: "55555"

  calling oct3 is:  0x80

  calling oct3a is: 0x0

In the next line, 44 is the CallEntry ID.

*Mar  1 15:35:53.600: //44/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 
0 

*Mar  1 15:35:53.600: //44/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 1

*Mar  1 15:35:53.600: //44/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE.

*Mar  1 15:35:53.600: //44/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is TRUE

*Mar  1 15:35:53.600: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: 
profileTable[0x6380E11C], numBuckets[11], numEntries[0]

*Mar  1 15:35:53.600: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtProfileTableBuildManager: Invoking 
necessary profileTable updaters...

*Mar  1 15:35:53.600: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: 
Updating profileTable[0x6380E11C] with objects in container[0x638C1BF0]

*Mar  1 15:35:53.600: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: 
obtained key[5] for the tag[6]

*Mar  1 15:35:53.600: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: 
profileTable[0x6380E11C], tdObject[0x638BC1AC]

*Mar  1 15:35:53.600: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: 
obtained key[0] for the tag[5]

*Mar  1 15:35:53.600: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: 
profileTable[0x6380E11C], tdObject[0x63401148]

*Mar  1 15:35:53.600: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtProfileTableBuildManager:

*Mar  1 15:35:53.600: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6380E11C],

 numBuckets[11], numEntries[2]

*Mar  1 15:35:53.600: Bucket { 0 } ------>0x63401148[0x0,t-5,l-276,d-0x63401168,

m-1,u-56153,g-FACE0FFF]

*Mar  1 15:35:53.604:

*Mar  1 15:35:53.604: Bucket { 5 } 
------>0x638BC1AC[0x0,t-6,l-16,d-0x638BC1CC,m-1,u-56153,g-FACE0FFF]

*Mar  1 15:35:53.604:

*Mar  1 15:35:53.604: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: 
Container[0x638C1BF0]

*Mar  1 15:35:53.604: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: not the VoIP or 
MMoIP

*Mar  1 15:35:53.608: //-1/xxxxxxxxxxxx/CCAPI/cc_process_call_setup_ind: (event=

0x63073AA0)

In the next line, 45F2AAE28044 is the GUID. The tag 10002 entry shows that the incoming dial peer matched the CallEntry ID.

*Mar  1 15:35:53.608: //44/45F2AAE28044/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed 
cid 44 with tag 10002 to app "DEFAULT" 

*Mar  1 15:35:53.608: //44/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: 
ev(24=CC_EV_CALL_SETUP_IND), cid(44), disp(0)

*Mar  1 15:35:53.608: //44/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(SSA_EV_CALL_SETUP_IND), 
cid(44), disp(0)

*Mar  1 15:35:53.608: //44/xxxxxxxxxxxx/SSAPP:-1:-1/ssaCallSetupInd:

The next line shows CallEntry ID in hexadecimal form, 0x2C (44 in decimal). The CallID and GUID numbers have been identified. The incoming dial-peer is 10002.

*Mar  1 15:35:53.608: //44/xxxxxxxxxxxx/CCAPI/ccCallSetContext: (callID=0x2C, 
context=0x634A430C) 

*Mar  1 15:35:53.608: //44/45F2AAE28044/SSAPP:10002:-1/ssaCallSetupInd: cid(44), 
st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag

 = 1 

*Mar  1 15:35:53.608: //44/45F2AAE28044/SSAPP:10002:-1/ssaCallSetupInd: src route label=, 
tgt route label= tg_label_flag 0x0

*Mar  1 15:35:53.608: //44/45F2AAE28044/SSAPP:10002:-1/ssaCallSetupInd: finalDest 
cllng(55555), clled(34999) tgt_route_label()tg_label_flag 0x0

*Mar  1 15:35:53.612: //44/45F2AAE28044/SSAPP:10002:-1/ssaCallSetupInd: cid(44),

 st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= 0

For CallEntry ID 44, two dial-peer tags (10001 and 20002) were matched with called number 34999.

*Mar  1 15:35:53.612: //44/45F2AAE28044/SSAPP:10002:-1/ssaDebugPeers: ssaSetupPe

er cid(44) peer list: tag(10001) called number (34999) tag(20002) called number

(34999) 

*Mar  1 15:35:53.612: //44/45F2AAE28044/SSAPP:10002:-1/ssaSetupPeer: dialpeer tags in 
rotary= 10001  20002

The next line shows that 5 digits were matched for this dial peer and no prefix was added. The encapType (2) entry indicates a VoIP call.

*Mar  1 15:35:53.612: //44/45F2AAE28044/SSAPP:10002:-1/ssaSetupPeer: cid(44), de

stPat(34999), matched(5), prefix(), peer(637B0984), peer->encapType (2)

*Mar  1 15:35:53.612: //-1/xxxxxxxxxxxx/CCAPI/cc_can_gateway: Call legs: In=6, O

ut=1

The next line shows the voice gateway sending out a call-proceeding message to the incoming call leg with progress indicator of 0x0.

*Mar  1 15:35:53.612: //44/xxxxxxxxxxxx/CCAPI/ccCallProceeding: (callID=0x2C, pr

og_ind=0x0) 

The next line shows the voice gateway sending out the call-setup request to the outgoing call leg. The dial-peer is 10001 with the incoming CallEntry ID being 0x2C.

*Mar  1 15:35:53.612: //44/xxxxxxxxxxxx/CCAPI/ccCallSetupRequest: (Inbound call

= 0x2C, outbound peer =10001, dest=,

        params=0x63085D80 mode=0, *callID=0x63086314, prog_ind = 0callingIE_pres

ent 1) 

*Mar  1 15:35:53.612: //44/45F2AAE28044/CCAPI/ccCallSetupRequest:

*Mar  1 15:35:53.612: ccCallSetupRequest numbering_type 0x80 

*Mar  1 15:35:53.612: //44/45F2AAE28044/CCAPI/ccCallSetupRequest:

*Mar  1 15:35:53.616: ccCallSetupRequest: calling number is:55555

*Mar  1 15:35:53.616: //44/45F2AAE28044/CCAPI/ccCallSetupRequest: calling oct3a

is:0x0

*Mar  1 15:35:53.616: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

*Mar  1 15:35:53.616: ccCheckClipClir: calling number is: "55555", calling oct3a

 is: 0x0

*Mar  1 15:35:53.616: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

*Mar  1 15:35:53.616: Calling Party number is User Provided

*Mar  1 15:35:53.616: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir:

*Mar  1 15:35:53.616: Leaving ccCheckClipClir

  calling number is: "55555"

  calling oct3 is:  0x80

  calling oct3a is: 0x0

*Mar  1 15:35:53.616: //44/45F2AAE28044/CCAPI/ccCallSetupRequest: after ccCheckC

lipClir - calling oct3a is:0x0

The next line shows that all digits are passed.

*Mar  1 15:35:53.616: //44/45F2AAE28044/CCAPI/ccCallSetupRequest: dest pattern 3

4999, called 34999, digit_strip 0 

*Mar  1 15:35:53.616: //44/45F2AAE28044/CCAPI/ccCallSetupRequest:

*Mar  1 15:35:53.616: callingNumber=55555, calledNumber=34999, redirectNumber= d

isplay_info= calling_oct3a=0

*Mar  1 15:35:53.616: accountNumber=, finalDestFlag=1,

guid=45f2.aae2.1571.11cc.8044.95f5.fabb.6b0f

*Mar  1 15:35:53.616: peer_tag=10001

*Mar  1 15:35:53.616: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields:

*Mar  1 15:35:53.616: ccCallSetupRequest:

*Mar  1 15:35:53.616:  cisco-username=

*Mar  1 15:35:53.616: ----- ccCallInfo IE subfields -----

*Mar  1 15:35:53.616:  cisco-ani=55555

*Mar  1 15:35:53.616:  cisco-anitype=0

*Mar  1 15:35:53.616:  cisco-aniplan=0

*Mar  1 15:35:53.616:  cisco-anipi=0

*Mar  1 15:35:53.616:  cisco-anisi=0

*Mar  1 15:35:53.620:  dest=34999

*Mar  1 15:35:53.620:  cisco-desttype=0

*Mar  1 15:35:53.620:  cisco-destplan=0

*Mar  1 15:35:53.620:  cisco-rdn=

*Mar  1 15:35:53.620:  cisco-rdntype=-1

*Mar  1 15:35:53.620:  cisco-rdnplan=-1

*Mar  1 15:35:53.620:  cisco-rdnpi=-1

*Mar  1 15:35:53.620:  cisco-rdnsi=-1

*Mar  1 15:35:53.620:  cisco-redirectreason=-1

*Mar  1 15:35:53.620: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: (vdbP

tr=0x62EC61A4, dest=, callParams={called=34999,called_oct3=0x80, 
calling=55555,calling_oct3=0x80, calling_oct3a= 0x0, calling_xlated=false,  
subscriber_type_str

=RegularLine, fdest=1, voice_peer_tag=10001},mode=0x0)

*Mar  1 15:35:53.620: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:

*Mar  1 15:35:53.620: ccIFCallSetupRequestPrivate: src route label  tgt route label 
tg_label_flag 0x0

*Mar  1 15:35:53.620: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:  vdbPtr type = 
1

*Mar  1 15:35:53.620: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:

*Mar  1 15:35:53.620: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: 
(vdbPtr=0x62EC61A4, dest=, callParams={called=34999, called_oct3 0x80,  
calling=55555,calling_oct3 0x80, calling_oct3a 0x0, calling_xlated=false,  fdest=1, 
voice_pee

r_tag=10001}, mode=0x0, xltrc=-5)

*Mar  1 15:35:53.620: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:

In the next line, outgoing CallEntry ID 45 is bound to the same GUID 45F2AAE28044.

*Mar  1 15:35:53.620: //45/45F2AAE28044/CCAPI/cc_insert_call_entry: not incoming

 entry 

*Mar  1 15:35:53.620: //45/45F2AAE28044/CCAPI/cc_insert_call_entry: entry's incoming 
FALSE.

*Mar  1 15:35:53.620: //45/45F2AAE28044/CCAPI/cc_insert_call_entry: is_incoming

is FALSE

*Mar  1 15:35:53.624: //44/xxxxxxxxxxxx/CCAPI/ccSaveDialpeerTag: (callID=0x2C, 
dialpeer_tag=10001)

*Mar  1 15:35:53.624: //45/xxxxxxxxxxxx/CCAPI/ccCallSetContext: (callID=0x2D, 
context=0x634A537C) 0x2D (decimal 45 is the second call leg ID).

*Mar  1 15:35:53.624: //44/xxxxxxxxxxxx/CCAPI/ccCallReportDigits: (callID=0x2C,

enable=0x0)

The voice gateway informs the incoming call leg that digits were forwarded.

*Mar  1 15:35:53.624: //44/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done: 
(vdbPtr=0x637EC1E0, callID=0x2C, disp=0)

*Mar  1 15:35:53.624: //44/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(54=CC_EV_CALL_

REPORT_DIGITS_DONE), cid(44), disp(0)

*Mar  1 15:35:53.624: //44/45F2AAE28044/SS

Router#APP:10002:-1/ssaTraceSct: cid(44)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_RE

PORT_DIGITS_DONE)

oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(1)fDest(1)

*Mar  1 15:35:53.624: //44/45F2AAE28044/SSAPP:10002:-1/ssaTraceSct: -cid2(45)st2

(SSA_CS_CALL_SETTING)oldst2(SSA_CS_MAPPING)

*Mar  1 15:35:53.624: //44/45F2AAE28044/SSAPP:10002:-1/ssaDebugPeers: ssaReportDigitsDone 
cid(44) peer list: tag(20002) called number (34999)

*Mar  1 15:35:53.624: //44/45F2AAE28044/SSAPP:10002:-1/ssaReportDigitsDone: call

id=44 Reporting disabled.

*Mar  1 15:35:53.628: //-1/xxxxxxxxxxxx/CCAPI/cc_api_supported_data: data_mode=0x10082

*Mar  1 15:35:53.628: //45/xxxxxxxxxxxx/CCAPI/cc_api_get_ic_leg_obtained_numbers: 
callID=0x2D

The next two lines shows the IP address of the terminating gateway and that the terminating gateway is reached through Ethernet port 0/0.

*Mar  1 15:35:53.628: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: remote IP

is 171.69.85.111 

*Mar  1 15:35:53.632: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: hwidb is Ethernet0/0 

*Mar  1 15:35:53.632: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: create entry in 
list: 1

*Mar  1 15:35:53.636: //45/xxxxxxxxxxxx/CCAPI/ccTDUtilGetInstanceCount: For tagI

D[1] of callID[45]

*Mar  1 15:35:53.636: //45/45F2AAE28044/CCAPI/ccTDPvtProfileTableObjectAccessManager: No 
profileTable set for callID[45]

*Mar  1 15:35:53.636: //45/xxxxxxxxxxxx/CCAPI/ccTDUtilGetInstanceCount: For tagID[2] of 
callID[45]

*Mar  1 15:35:53.636: //45/45F2AAE28044/CCAPI/ccTDPvtProfileTableObjectAccessManager: No 
profileTable set for callID[45]

The next line shows that the voice gateway received a call proceeding message from the terminating gateway, and then the following line shows that the voice gateway received a call alert from the terminating gateway.

*Mar  1 15:35:53.740: //45/xxxxxxxxxxxx/CCAPI/cc_api_call_proceeding: (vdbPtr=0x62EC61A4, 
callID=0x2D,

      prog_ind=0x0) 

*Mar  1 15:35:53.740: //45/xxxxxxxxxxxx/CCAPI/cc_api_call_alert: (vdbPtr=0x62EC61A4, 
callID=0x2D, prog_ind=0x0, sig_ind=0x1) 

*Mar  1 15:35:53.744: //45/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: 
ev(21=CC_EV_CALL_PROCEEDING), cid(45), disp(0)

*Mar  1 15:35:53.744: //45/45F2AAE28044/SSAPP:0:-1/ssaTraceSct: cid(45)st(SSA_CS

_CALL_SETTING)ev(SSA_EV_CALL_PROCEEDING)

oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(0)fDest(0)

*Mar  1 15:35:53.744: //45/45F2AAE28044/SSAPP:0:-1/ssaTraceSct: 
-cid2(44)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_CALL_SETTING)

*Mar  1 15:35:53.744: //45/45F2AAE28044/SSAPP:0:-1/ssaCallProc:

*Mar  1 15:35:53.744: //44/xxxxxxxxxxxx/CCAPI/ccGetDialpeerTag: (callID=0x2C)

*Mar  1 15:35:53.744: //45/45F2AAE28044/SSAPP:0:-1/ssaIgnore: cid(45), st(SSA_CS

_CALL_SETTING),oldst(1), ev(21)

*Mar  1 15:35:53.744: //45/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(7=CC_EV_CALL_ALERT), 
cid(45), disp(0)

*Mar  1 15:35:53.744: //45/45F2AAE28044/SSAPP:0:-1/ssaTraceSct: cid(45)st(SSA_CS

_CALL_SETTING)ev(SSA_EV_CALL_ALERT)

oldst(SSA_CS_CALL_SETTING)cfid(-1)csize(0)in(0)fDest(0)

*Mar  1 15:35:53.744: //45/45F2AAE28044/SSAPP:0:-1/ssaTraceSct: -cid2(44)st2(SSA

_CS_CALL_SETTING)oldst2(SSA_CS_CALL_SETTING)

*Mar  1 15:35:53.744: //44/45F2AAE28044/SSAPP:10002:-1/ssaAlert:

*Mar  1 15:35:53.744: //44/xxxxxxxxxxxx/CCAPI/ccGetDialpeerTag: (callID=0x2C)

Router#

The voice gateway forwarded a call alert to the originating gateway.

*Mar  1 15:35:53.744: //44/xxxxxxxxxxxx/CCAPI/ccCallAlert: (callID=0x2C, prog_ind=0x0, 
sig_ind=0x1) 

Router#

The phone is answered at the called number.

Router#!call answered 

Router#

The voice gateway receives a connect message from the terminating gateway.

*Mar  1 15:36:05.016: //45/xxxxxxxxxxxx/CCAPI/cc_api_call_connected: (vdbPtr=0x6

2EC61A4, callID=0x2D), prog_ind = 0 

*Mar  1 15:36:05.016: //45/45F2AAE28044/CCAPI/cc_api_call_connected: setting 
callEntry->connected to TRUE

The next line shows that the call accounting starts. The leg_type=False message means this is for an outgoing call. The line that follows shows that AAA accounting is not configured.

*Mar  1 15:36:05.016: //45/45F2AAE28044/CCAPI/cc_api_call_connected: calling accounting 
start for callID=45 leg_type=0 

*Mar  1 15:36:05.020: //45/xxxxxxxxxxxx/CCAPI/ccCallSetAAA_Accounting: callID=0x

2D, accounting=0 

*Mar  1 15:36:05.020: //45/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(8=CC_EV_CALL_CONNECTED), 
cid(45), disp(0)

*Mar  1 15:36:05.020: //45/45F2AAE28044/SSAPP:0:-1/ssaTraceSct: cid(45)st(SSA_CS

_ALERT_RCVD)ev(SSA_EV_CALL_CONNECTED)

oldst(SSA_CS_CALL_SETTING)cfid(-1)csize(0)in(0)fDest(0)

*Mar  1 15:36:05.020: //45/45F2AAE28044/SSAPP:0:-1/ssaTraceSct: -cid2(44)st2(SSA

_CS_ALERT_RCVD)oldst2(SSA_CS_CALL_SETTING)

*Mar  1 15:36:05.020: //45/45F2AAE28044/SSAPP:0:-1/ssaConnect:

*Mar  1 15:36:05.020: //44/xxxxxxxxxxxx/CCAPI/ccGetDialpeerTag: (callID=0x2C)

The next lines show a conference being set up between the two call legs 0x2C and 0x2D. Bridge complete messages are sent to both the terminating and originating gateways.

*Mar  1 15:36:05.020: //44/xxxxxxxxxxxx/CCAPI/ccConferenceCreate: (confID=0x6308

6424, callID1=0x2C, callID2=0x2D, tag=0x0) 

*Mar  1 15:36:05.020: //45/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: (confID=0x15,

srcIF=0x62EC61A4, srcCallID=0x2D, dstCallID=0x2C, disposition=0, tag=0x0)

*Mar  1 15:36:05.024: //44/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: (confID=0x15,

srcIF=0x637EC1E0, srcCallID=0x2C, dstCallID=0x2D, disposition=0, tag=0x0)

Here, the voice gateway sets up negotiating capability with the originating telephony leg.

*Mar  1 15:36:05.024: //44/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind: (dstVdbPtr=0x62EC

61A4, dstCallId=0x2D, srcCallId=0x2C,

     caps={codec=0x2887F, fax_rate=0xBF, vad=0x3, modem=0x2

           codec_bytes=0, signal_type=3})

*Mar  1 15:36:05.024: //44/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind: (Playout: mode 0,

 initial 60,min 40, max 300) 

*Mar  1 15:36:05.024: //44/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(29=CC_EV_CONF_

CREATE_DONE), cid(44), disp(0)

*Mar  1 15:36:05.024: //44/45F2AAE28044/SSAPP:10002:21/ssaTraceSct: 
cid(44)st(SSA_CS_CONFERENCING)ev(SSA_EV_CONF_CREATE_DONE)

oldst(SSA_CS_CALL_SETTING)cfid(21)csize(2)in(1)fDest(1)

*Mar  1 15:36:05.024: //44/45F2AAE28044/SSAPP:10002:21/ssaTraceSct: -cid2(45)st2

(SSA_CS_CONFERENCING)oldst2(SSA_CS_ALERT_RCVD)

*Mar  1 15:36:05.024: //44/45F2AAE28044/SSAPP:10002:21/ssaConfCreateDone:

*Mar  1 15:36:05.024: //44/xxxxxxxxxxxx/CCAPI/ccCallConnect: (callID=0x2C), prog

_ind = 0

*Mar  1 15:36:05.024: //44/45F2AAE28044/CCAPI/ccCallConnect: setting callEntry->

connected to TRUE

*Mar  1 15:36:05.024: //44/45F2AAE28044/SSAPP:10002:21/ssaDebugPeers: ssaFlushPeerTagQueue 
cid(44) peer list: tag(20002) called number (34999)

*Mar  1 15:36:05.028: //-1/xxxxxxxxxxxx/CCAPI/cc_process_notify_bridge_done: 
(event=0x63067FC0)

The voice gateway sets up negotiating capability with the terminating VoIP leg.

*Mar  1 15:36:05.028: //45/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind: (dstVdbPtr=0x637E

C1E0, dstCallId=0x2C, srcCallId=0x2D,

     caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x0

           codec_bytes=20, signal_type=2})

*Mar  1 15:36:05.028: //45/xxxxxxxxxxxx/CCAPI/cc_api_caps_ind: (Playout: mode 0,

 initial 60,min 40, max 300) 

The capabilities are acknowledged for both call legs.

*Mar  1 15:36:05.028: //45/xxxxxxxxxxxx/CCAPI/cc_api_caps_ack: (dstVdbPtr=0x637E

C1E0, dstCallId=0x2C, srcCallId=0x2D,

     caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x0

           codec_bytes=20, signal_type=2, seq_num_start=2944})

*Mar  1 15:36:05.028: //44/xxxxxxxxxxxx/CCAPI/cc_api_caps_ack: (dstVdbPtr=0x62EC

61A4, dstCallId=0x2D, srcCallId=0x2C,

     caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x0

           codec_bytes=20, signal_type=2, seq_num_start=2944})

*Mar  1 15:36:05.032: //44/xxxxxxxxxxxx/CCAPI/cc_api_voice_mode_event: callID=0x2C

*Mar  1 15:36:05.032: //44/45F2AAE28044/CCAPI/cc_api_voice_mode_event: Call Pointer 
=634A430C

*Mar  1 15:36:05.032: //44/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(52=CC_EV_VOICE

_MODE_DONE), cid(44), disp(0)

*Mar  1 15:36:05.032: //44/45F2AAE28044/SSAPP:10002:21/ssaTraceSct:

Router#

Router# cid(44)st(SSA_CS_ACTIVE)ev(SSA_EV_VOICE_MODE_DONE)

oldst(SSA_CS_CONFERENCING)cfid(21)csize(2)in(1)fDest(1)

*Mar  1 15:36:05.032: //44/45F2AAE28044/SSAPP:10002:21/ssaTraceSct: -cid2(45)st2

(SSA_CS_ACTIVE)oldst2(SSA_CS_ALERT_RCVD)

*Mar  1 15:36:05.032: //44/45F2AAE28044/SSAPP:10002:21/ssaIgnore: cid(44), st(SS

A_CS_ACTIVE),oldst(5), ev(52)

Router#

Router#! digit punched

Router#

The phone at the terminating gateway enters digit 1.

*Mar  1 15:36:11.204: //45/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin: 
(dstVdbPtr=0x637EC1E0, dstCallId=0x2C, srcCallId=0x2D,

    digit=1, digit_begin_flags=0x0, rtp_timestamp=0x0

    rtp_expiration=0x0, dest_mask=0x2)

*Mar  1 15:36:11.504: //45/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end: (dstVdbPtr=

0x637EC1E0, dstCallId=0x2C, srcCallId=0x2D,

    digit=1,duration=300,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x2), 
digit_tone_mode=0

The phone at the terminating gateway enters digit 2.

*Mar  1 15:36:11.604: //45/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin: (dstVdbPt

r=0x637EC1E0, dstCallId=0x2C, srcCallId=0x2D,

    digit=2, digit_begin_flags=0x0, rtp_timestamp=0x0

    rtp_expiration=0x0, dest_mask=0x2)

*Mar  1 15:36:11.904: //45/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end: (dstVdbPtr=

0x637EC1E0, dstCallId=0x2C, srcCallId=0x2D,

    digit=2,duration=300,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x2), 
digit_tone_mode=0

Router#

Router#

*Mar  1 15:36:14.476: //-1/xxxxxxxxxxxx/CCAPI/cc_handle_periodic_timer: Calling

the callback, ccTimerctx - 0x628B6330

*Mar  1 15:36:14.476: //-1/xxxxxxxxxxxx/CCAPI/ccTimerStart: ccTimerctx - 0x628B6330

Router#

Router# !call hung up  The user at the terminating gateway hangs up the call.

Router#

The voice gateway receives a disconnect message from the terminating gateway. The cause code is 0x10 which is normal call clearing.

*Mar  1 15:36:22.916: //45/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnected: (vdbPtr=

0x62EC61A4, callID=0x2D, cause=0x10)

*Mar  1 15:36:22.920: //45/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(11=CC_EV_CALL_

DISCONNECTED), cid(45), disp(0)

*Mar  1 15:36:22.920: //45/45F2AAE28044/SSAPP:0:21/ssaTraceSct: cid(45)st(SSA_CS

_ACTIVE)ev(SSA_EV_CALL_DISCONNECTED)

oldst(SSA_CS_ALERT_RCVD)cfid(21)csize(2)in(0)fDest(0)

*Mar  1 15:36:22.920: //45/45F2AAE28044/SSAPP:0:21/ssaTraceSct: -cid2(44)st2(SSA

_CS_ACTIVE)oldst2(SSA_CS_ACTIVE)

*Mar  1 15:36:22.920: ssa: Disconnected cid(45) state(5) cause(0x10)

The voice gateway begins tearing down the conference and dropping the bridge.

*Mar  1 15:36:22.920: //-1/xxxxxxxxxxxx/CCAPI/ccConferenceDestroy: (confID=0x15,

 tag=0x0) 

*Mar  1 15:36:22.920: //45/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: (confID=0

x15, srcIF=0x62EC61A4, srcCallID=0x2D, dstCallID=0x2C, disposition=0 tag=0x0)

*Mar  1 15:36:22.920: //44/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: (confID=0

x15, srcIF=0x637EC1E0, srcCallID=0x2C, dstCallID=0x2D, disposition=0 tag=0x0)

*Mar  1 15:36:22.924: //44/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(30=CC_EV_CONF_

DESTROY_DONE), cid(44), disp(0)

*Mar  1 15:36:22.924: //44/45F2AAE28044/SSAPP:10002:21/ssaTraceSct: 
cid(44)st(SSA_CS_CONF_DESTROYING)ev(SSA_EV_CONF_DESTROY_DONE)

oldst(SSA_CS_ACTIVE)cfid(21)csize(2)in(1)fDest(1)

*Mar  1 15:36:22.924: //44/45F2AAE28044/SSAPP:10002:21/ssaTraceSct: -cid2(45)st2

(SSA_CS_CONF_DESTROYING)oldst2(SSA_CS_ACTIVE)

*Mar  1 15:36:22.924: //45/45F2AAE28044/SSAPP:0:-1/ssaConfDestroyDone:

*Mar  1 15:36:22.924: //44/xxxxxxxxxxxx/CCAPI/ccCallDisconnect: (callID=0x2C, cause=0x10 
tag=0x0)

The voice gateway stops call accounting on the incoming call, indicated by the leg_type=True message. The cause code is then set for the originating leg.

*Mar  1 15:36:22.924: //44/45F2AAE28044/CCAPI/ccCallDisconnect: calling accounting start 
for callID=44 leg_type=1 

*Mar  1 15:36:22.924: //44/45F2AAE28044/CCAPI/ccCallDisconnect: existing_cause =

 0x0, new_cause = 0x10 

*Mar  1 15:36:22.924: //44/xxxxxxxxxxxx/CCAPI/cc_api_get_transfer_info: (callID=0x2C)

*Mar  1 15:36:22.924: //45/xxxxxxxxxxxx/CCAPI/ccCallDisconnect: (callID=0x2D, cause=0x10 
tag=0x0)

The voice gateway stops call accounting for the outgoing call, indicated by the leg_type=False message. The cause code is verified for the terminating leg.

*Mar  1 15:36:22.924: //45/45F2AAE28044/CCAPI/ccCallDisconnect: calling accounting start 
for callID=45 leg_type=0

*Mar  1 15:36:22.924: //45/45F2AAE28044/CCAPI/ccCallDisconnect: existing_cause =

 0x10, new_cause = 0x10 

*Mar  1 15:36:22.924: //45/45F2AAE28044/CCAPI/ccCallDisconnect: using the existing_cause 
0x10

*Mar  1 15:36:22.928: //45/xxxxxxxxxxxx/CCAPI/cc_api_get_transfer_info: (callID=0x2D)

*Mar  1 15:36:22.932: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif: expect factor = 0

*Mar  1 15:36:22.932: //-1/xxxxxxxxxxxx/CCAPI/g113_calculate_impairment: (delay=79,

      loss=0), Io=0 Iq=0 Idte=0 Idd=0 Ie=10 Itot=10

*Mar  1 15:36:22.932: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: the remote

 IP is 171.69.85.111

*Mar  1 15:36:22.932: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: hwidb is Ethernet0/0

*Mar  1 15:36:22.932: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: reduce callnum of 
entry: 0, voip: 0, mmoip: 0

*Mar  1 15:36:22.932: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: remove an entry

*Mar  1 15:36:22.932: //45/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done: 
(vdbPtr=0x62EC61A4, callID=0x2D, disp=0, tag=0x0)

*Mar  1 15:36:22.932: //45/45F2AAE28044/CCAPI/ccTDPvtProfileTableObjectAccessManager: No 
profileTable set for callID[45]

*Mar  1 15:36:22.936: //45/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject

found in profileTable for tagID[6] of callID[45]

*Mar  1 15:36:22.936: //45/45F2AAE28044/CCAPI/cc_delete_call_entry: not incoming

 entry

*Mar  1 15:36:22.936: //45/45F2AAE28044/CCAPI/cc_delete_call_entry: entry's incoming 
FALSE.

*Mar  1 15:36:22.936: //45/45F2AAE28044/CCAPI/cc_delete_call_entry: is_incoming

is FALSE

*Mar  1 15:36:22.940: //45/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_

DISCONNECT_DONE), cid(45), disp(0)

*Mar  1 15:36:22.940: //45/45F2AAE28044/SSAPP:0:-1/ssaTraceSct: cid(45)st(SSA_CS

_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE)

oldst(SSA_CS_ACTIVE)cfid(-1)csize(2)in(0)fDest(0)

*Mar  1 15:36:22.940: //45/45F2AAE28044/SSAPP:0:-1/ssaTraceSct: -cid2(44)st2(SSA

_CS_DISCONNECTING)oldst2(SSA_CS_CONF_DESTROYING)

*Mar  1 15:36:22.940: //45/45F2AAE28044/SSAPP:0:-1/ssaDisconnectDone:

*Mar  1 15:36:22.940: //45/45F2AAE28044/SSAPP:0:-1/ssaAAA_CheckAccounting: accounting 
generation enabled

*Mar  1 15:36:22.940: //45/xxxxxxxxxxxx/CCAPI/ccCallSetAAA_Accounting: callID=0x2D, 
accounting=0

*Mar  1 15:36:22.944: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: not the VoIP or 
MMoIP

*Mar  1 15:36:22.948: //44/xxxxxxxxxxxx/CCAPI/cc_api_call_disconnect_done: 
(vdbPtr=0x637EC1E0, callID=0x2C, disp=0, tag=0x0)

*Mar  1 15:36:22.948: //44/45F2AAE28044/CCAPI/cc_delete_call_entry: ccFreeRawMsg

Info(0x6307595C)

*Mar  1 15:36:22.948: //44/45F2AAE28044/CCAPI/cc_delete_call_entry: Decrement call volume 
counter 1

*Mar  1 15:36:22.948: //44/45F2AAE28044/CCAPI/cc_delete_call_entry: current call volume: 0

*Mar  1 15:36:22.948: //44/45F2AAE28044/CCAPI/cc_delete_call_entry: entry's incoming TRUE.

*Mar  1 15:36:22.948: //44/45F2AAE28044/CCAPI/cc_delete_call_entry: is_incoming

is TRUE

*Mar  1 15:36:22.948: //44/45F2AAE28044/CCAPI/cc_delete_call_entry: Deleting 
profileTable[0x6380E11C]

*Mar  1 15:36:22.948: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor 
Profile Table (0x6380E11C)

*Mar  1 15:36:22.948: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: 
tdObject[0x63401148] tagID[5]

*Mar  1 15:36:22.948: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: 
tdObject[0x638BC1AC] tagID[6]

*Mar  1 15:36:22.956: //44/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_

DISCONNECT_DONE), cid(44), disp(0)

*Mar  1 15:36:22.956: //44/45F2AAE28044/SSAPP:10002:-1/ssaTraceSct: 
cid(44)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE)

oldst(SSA_CS_CONF_DESTROYING)cfid(-1)csize(1)in(1)fDest(1)

Router#

*Mar  1 15:36:22.956: //44/45F2AAE28044/SSAPP:10002:-1/ssaDisconnectDone:

Related Commands

Command	Description
debug voip ccapi error	Traces error logs in the CCAPI.
voice call debug	Allows configuration of the voice call debug output.

debug voip confmsp

To display debugging information from the Conference Media Service Provider (CONFMSP) and its related applications, use the debug voip confmsp command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip confmsp

no debug voip confmsp

Syntax Description

This command has no arguments or keywords.

Command Modes

Privileged EXEC (#)

Command History

Release	Modification
12.3(8)T	This command was introduced.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Examples

The following is sample output from the debug voip confmsp command:

Router# debug voip confmsp 

CONFMSP debugging is on

.

.

.

00:06:44:confmsp_setup_request:callID (6),  

00:06:44:confmsp_setup_request:conf structure  (63DD27E4) created,  

00:06:44:confmsp_bridge:confID(4), callIDs(6,5) xmitFunc 61D46D4C, dstIF 64912880

00:06:44:confmsp_bridge:confID(4), callIDs(6,5) event queued 

00:06:44:confmsp_act_bridge: state = CONFMSP_STATE_SETUP, event=EV_CONFMSP_BRIDGING 

00:06:44:confmsp_act_bridge:codec 1, codec_bytes 160, vad 1

00:06:44:confmsp_act_bridge:codec 1, codec_bytes 160, vad 1

00:06:44:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_SETUP,

event:EV_CONFMSP_BRIDGING, next_state:CONFMSP_STATE_CONNECT_CONFEREE

00:06:44:confmsp_get_dsmp_req_status:condition to be returned FALSE

00:06:44:confmsp_connect_response:found conf (63DD27E4) dsmp ret is 1

00:06:44:confmsp_act_bridge_success: state = CONFMSP_STATE_CONNECT_CONFEREE,

event=EV_CONFMSP_CNFRE_CONNECT_RESP_SUCCESS confmsp_caps_ind:context = 65241B34

00:06:44:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_CONNECT_CONFEREE,

event:EV_CONFMSP_CNFRE_CONNECT_RESP_SUCCESS, next_state:CONFMSP_STATE_CONNECTED

00:06:44:confmsp_bridge:confID(5), callIDs(6,7) xmitFunc 61D46D4C, dstIF 64912880

00:06:44:confmsp_bridge:confID(5), callIDs(6,7) event queued 

00:06:44:confmsp_act_bridge: state = CONFMSP_STATE_SETUP, event=EV_CONFMSP_BRIDGING 

00:06:44:confmsp_act_bridge:codec 1, codec_bytes 160, vad 1

00:06:44:confmsp_act_bridge:codec 1, codec_bytes 160, vad 1

00:06:44:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_SETUP,

event:EV_CONFMSP_BRIDGING, next_state:CONFMSP_STATE_CONNECT_CONFEREE

00:06:44:confmsp_get_dsmp_req_status:condition to be returned FALSE

00:06:44:confmsp_connect_response:found conf (6358A338) dsmp ret is 1

00:06:44:confmsp_act_bridge_success: state = CONFMSP_STATE_CONNECT_CONFEREE,

event=EV_CONFMSP_CNFRE_CONNECT_RESP_SUCCESS confmsp_caps_ind:context = 63588E70

00:06:44:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_CONNECT_CONFEREE,

event:EV_CONFMSP_CNFRE_CONNECT_RESP_SUCCESS, next_state:CONFMSP_STATE_CONNECTED

00:06:44:confmsp_bridge:confID(6), callIDs(6,8) xmitFunc 61D46D4C, dstIF 64912880

00:06:44:confmsp_bridge:confID(6), callIDs(6,8) event queued 

00:06:44:confmsp_act_bridge: state = CONFMSP_STATE_SETUP, event=EV_CONFMSP_BRIDGING 

00:06:44:confmsp_act_bridge:codec 1, codec_bytes 160, vad 1

00:06:44:confmsp_act_bridge:codec 1, codec_bytes 160, vad 1

00:06:44:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_SETUP,

event:EV_CONFMSP_BRIDGING, next_state:CONFMSP_STATE_CONNECT_CONFEREE

00:06:44:confmsp_get_dsmp_req_status:condition to be returned FALSE

00:06:44:confmsp_connect_response:found conf (6358CE50) dsmp ret is 1

00:06:44:confmsp_act_bridge_success: state = CONFMSP_STATE_CONNECT_CONFEREE,

event=EV_CONFMSP_CNFRE_CONNECT_RESP_SUCCESS confmsp_caps_ind:context = 63DD2524

00:06:44:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_CONNECT_CONFEREE,

event:EV_CONFMSP_CNFRE_CONNECT_RESP_SUCCESS, next_state:CONFMSP_STATE_CONNECTED

00:07:28:confmsp_bdrop:confID(4), callIDs(6,5) 

00:07:28:confmsp_bdrop:confID(4), callIDs(6,5) event queued 

00:07:28:confmsp_act_bdrop: state = CONFMSP_STATE_CONNECTED, event=EV_CONFMSP_BRIDGEDROP 

00:07:28:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_CONNECTED,

event:EV_CONFMSP_BRIDGEDROP, next_state:CONFMSP_STATE_DISCONNECT_CONFEREE

00:07:28:confmsp_get_dsmp_req_status:condition to be returned FALSE

00:07:28:confmsp_disconnect_response:found conf (63DD27E4)

00:07:28:confmsp_connect_response:found conf (63DD27E4) dsmp ret is 10

00:07:28:confmsp_act_bdrop_success: state = CONFMSP_STATE_DISCONNECT_CONFEREE,

event=EV_CONFMSP_CNFRE_DISCONNECT_RESP_SUCCESS 

00:07:28:CNFSM:cur_container:confmsp container,

cur_state:CONFMSP_STATE_DISCONNECT_CONFEREE,

event:EV_CONFMSP_CNFRE_DISCONNECT_RESP_SUCCESS, next_state:CONFMSP_STATE_BRIDGE_DROPPED

00:07:28:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_BRIDGE_DROPPED,

event:CNFSM_LAMBDA_EVENT, next_state:CNFSM_NO_STATE_CHANGE

00:07:29:confmsp_bdrop:confID(6), callIDs(6,8) 

00:07:29:confmsp_bdrop:confID(6), callIDs(6,8) event queued 

00:07:29:confmsp_bdrop:confID(5), callIDs(6,7) 

00:07:29:confmsp_bdrop:confID(5), callIDs(6,7) event queued 

00:07:29:confmsp_act_bdrop: state = CONFMSP_STATE_CONNECTED, event=EV_CONFMSP_BRIDGEDROP 

00:07:29:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_CONNECTED,

event:EV_CONFMSP_BRIDGEDROP, next_state:CONFMSP_STATE_DISCONNECT_CONFEREE

00:07:29:confmsp_get_dsmp_req_status:condition to be returned FALSE

00:07:29:confmsp_act_bdrop: state = CONFMSP_STATE_CONNECTED, event=EV_CONFMSP_BRIDGEDROP 

00:07:29:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_CONNECTED,

event:EV_CONFMSP_BRIDGEDROP, next_state:CONFMSP_STATE_DISCONNECT_CONFEREE

00:07:29:confmsp_get_dsmp_req_status:condition to be returned FALSE

00:07:29:confmsp_disconnect_response:found conf (6358CE50)

00:07:29:confmsp_connect_response:found conf (6358CE50) dsmp ret is 10

00:07:29:confmsp_act_bdrop_success: state = CONFMSP_STATE_DISCONNECT_CONFEREE,

event=EV_CONFMSP_CNFRE_DISCONNECT_RESP_SUCCESS 

00:07:29:CNFSM:cur_container:confmsp container,

cur_state:CONFMSP_STATE_DISCONNECT_CONFEREE,

event:EV_CONFMSP_CNFRE_DISCONNECT_RESP_SUCCESS, next_state:CONFMSP_STATE_BRIDGE_DROPPED

00:07:29:confmsp_act_terminate: state = CONFMSP_STATE_BRIDGE_DROPPED,

event=CNFSM_LAMBDA_EVENT 

00:07:29:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_BRIDGE_DROPPED,

event:CNFSM_LAMBDA_EVENT, next_state:CNFSM_NULL_STATE

00:07:29:confmsp_free_conf:freeing 6358CE50

00:07:29:confmsp_disconnect_response:found conf (6358A338)

00:07:29:confmsp_connect_response:found conf (6358A338) dsmp ret is 10

00:07:29:confmsp_act_bdrop_success: state = CONFMSP_STATE_DISCONNECT_CONFEREE,

event=EV_CONFMSP_CNFRE_DISCONNECT_RESP_SUCCESS 

00:07:29:CNFSM:cur_container:confmsp container,

cur_state:CONFMSP_STATE_DISCONNECT_CONFEREE,

event:EV_CONFMSP_CNFRE_DISCONNECT_RESP_SUCCESS, next_state:CONFMSP_STATE_BRIDGE_DROPPED

00:07:29:confmsp_act_terminate: state = CONFMSP_STATE_BRIDGE_DROPPED,

event=CNFSM_LAMBDA_EVENT 

00:07:29:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_BRIDGE_DROPPED,

event:CNFSM_LAMBDA_EVENT, next_state:CNFSM_NULL_STATE

00:07:29:confmsp_free_conf:freeing 6358A338

00:07:29:confmsp_disconnect:callID (6) 

00:07:29:confmsp_disconnect:callID (6) event queued 

00:07:29:confmsp_act_disconnected: state = CONFMSP_STATE_BRIDGE_DROPPED,

event=EV_CONFMSP_DISCONNECT 

00:07:29:CNFSM:cur_container:confmsp container, cur_state:CONFMSP_STATE_BRIDGE_DROPPED,

event:EV_CONFMSP_DISCONNECT, next_state:CNFSM_NULL_STATE

00:07:29:confmsp_free_conf:freeing 63DD27E4

debug voip dcapi

To debug the device control application programming interface (DCAPI), use the debug voip dcapi command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip dcapi [error | inout]

no debug voip dcapi [error | inout]

Syntax Description

error	(Optional) Displays error logs in the DCAPI.
inout	(Optional) Displays the execution path through the DCAPI.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.3(14)T	This command was introduced.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

The debug voip dcapi error command traces the error logs in the DCAPI, which is the software layer that interfaces the SCCP Telephony Control Application (STCAPP) with the Cisco CallManager using the Skinny Client Control Protocol (SCCP). Error logs are generated during normal call processing when there are insufficient resources, or when there are problems in the device control API. This debug command shows error events or unexpected behavior in system software.

The debug voip dcapi inout command shows how a call executes through the software. This command traces the execution path through the DCAPI during communications with the SCCP service provider interface (SPI) and the call control API (CCAPI) that controls the physical voice port. You can use the output from this command to understand how devices are being handled by the APIs and to see the call setup and teardown operations performed on the telephony call leg.

Examples

Following is sample output from the debug voip dcapi inout command during call setup:

Router# debug voip dcapi inout

*Jan 27 16:26:23.957: dc_api_device_set_ringer_res: Set Ringer message success

*Jan 27 16:26:23.957: //-1/xxxxxxxxxxxx/CCAPI/dc_api_device_stop_tone_res:

*Jan 27 16:26:23.957: dc_api_device_stop_tone_res: Stop Tone message success

*Jan 27 16:26:23.957: //-1/xxxxxxxxxxxx/CCAPI/dc_api_media_open_rcv_channel:

*Jan 27 16:26:23.957: dc_api_media_open_rcv_channel: evt DC_EV_MEDIA_OPEN_RCV_CHNL is 
successsfully enqueued to app

*Jan 27 16:26:23.957: //-1/xxxxxxxxxxxx/CCAPI/dc_api_device_stop_tone_res:

*Jan 27 16:26:23.957: dc_api_device_stop_tone_res: Stop Tone message success

*Jan 27 16:26:23.957: //-1/xxxxxxxxxxxx/CCAPI/dc_api_device_call_state_res:

*Jan 27 16:26:23.957: dc_api_device_call_state_res: Call State message success

*Jan 27 16:26:23.957: //-1/xxxxxxxxxxxx/CCAPI/dc_api_device_call_info_res:

Table 373 describes the significant fields shown in the display.

Table 373 debug voip dcapi Field Descriptions

Field	Description
nn:nn:nn:	Timestamp time in hours (military format), minutes, and seconds that indicates when the DCAPI event occurred.
dc_api_message:	The DCAPI event in which the SCCP SPI translation occurred.

Related Commands

Command	Description
debug voip application stcapp events	Debugs STCAPP events.
debug voip application stcapp functions	Debugs STCAPP functions.

debug voip dialpeer

To display information about the voice dial peers, use the debug voip dialpeer command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip dialpeer [all | default | detail | error [call [informational] | software [informational]] | | function | inout]

no debug voip dialpeer

Syntax Description

all	(Optional) Displays all dialpeer debugging messages.
default	(Optional) Displays dialpeer inout and error debugging messages. This option also runs if no keywords are added.
detail	(Optional) Displays detailed dialpeer information.
error	(Optional) Displays dialpeer error messages.
call	(Optional) Displays call processing errors.
informational	(Optional) Displays minor errors and major errors. Without the informational keyword, only major errors are displayed.
software	(Optional) Displays software processing errors.
function	(Optional) Displays dialpeer functions.
inout	(Optional) Displays dialpeer in/out functions.

Defaults

Debugging is not enabled.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.3(8)T	This command replaces the debug dialpeer command.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

Disable console logging and use buffered logging before using the debug voip dialpeer command. Using the debug voip dialpeer command generates a large volume of debugging messages, which can affect router performance.

Examples

The following examples show output for variations of the debug voip dialpeer command:

•debug voip dialpeer detail on the Originating Gateway

•debug voip dialpeer inout on the Terminating Gateway

For these examples, the topology shown in Figure 2 is used.

Figure 2 Network Topology for debug voip dialpeer Output Examples

debug voip dialpeer detail on the Originating Gateway

Router# debug voip dialpeer detail 

voip dialpeer detail debugging is on

Router#

The following event identifies the called number:

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=83103

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Dial String=83103, Expanded String=83103, Calling Number=

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

The following event identifies the incoming dial peer and shows that it has been matched:

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

   Result=Success(0); Incoming Dial-peer=1 Is Matched

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=83103

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Dial String=83103, Expanded String=83103, Calling Number=

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_FAX

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Result=-1

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_ANSWER; Calling Number=4085550111

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Dial String=, Expanded String=, Calling Number=4085550111T

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_FAX

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Result=-1

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_ORIGINATE; Calling Number=4085550111

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Apr 18 21:07:35.291: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Dial String=, Expanded String=, Calling Number=4085550111T

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_FAX

*Apr 18 21:07:35.295: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Result=-1

The following event shows the number expansion. This is the only dial peer debug command that shows the number expansion.

*Apr 18 21:07:35.295: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Dial String=83103, Expanded String=3600, Calling Number=

   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

The next few lines show matching for the outgoing dial peer. These lines show the matching sequence if the first match is not available.

*Apr 18 21:07:35.295: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=3600 Is Matched

*Apr 18 21:07:35.295: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=36 Is Matched

*Apr 18 21:07:35.295: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=360 Is Matched

*Apr 18 21:07:35.295: //-1/23ED4B1B8010/DPM/dpMatchCore:

   Dial String=83103, Expanded String=3600, Calling Number=

   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

debug voip dialpeer inout on the Terminating Gateway

Router# debug voip dialpeer inout 

voip dialpeer inout debugging is on

The following event shows the calling and called numbers:

*May  1 19:32:11.731: //-1/6372E2598012/DPM/dpAssociateIncomingPeerCore:

   Calling Number=4085550111, Called Number=3600, Voice-Interface=0x0,

   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

   Peer Info Type=DIALPEER_INFO_SPEECH

The following event shows the incoming dial peer:

*May  1 19:32:11.731: //-1/6372E2598012/DPM/dpAssociateIncomingPeerCore:

   Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=100

*May  1 19:32:11.731: //-1/6372E2598012/DPM/dpAssociateIncomingPeerCore:

   Calling Number=4085550111, Called Number=3600, Voice-Interface=0x0,

   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

   Peer Info Type=DIALPEER_INFO_SPEECH

*May  1 19:32:11.731: //-1/6372E2598012/DPM/dpAssociateIncomingPeerCore:

   Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=100

*May  1 19:32:11.735: //-1/6372E2598012/DPM/dpMatchPeersCore:

   Calling Number=, Called Number=3600, Peer Info Type=DIALPEER_INFO_SPEECH

*May  1 19:32:11.735: //-1/6372E2598012/DPM/dpMatchPeersCore:

   Match Rule=DP_MATCH_DEST; Called Number=3600

*May  1 19:32:11.735: //-1/6372E2598012/DPM/dpMatchPeersCore:

   Result=Success(0) after DP_MATCH_DEST

*May  1 19:32:11.735: //-1/6372E2598012/DPM/dpMatchPeersMoreArg:

   Result=SUCCESS(0)

The following event shows the matched dial peers in the order of priority:

   List of Matched Outgoing Dial-peer(s):

     1: Dial-peer Tag=3600

     2: Dial-peer Tag=36

Related Commands

Command	Description
call-block (dial peer)	Enables blocking of incoming calls on the dial peer.
carrier-id (dial-peer)	Identifies the carrier handling the incoming call.
session target (ENUM)	Specifies the ENUM search table for the target session.
show dial-peer voice	Displays the configuration of the dial peer.
translation-profile (dial-peer)	Assigns a translation profile to the dial peer.
trunkgroup (dial-peer)	Assigns a trunk group to the dial peer.
trunk-group-label (dial-peer)	Identifies the trunk group handling the incoming call.

debug voip dsm

To troubleshoot the DSP stream manager (DSM) subsystem, use the debug voip dsm command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip dsm [all | dsp | error | rtp | session | stats | tone | vofr]

no debug voip dsm

Syntax Description

all	(Optional) Displays all DSM debugging messages.
dsp	(Optional) Enables a digital signal processor (DSP) message trace.
error	(Optional) Displays DSM error messages.
rtp	(Optional) Enables Real-Time Protocol (RTP) debugging on DSM.
session	(Optional) Enables session debugging.
stats	(Optional) Displays DSM statistics.
tone	(Optional) Displays tone debugging.
vofr	(Optional) Enables Voice over Frame Relay (VoFR) debugging on the VPM.

Defaults

Debugging is not enabled.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.3(8)T	This command replaces the debug vtsp dsp command.
12.3(14)T	The vofr keyword is no longer available in Cisco IOS Release 12.3(14)T.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

To debug VoIP calls, use this command in conjunction with debug voip vtsp command and debug voip dsmp commands. All the related information for media processing is now available by using Distributed Stream Media Processor (DSMP). DSM is responsible for creating streams and issuing connections between them.

Examples

The following examples show output for variations of the debug voip dsm command:

•debug voip dsm session on the Originating Gateway

•debug voip dsm stats on the Terminating Gateway

•debug voip dsm Session of a VoIP Call in Transition to Connected State and in Transition from Connected to Disconnected State

For these examples, the topology shown in Figure 3 is used.

Figure 3 Network Topology for debug voip dsm Examples

debug voip dsm session on the Originating Gateway

Router# debug voip dsm session 

DSP Stream Manager session debugging is on

*Apr 18 21:15:39.679: //-1/44A507668015/DSM:(2/1:23):0:0:0/dsm_start_basic_sm: .

*Apr 18 21:15:39.679: //-1/44A507668015/DSM:(2/1:23):0:8:4/dsm_start_basic_sm: dsp 
resource manager opened. ret 4

*Apr 18 21:15:39.679: //-1/44A507668015/DSM:(2/1:23):0:8:4/dsm_open_voice_and_set_params: 
.

*Apr 18 21:15:39.679: //-1/44A507668015/DSM:(2/1:23):0:8:4/set_playout_dmgr: playout 
default

The following event shows the echo cancellation setting:

*Apr 18 21:15:39.679: //-1/44A507668015/DSM:(2/1:23):0:8:4/dsm_dsp_echo_canceller_control: 
echo_cancel: 1

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsp_stream_mgr_play_tone: .

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_INIT E:E_DSM_CC_GEN_TONE]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_gen_tone: Tone is not 
on, ignoring

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [B SM: R:FSM_OK -> 
S:S_DSM_INIT]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_INIT E:E_DSM_CC_BRIDGE]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_bridge: .

The following event indicates that modem relay is not supported on the specified port, which is port 2/1:23:

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_bridge: Modem Relay NOT 
Supported on this end-point/voice-port.

disabling it...

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [B SM: R:FSM_OK -> 
S:S_DSM_BRIDGING]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_BRIDGING E:E_DSM_CC_CAPS_IND]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ind: .

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ind: RTP 
PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDT

121],FaxRelay[122],CASsig[123],ClearChan[125],PCMu[0],PCMa[8]Codec[4], 
TxDynamicPayload[0], RxDynamicPayload[0]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ind: dtmf relay: 
mode=1, codec=1

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ind: Modem Relay 
NOT Supported on this end-point/voice-port

disabling it...

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ind: passthrough: 
cap_modem_proto 4,cap_modem_codec 1, cap_

em_redundancy 0, payload 103, modem_relay 0, gw-xid 0

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_debug_caps_encap: Encap 1, 
Vad 2, Codec 0x4, CodecBytes 20,

             FaxRate 1, FaxBytes 20, FaxNsf 0xAD0051

             SignalType 2

             DtmfRelay 1, Modem 2, SeqNumStart 0x5A1

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ind: FORKING 
Parameters are forking mask: 0, simple_forking

dec_mask: 0, complex_forking_codec_mask 0

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ind: playout: [ 
mode:1,init:60, min:40, max:200]. data_mode

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [B SM: R:FSM_OK -> 
S:S_DSM_BRIDGING]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_BRIDGING E:E_DSM_CC_CAPS_ACK]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ack: .

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ack: passthrough: 
cap_modem_proto 4, cap_modem_codec 1, cap

dem_redundancy 0, payload 103, modem_relay 0, gw-xid 0

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_caps_ack: Named 
Telephone Event payload rcv 101, tx 101

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_switch_codec: Required 
codec is 16,  current dsp codec is -1

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_switch_codec: codec = 16

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [B SM: R:FSM_OK -> 
S:S_DSM_PENDING_CODEC_SWITCH]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_PENDING_CODEC_SWITCH E:E_DSM_DS_PEND_SUCCESS]

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_act_pend_codec_success: .

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_open_voice_and_set_params: 

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/set_playout_dmgr: playout 
default

The following event shows the echo cancellation setting:

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_dsp_echo_canceller_control: 
echo_cancel: 1

The following event shows that the codec has changed:

*Apr 18 21:15:39.695: 
//43/44A507668015/DSM:(2/1:23):0:8:4/dsm_setup_stream_after_switch_codec_succ: codec 
change success

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_add_fork: .

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_update_fork_info: 
add_fork=0

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_get_xmit_info_node: .

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_update_fork_info:  xmit 
func is 61A7CDC4, context is 64F42DA0 peer_c

_id: 44, stream_count: 1, update_flag 0

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_update_fork_info: The 
stream bit-mask is 1

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_update_fork_info: The 
stream type is 0

*Apr 18 21:15:39.695: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_update_fork_info: The 
logical ssrc is 64 for stream 0

*Apr 18 21:15:39.699: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_update_stream_count:

*Apr 18 21:15:39.699:  g711_voice_count=0 g711_avt_count = 0

 g711_voice_avt_count = 0 complex_voice_count = 1

 complex_avt_count = 0 complex_voice_avt_count = 0

*Apr 18 21:15:39.699: //43/44A507668015/DSM:(2/1:23):0:8:4/dsm_exec: [B SM: R:FSM_OK -> 
S:S_DSM_BRIDGED]

debug voip dsm stats on the Terminating Gateway

Router# debug voip dsm stats 

DSP Stream Manager stats debugging is on

The following event shows that the DSM is requesting statistics:

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_BRIDGED E:E_DSM_CC_REQ_PACK_STAT]

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_cc_stats_req: .

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_cc_stats_req:  
-1->dmgr=0x645461E0, stats_reqs=0

The following events show statistics for DSM transmission, reception, delay, and errors:

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_exec: [B SM: R:FSM_OK -> 
S:S_DSM_BRIDGED]

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_BRIDGED E:E_DSM_DSP_GET_TX]

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_packet_stats_res: .

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_packet_stats_res:  
-4->dmgr=0x645461E0,stats_reqs=3

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_exec: [B SM: R:FSM_OK -> 
S:S_DSM_BRIDGED]

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_BRIDGED E:E_DSM_DSP_GET_RX]

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_packet_stats_res: .

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_packet_stats_res:  
-4->dmgr=0x645461E0,stats_reqs=2

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_exec: [B SM: R:FSM_OK -> 
S:S_DSM_BRIDGED]

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_BRIDGED E:E_DSM_DSP_GET_VP_DELAY]

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_packet_stats_res: .

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_packet_stats_res:  
-4->dmgr=0x645461E0,stats_reqs=1

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_exec: [B SM: R:FSM_OK -> 
S:S_DSM_BRIDGED]

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_exec: [Feat SM: S:NONE B 
SM: S:S_DSM_BRIDGED E:E_DSM_DSP_GET_VP_ERROR]

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_packet_stats_res: .

Router#

*May  1 19:40:09.775: //43/7EE6F84B8016/DSM:(4/0/0):-1:1:1/dsm_act_packet_stats_res:  
-4->dmgr=0x645461E0,stats_reqs=0

debug voip dsm Session of a VoIP Call in Transition to Connected State and in Transition from Connected to Disconnected State

The following is sample output from the debug voip dsm command, with Cisco IOS Release 12.3(14)T software, when a VoIP call is in transition to the connected state:

Router# debug voip dsm 

*Jun  8 20:10:33.205: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_create: .

*Jun  8 20:10:33.209: //28/6F1FA7868003/DSM:(2/0:23):-1/set_echo_canceller_data: 
echo_cancel: 1

*Jun  8 20:10:33.209: //28/6F1FA7868003/DSM:(2/0:23):-1/set_echo_canceller_data: 
echo_flags: 55, echo_len: 512

*Jun  8 20:10:33.217: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmp_dsmapi_reserve_resource_cb: .

*Jun  8 20:10:33.217: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_set_elog_enable: .

*Jun  8 20:10:33.477: 
//28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_reinit_platform_info: .

*Jun  8 20:10:33.477: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmp_dsmapi_reserve_resource_cb: .

*Jun  8 20:10:33.485: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_bridge: .

*Jun  8 20:10:33.485: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_bridge: 

*Jun  8 20:10:33.485:  dsp_stream_mgr_bridge, src_call_id 28, dst_call_id 27

*Jun  8 20:10:33.485: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_bridge: creating 
packet streams

*Jun  8 20:10:33.489: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_insert_conn_array_entry: 
dmgr->connArr.count = 2

*Jun  8 20:10:33.489: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_do_caps_ind: .

*Jun  8 20:10:33.493: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_cap_ind_ack: 
src_call_id = 28, dst_call_id = 27, is_cap_ack = 1

*Jun  8 20:10:33.493: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_cap_ind_ack: 
src_call_id = 28, dst_call_id = 27, is_cap_ack = 0

*Jun  8 20:10:33.493: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_ind_negotiation: Caps in 
caps indication:

*Jun  8 20:10:33.497: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: playout: [ 
mode:1,init:60, min:40, max:250]. data_mode:0

*Jun  8 20:10:33.497: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: RTP 
PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],ClearC
han[125],PCMu[0],PCMa[8]Codec[4], TxDynamicPayload[0], RxDynamicPayload[0]

*Jun  8 20:10:33.497: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: passthrough: 
cap_modem_proto 4,cap_modem_codec 1, cap_modem_redundancy 1, payload 100, modem_relay 0, 
gw-xid 0

*Jun  8 20:10:33.497: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_debug_caps_encap: Encap 1, Vad 
2, Codec 0x4, CodecBytes 20, 

             FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051 

             SignalType 2

             DtmfRelay 1, Modem 2, SeqNumStart 0x0

*Jun  8 20:10:33.497: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_ind_negotiation: Caps 
after caps negotiation:

*Jun  8 20:10:33.497: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: playout: [ 
mode:1,init:60, min:40, max:250]. data_mode:0

*Jun  8 20:10:33.497: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: RTP 
PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],ClearC
han[125],PCMu[0],PCMa[8]Codec[4], TxDynamicPayload[0], RxDynamicPayload[0]

*Jun  8 20:10:33.501: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: passthrough: 
cap_modem_proto 4,cap_modem_codec 1, cap_modem_redundancy 1, payload 100, modem_relay 0, 
gw-xid 0

*Jun  8 20:10:33.501: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_debug_caps_encap: Encap 1, Vad 
2, Codec 0x4, CodecBytes 20, 

             FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051 

             SignalType 2

             DtmfRelay 1, Modem 2, SeqNumStart 0x0

*Jun  8 20:10:33.501: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_cap_ind_ack: packet 
streams already created during bridging

*Jun  8 20:10:33.501: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_ind_negotiation: Caps in 
caps indication:

*Jun  8 20:10:33.501: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: playout: [ 
mode:1,init:60, min:40, max:250]. data_mode:0

*Jun  8 20:10:33.501: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: RTP 
PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],ClearC
han[125],PCMu[0],PCMa[8]Codec[4], TxDynamicPayload[0], RxDynamicPayload[0]

*Jun  8 20:10:33.505: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: passthrough: 
cap_modem_proto 4,cap_modem_codec 1, cap_modem_redundancy 1, payload 100, modem_relay 0, 
gw-xid 0

*Jun  8 20:10:33.505: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_debug_caps_encap: Encap 1, Vad 
2, Codec 0x4, CodecBytes 20, 

             FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051 

             SignalType 2

             DtmfRelay 1, Modem 2, SeqNumStart 0x0

*Jun  8 20:10:33.505: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_ind_negotiation: Caps 
after caps negotiation:

*Jun  8 20:10:33.505: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: playout: [ 
mode:1,init:60, min:40, max:250]. data_mode:0

*Jun  8 20:10:33.505: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: RTP 
PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],ClearC
han[125],PCMu[0],PCMa[8]Codec[4], TxDynamicPayload[0], RxDynamicPayload[0]

*Jun  8 20:10:33.505: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_caps_dump: passthrough: 
cap_modem_proto 4,cap_modem_codec 1, cap_modem_redundancy 1, payload 100, modem_relay 0, 
gw-xid 0

*Jun  8 20:10:33.505: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_debug_caps_encap: Encap 1, Vad 
2, Codec 0x4, CodecBytes 20, 

             FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051 

             SignalType 2

             DtmfRelay 1, Modem 2, SeqNumStart 0x0

*Jun  8 20:10:33.509: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmp_dsmapi_connect_cb: .

*Jun  8 20:10:36.229: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_req_stats: .

*Jun  8 20:10:36.233: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmp_dsmapi_stats_cb: .

*Jun  8 20:10:38.265: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_req_stats: .

*Jun  8 20:10:38.269: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmp_dsmapi_stats_cb: .

*Jun  8 20:10:43.481: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_req_stats: .

*Jun  8 20:10:43.489: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmp_dsmapi_stats_cb: .

The following is sample output from the debug voip dsm command, with Cisco IOS Release 12.3(14)T software, when a VoIP call is in transition from connected to the disconnected state:

Router# debug voip dsm

*Jun 8 20:12:14.701: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_req_stats: .

*Jun 8 20:12:14.705: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmp_dsmapi_stats_cb: .

*Jun 8 20:12:18.721: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_bridge_drop:

*Jun 8 20:12:18.721: dsp_stream_mgr_bridge_drop, src_call_id 28, dst_call_id 27

*Jun 8 20:12:18.721: //28/6F1FA7868003/DSM:(2/0:23):-1/dsm_delete_conn_array_entry: dmgr->connArr.count = 0

*Jun 8 20:12:18.737: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmp_dsmapi_stats_cb: .

*Jun 8 20:12:18.765: //28/6F1FA7868003/DSM:(2/0:23):-1/dsp_stream_mgr_destroy: .

*Jun 8 20:12:18.765: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmapi_accept_modem_passthrough_session: : dmgr: 6561C520, active sessions 0, max sessions: 16 rejected sessions till now: 0

*Jun 8 20:12:18.769: //28/6F1FA7868003/DSM:(2/0:23):-1/dsmp_dsmapi_reserve_resource_cb: .

Related Commands

Command	Description
debug voip dsmp	Displays debugging information from the DSMP and its related applications.
debug voip vtsp	Displays information about the VTSP.

debug voip dsmp

To display debugging information from the Distributed Stream Media Processor (DSMP) and its related applications, use the debug voip dsmp command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip dsmp [all | default | error | event | function | individual | inout | rtp | session | stats | tone | vofr]

no debug voip dsmp

Syntax Description

all	(Optional) Enables all DSMP debugging (except stats).
default	(Optional) Activates inout, error, and event debugging.
error	(Optional) Enables DSMP error debugging.
event	(Optional) Enables state machine debugging.
function	(Optional) Enables procedure tracing.
individual	(Optional) Enables individual DSMP debugging.
inout	(Optional) Enables subsystem inout debugging.
rtp	(Optional) Enables Real-Time Protocol (RTP) debugging on DSMP.
session	(Optional) Enables session debugging.
stats	(Optional) Enables DSMP statistics debugging.
tone	(Optional) Enables tone debugging.
vofr	(Optional) Enables Voice over Frame Relay (VoFR) debugging on the VPM.

Command Modes

Privileged EXEC (#)

Command History

Release	Modification
12.3(8)T	This command was introduced.
12.3(14)T	The all, default, error, event, function, individual, inout, rtp, session, stats, tone, and vofr keywords were added to the command.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

To debug VoIP calls, use this command in conjunction with debug voip vtsp command and debug voip dsm commands. All the related information for media processing is now available by using DSMP. DSM is responsible for creating streams and issuing connections between them.

Examples

The following is sample output from the debug voip dsmp command for transcoding call:

Router# debug voip dsmp 

Syslog logging:enabled (11 messages dropped, 2 messages rate-limited,

                0 flushes, 0 overruns, xml disabled, filtering disabled)

    Console logging:disabled

    Monitor logging:level debugging, 0 messages logged, xml disabled,

                     filtering disabled

    Buffer logging:level debugging, 236 messages logged, xml disabled,

                    filtering disabled

    Logging Exception size (4096 bytes)

    Count and timestamp logging messages:disabled

    Trap logging:level informational, 25 message lines logged

Log Buffer (3000000 bytes):

00:10:18:dsmpAddStream::

00:10:18:dsmpAddStream::

00:10:18:dsmpAddStream::

00:10:18:dsmpAddStream::

00:10:18:dsmpConnectXcodeBinderReq

00:10:18:dsmp_process_event:

00:10:18:dsmp_process_event:evt->requestType = E_DSMP_CC_XCODE_REQ

00:10:18:dsmpxc_act_alloc_rsc::state = S_DSMPXC_INITIAL event = E_DSMP_CC_XCODE_REQ 

00:10:18:dsp_ret = 4 (if failed, cause = 0)

00:10:18:dsp_intf = 0x63DD21D8

00:10:18:dsmp_set_state_var:message to DSP

    successful,ret::4

00:10:18:CNFSM:cur_container:xcoder_container, cur_state:S_DSMPXC_INITIAL,

event:E_DSMP_CC_XCODE_REQ, next_state:S_DSMPXC_RSC_ALLOCING

00:10:18:dsmp_is_ret_succ::

00:10:18:dsmpxc_act_init_rsc::state = S_DSMPXC_RSC_ALLOCING event = CNFSM_LAMBDA_EVENT 

00:10:18:dsmp_set_state_var:message to DSP

    successful,ret::4

00:10:18:CNFSM:cur_container:xcoder_container, cur_state:S_DSMPXC_RSC_ALLOCING,

event:CNFSM_LAMBDA_EVENT, next_state:S_DSMPXC_RSC_INITING

00:10:18:dsmp_is_ret_succ::

00:10:18:dsmpxc_act_open_rsc::state = S_DSMPXC_RSC_INITING event = CNFSM_LAMBDA_EVENT 

00:10:18:dsmp_set_state_var:message to DSP

    successful,ret::4

00:10:18:CNFSM:cur_container:xcoder_container, cur_state:S_DSMPXC_RSC_INITING,

event:CNFSM_LAMBDA_EVENT, next_state:S_DSMPXC_RSC_OPENING

00:10:18:dsmp_is_ret_succ::

00:10:18:dsmpxc_act_program_rsc::state = S_DSMPXC_RSC_OPENING event = CNFSM_LAMBDA_EVENT 

00:10:18:dsmp_set_state_var:message to DSP

    successful,ret::4

00:10:18:CNFSM:cur_container:xcoder_container, cur_state:S_DSMPXC_RSC_OPENING,

event:CNFSM_LAMBDA_EVENT, next_state:S_DSMPXC_XCODE_PEND

00:10:18:CNFSM:new_container:xcoding_container

00:10:18:dsmp_is_ret_succ::

00:10:18:dsmpxc_act_succ_conn_req::state = S_DSMPXC_XCODE_PEND event = CNFSM_LAMBDA_EVENT 

00:10:18:CNFSM:cur_container:xcoding_container, cur_state:S_DSMPXC_XCODE_PEND,

event:CNFSM_LAMBDA_EVENT, next_state:S_DSMPXC_XCODE

00:10:18:no_stream_in_session

Router# clear log

Clear logging buffer [confirm]

Router#

Router# clear logsh logg  

Syslog logging:enabled (11 messages dropped, 2 messages rate-limited,

                0 flushes, 0 overruns, xml disabled, filtering disabled)

    Console logging:disabled

    Monitor logging:level debugging, 0 messages logged, xml disabled,

                     filtering disabled

    Buffer logging:level debugging, 274 messages logged, xml disabled,

                    filtering disabled

    Logging Exception size (4096 bytes)

    Count and timestamp logging messages:disabled

    Trap logging:level informational, 25 message lines logged

Log Buffer (3000000 bytes):

00:10:50:dsmpDeleteStream

00:10:50:dsmpDeleteStream

00:10:50:dsmpDeleteStream

00:10:50:dsmpDeleteStream

00:10:50:dsmp_process_event:

00:10:50:dsmp_process_event:evt->requestType = E_DSMP_CC_DELETE_STREAM

00:10:50:dsmpxc_act_delete_stream::state = S_DSMPXC_XCODE event = E_DSMP_CC_DELETE_STREAM 

00:10:50:CNFSM:cur_container:xcoding_container, cur_state:S_DSMPXC_XCODE,

event:E_DSMP_CC_DELETE_STREAM, next_state:CNFSM_NO_STATE_CHANGE

00:10:50:no_stream_in_session

00:10:50:dsmp_process_event:evt->requestType = E_DSMP_CC_DELETE_STREAM

00:10:50:dsmpxc_act_delete_stream::state = S_DSMPXC_XCODE event = E_DSMP_CC_DELETE_STREAM 

00:10:50:CNFSM:cur_container:xcoding_container, cur_state:S_DSMPXC_XCODE,

event:E_DSMP_CC_DELETE_STREAM, next_state:CNFSM_NO_STATE_CHANGE

00:10:50:no_stream_in_session

00:10:50:dsmp_process_event:evt->requestType = E_DSMP_CC_DELETE_STREAM

00:10:50:dsmpxc_act_delete_stream::state = S_DSMPXC_XCODE event = E_DSMP_CC_DELETE_STREAM 

00:10:50:CNFSM:cur_container:xcoding_container, cur_state:S_DSMPXC_XCODE,

event:E_DSMP_CC_DELETE_STREAM, next_state:CNFSM_NO_STATE_CHANGE

00:10:50:no_stream_in_session

00:10:50:dsmp_process_event:evt->requestType = E_DSMP_CC_DELETE_STREAM

00:10:50: dsmpxc_act_delete_stream::state = S_DSMPXC_XCODE event = E_DSMP_CC_DELETE_STREAM 

00:10:50:CNFSM:cur_container:xcoding_container, cur_state:S_DSMPXC_XCODE,

event:E_DSMP_CC_DELETE_STREAM, next_state:CNFSM_NO_STATE_CHANGE

00:10:50:no_stream_in_session

00:10:50:dsmpxc_act_stop_rsc::state = S_DSMPXC_XCODE event = CNFSM_LAMBDA_EVENT 

00:10:50:dsmp_set_state_var:message to DSP

    successful,ret::4

00:10:50:CNFSM:cur_container:xcoding_container, cur_state:S_DSMPXC_XCODE,

event:CNFSM_LAMBDA_EVENT, next_state:S_DSMPXC_CLOSING

00:10:50:dsmp_is_ret_succ::

00:10:50:dsmpxc_act_dealloc_rsc::state = S_DSMPXC_CLOSING event = CNFSM_LAMBDA_EVENT 

00:10:50:dsmp_set_state_var:message to DSP

    successful,ret::4

00:10:50:CNFSM:cur_container:xcoding_container, cur_state:S_DSMPXC_CLOSING,

event:CNFSM_LAMBDA_EVENT, next_state:S_DSMPXC_STILL_CLOSING

00:10:50:CNFSM:new_container:xcoder_container

00:10:50:dsmp_is_ret_succ::

00:10:50:dsmpxc_act_start_timer::state = S_DSMPXC_STILL_CLOSING event = CNFSM_LAMBDA_EVENT 

00:10:50:CNFSM:cur_container:xcoder_container, cur_state:S_DSMPXC_STILL_CLOSING,

event:CNFSM_LAMBDA_EVENT, next_state:CNFSM_NO_STATE_CHANGE

00:10:50:dsmp_process_event:evt->requestType = E_DSMP_DSPRM_CLOSE_COMPLETE

00:10:50:dsmpxc_act_terminate::state = S_DSMPXC_STILL_CLOSING event =

E_DSMP_DSPRM_CLOSE_COMPLETE 

00:10:50:dsmpxc_act_terminate Removing the program based stream = 1

00:10:50:CNFSM:cur_container:xcoder_container, cur_state:S_DSMPXC_STILL_CLOSING,

event:E_DSMP_DSPRM_CLOSE_COMPLETE, next_state:CNFSM_NULL_STATE

00:10:50:dsmp_free_session

00:10:50:dsmp_process_event:

The following is sample output from the debug voip dsmp command, with Cisco IOS Release 12.3(14)T software, when a VoIP call is in transition to the connected state:

Router# debug voip dsmp 

*May 22 04:12:17.775: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 24, confID = 
-1, streamType = TDM, streamDir = INPUT, ownerContext = 0x0000000C, codec = 0x0, 
fax_modem_type =0, XmitFn = 0x00000000, multicastStreamDtmfType = 0

*May 22 04:12:17.775: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 2

*May 22 04:12:17.779: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 24, confID = 
-1, streamType = TDM, streamDir = OUTPUT, ownerContext = 0x0000000C, codec = 0x0, 
fax_modem_type =0, XmitFn = 0x00000000, multicastStreamDtmfType = 0

*May 22 04:12:17.779: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 3

*May 22 04:12:17.779: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpReserveGwResource: sIdLegInput1 = 
2, sIdLegOutput1 = 3, requesterCallID = 24, respFunc = 0x61CD1EE0

*May 22 04:12:17.787: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_new_or_existing_gw_session: 
created a new session = 0x657EEE20

*May 22 04:12:17.787: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_enlist_stream: Stream 2 is 
enlisted, total = 1

*May 22 04:12:17.787: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_enlist_stream: Stream 3 is 
enlisted, total = 2

*May 22 04:12:17.787: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_INITIAL, event:E_DSMP_CC_RESERVE_RESOURCE_REQ]

*May 22 04:12:17.791: CNFSM: cur_container:rsc_main_container, cur_state:S_DSMP_INITIAL, 
event:E_DSMP_CC_RESERVE_RESOURCE_REQ

*May 22 04:12:17.791: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_gw_act_alloc_rsc: 

*May 22 04:12:17.791: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_decide_pgm_based_stm: 

*May 22 04:12:17.791: //-1/F401BFC88006/DSMP:():-1/dsmp_set_state_var: Message to DSP is 
successful

*May 22 04:12:17.791: CNFSM: new_container:rsc_allocating_container

*May 22 04:12:17.791: CNFSM: next_state:S_DSMP_RSC_ALLOCATING

*May 22 04:12:17.791: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_rsc_alloc_succ: 

*May 22 04:12:17.791: CNFSM: next_state:S_DSMP_RSC_ALLOCATED

*May 22 04:12:18.047: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpReserveGwResource: sIdLegInput1 = 
2, sIdLegOutput1 = 3, requesterCallID = 24, respFunc = 0x61CD1EE0

*May 22 04:12:18.047: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_RSC_ALLOCATED, event:E_DSMP_CC_RESERVE_RESOURCE_REQ]

*May 22 04:12:18.051: CNFSM: cur_container:rsc_allocating_container, 
cur_state:S_DSMP_RSC_ALLOCATED, event:E_DSMP_CC_RESERVE_RESOURCE_REQ

*May 22 04:12:18.051: CNFSM: new_container:rsc_main_container

*May 22 04:12:18.051: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_rsc_reopen: 

*May 22 04:12:18.051: //-1/F401BFC88006/DSMP:():-1/dsmp_set_state_var: Message to DSP is 
successful

*May 22 04:12:18.051: CNFSM: history stored state: S_DSMP_RSC_ALLOCATED, container: 
rsc_allocating_container

*May 22 04:12:18.051: CNFSM: updated current container: rsc_main_container

*May 22 04:12:18.051: CNFSM: next_state:S_DSMP_RSC_REOPENING

*May 22 04:12:18.051: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_rsc_alloc_succ: 

*May 22 04:12:18.051: CNFSM: restoring history state: S_DSMP_RSC_ALLOCATED

*May 22 04:12:18.055: CNFSM: restoring history container: rsc_allocating_container

*May 22 04:12:18.055: CNFSM: new_container:rsc_allocating_container

*May 22 04:12:18.055: CNFSM: next_state:S_DSMP_RSC_ALLOCATED

*May 22 04:12:18.055: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpStopPlay: sIdLegOutput = 3

*May 22 04:12:18.059: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_RSC_ALLOCATED, event:E_DSMP_CC_STOP_PLAY_REQ]

*May 22 04:12:18.059: CNFSM: cur_container:rsc_allocating_container, 
cur_state:S_DSMP_RSC_ALLOCATED, event:E_DSMP_CC_STOP_PLAY_REQ

*May 22 04:12:18.059: CNFSM: new_container:rsc_main_container

*May 22 04:12:18.059: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_ignore: 

*May 22 04:12:18.059: CNFSM: next_state:CNFSM_NO_STATE_CHANGE

*May 22 04:12:18.063: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 23, confID = 
12, streamType = PACKET, streamDir = INPUT, ownerContext = 0x0000000C, codec = 0x1, 
fax_modem_type =0, XmitFn = 0x6158E1F8, multicastStreamDtmfType = -1

*May 22 04:12:18.067: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 1

*May 22 04:12:18.067: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: callID = 23, confID = 
12, streamType = PACKET, streamDir = OUTPUT, ownerContext = 0x0000000C, codec = 0x1, 
fax_modem_type =0, XmitFn = 0x6158E1F8, multicastStreamDtmfType = -1

*May 22 04:12:18.067: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpAddStream: streamID = 4

*May 22 04:12:18.071: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 1, callID = 
24, mode = 1

*May 22 04:12:18.071: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: sIdLegInput = 1, 
sIdLegOutput = 4, callID = 24

*May 22 04:12:18.075: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpConnectGwBinderReq:  

   sIdLegInputTdm1=2, sIdLegOutputPak1=4, sIdLegInputPak2=1, sIdLegOutputTdm2=3, Call 
Id=24

*May 22 04:12:18.075: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpModifyReq: sIdLegInput = 2, 
sIdLegOutput = 3, callID = 24FORKING Parameters are forking mask: 7, 
simple_forking_codec_mask: 327679, complex_forking_codec_mask 327679

*May 22 04:12:18.075: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_process_event: No session is 
associated to the streams.

*May 22 04:12:18.075: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_caps: 

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: 

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: 

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write:  

   CALL_ERROR; DSMP Session Is NULL

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write:exit@2908

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: 

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write:  

   CALL_ERROR; DSMP Session Is NULL

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write:exit@2908

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: 

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write:  

   CALL_ERROR; DSMP Session Is NULL

*May 22 04:12:18.079: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write:exit@2908

*May 22 04:12:18.083: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: 

*May 22 04:12:18.083: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write:  

   CALL_ERROR; DSMP Session Is NULL

*May 22 04:12:18.083: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write:exit@2908

*May 22 04:12:18.083: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: 

*May 22 04:12:18.083: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: 

*May 22 04:12:18.083: 
//-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps:exit@385

*May 22 04:12:18.087: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: 

*May 22 04:12:18.087: 
//-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps:exit@385

*May 22 04:12:18.087: //-1/F401BFC88006/DSMP:():-1/dsmp_enlist_stream: Stream 1 is 
enlisted, total = 3

*May 22 04:12:18.087: //-1/F401BFC88006/DSMP:():-1/dsmp_enlist_stream: Stream 4 is 
enlisted, total = 4

*May 22 04:12:18.087: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_RSC_ALLOCATED, event:E_DSMP_CC_CONNECT_REQ]

*May 22 04:12:18.091: CNFSM: cur_container:rsc_allocating_container, 
cur_state:S_DSMP_RSC_ALLOCATED, event:E_DSMP_CC_CONNECT_REQ

*May 22 04:12:18.091: //-1/F401BFC88006/DSMP:():-1/dsmp_is_req_not_in_proc_dsp_ready: 

*May 22 04:12:18.091: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_rsc_init: 

*May 22 04:12:18.091: //-1/F401BFC88006/DSMP:():-1/dsmp_set_state_var: Message to DSP is 
successful

*May 22 04:12:18.091: CNFSM: history stored state: S_DSMP_RSC_ALLOCATED, container: 
rsc_allocating_container

*May 22 04:12:18.091: CNFSM: new_container:rsc_main_container

*May 22 04:12:18.095: CNFSM: next_state:S_DSMP_RSC_INITING

*May 22 04:12:18.095: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_decide_pgm_based_stm: 

*May 22 04:12:18.095: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_rsc_init_succ: 
pgm_base_stm_id = 1, service_id = 26, codec =4

*May 22 04:12:18.095: //-1/F401BFC88006/DSMP:():-1/dsmp_set_state_var: Message to DSP is 
successful

*May 22 04:12:18.099: CNFSM: next_state:S_DSMP_RSC_OPENING

*May 22 04:12:18.099: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_rsc_open_succ: 

*May 22 04:12:18.099: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: 

*May 22 04:12:18.099: //-1/F401BFC88006/DSMP:():-1/dsmp_conf_static_params: 

*May 22 04:12:18.099: //-1/F401BFC88006/DSMP:():-1/dsmp_conf_static_params: servic_type=1 
base stream=1 tdm_caps=0x6557E454 pkt_caps=0x6557EC3C

*May 22 04:12:18.099: //-1/F401BFC88006/DSMP:():-1/dsmp_conf_static_params: 

*May 22 04:12:18.103:  ip_tones = 0, pstn_tones = 0

*May 22 04:12:18.103: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_static_params: voice 
detection disabled

*May 22 04:12:18.103: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_static_params: silence 
detection disabled

*May 22 04:12:18.103: //-1/F401BFC88006/DSMP:():-1/dsmp_set_state_var: Message to DSP is 
successful

*May 22 04:12:18.107: CNFSM: next_state:S_DSMP_RSC_STATIC_CONF

*May 22 04:12:18.107: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_start_service: 

*May 22 04:12:18.107: //-1/F401BFC88006/DSMP:():-1/dsmp_set_state_var: Message to DSP is 
successful

*May 22 04:12:18.107: CNFSM: next_state:S_DSMP_RSC_STARTING

*May 22 04:12:18.107: //-1/F401BFC88006/DSMP:():-1/dsmp_act_rsc_start_succ: 

*May 22 04:12:18.107: //-1/F401BFC88006/DSMP:():-1/dsmp_conf_dynamic_params: 

*May 22 04:12:18.111: //-1/F401BFC88006/DSMP:():-1/dsmp_conf_dynamic_params: servic_type=1 
base stream=1

*May 22 04:12:18.111: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: 

*May 22 04:12:18.111: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: 

*May 22 04:12:18.111: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_leg_voice_elog_write: 

*May 22 04:12:18.111: //-1/F401BFC88006/DSMP:():-1/dsmp_conf_detector_params: 

*May 22 04:12:18.115: //-1/F401BFC88006/DSMP:():-1/dsmp_set_state_var: Message to DSP is 
successful

*May 22 04:12:18.115: CNFSM: next_state:S_DSMP_RSC_DYNAMIC_CONF

*May 22 04:12:18.115: CNFSM: new_container:rsc_running_container

*May 22 04:12:18.115: CNFSM: next_state:S_DSMP_MCAST_CHECK

*May 22 04:12:18.115: //-1/F401BFC88006/DSMP:():-1/dsmp_is_mcast: 

*May 22 04:12:18.119: //-1/F401BFC88006/DSMP:():-1/dsmp_is_mcast:exit@1487

*May 22 04:12:18.119: //-1/F401BFC88006/DSMP:():-1/dsmp_is_not_mcast: 

*May 22 04:12:18.119: //-1/F401BFC88006/DSMP:():-1/dsmp_is_mcast: 

*May 22 04:12:18.119: //-1/F401BFC88006/DSMP:():-1/dsmp_is_mcast:exit@1487

*May 22 04:12:18.119: //-1/F401BFC88006/DSMP:():-1/dsmp_is_not_mcast:exit@1500

*May 22 04:12:18.119: CNFSM: next_state:S_DSMP_DTMF_FSK_MODE_CHECK

*May 22 04:12:18.119: //-1/F401BFC88006/DSMP:():-1/dsmp_is_fsk_or_dtmf: 

*May 22 04:12:18.123: //-1/F401BFC88006/DSMP:():-1/dsmp_is_diagnostic: 

*May 22 04:12:18.123: //-1/F401BFC88006/DSMP:():-1/dsmp_is_diagnostic:exit@1587

*May 22 04:12:18.123: //-1/F401BFC88006/DSMP:():-1/dsmp_is_not_dtmf_fsk: 

*May 22 04:12:18.123: //-1/F401BFC88006/DSMP:():-1/dsmp_is_fsk_or_dtmf: 

*May 22 04:12:18.123: //-1/F401BFC88006/DSMP:():-1/dsmp_is_diagnostic: 

*May 22 04:12:18.123: //-1/F401BFC88006/DSMP:():-1/dsmp_is_diagnostic:exit@1587

*May 22 04:12:18.123: //-1/F401BFC88006/DSMP:():-1/dsmp_is_not_dtmf_fsk:exit@1600

*May 22 04:12:18.127: CNFSM: next_state:S_DSMP_NOT_DTMF_FSK

*May 22 04:12:18.127: //-1/F401BFC88006/DSMP:():-1/dsmp_is_simple_voice: 

*May 22 04:12:18.127: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_get_stm_service_type: 

*May 22 04:12:18.127: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_simple_voice_start: 

*May 22 04:12:18.127: //-1/F401BFC88006/DSMP:():-1/dsmp_connect_req_done: 

*May 22 04:12:18.127: //-1/F401BFC88006/DSMP:():-1/dsmp_enlist_connection: Connection is 
added, enlisted LegIn: 2, enlisted LegOut: 4

*May 22 04:12:18.131: //-1/F401BFC88006/DSMP:():-1/dsmp_enlist_connection: Connection is 
added, enlisted LegIn: 1, enlisted LegOut: 3

*May 22 04:12:18.131: CNFSM: new_container:simple_voice_container

*May 22 04:12:18.131: CNFSM: next_state:S_DSMP_VC_RUNNING

*May 22 04:12:18.131: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_MODIFY_REQ]

*May 22 04:12:18.135: CNFSM: cur_container:simple_voice_container, 
cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_MODIFY_REQ

*May 22 04:12:18.135: CNFSM: new_container:rsc_running_container

*May 22 04:12:18.135: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_save_modify_caps: 

*May 22 04:12:18.135: //-1/F401BFC88006/DSMP:():-1/dsmp_save_modify_caps: 

*May 22 04:12:18.135: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: 

*May 22 04:12:18.139: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_packet_stream_caps: 

*May 22 04:12:18.139: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: 

*May 22 04:12:18.139: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_save_modify_tdm_stream_caps: 

*May 22 04:12:18.139: //-1/F401BFC88006/DSMP:():-1/dsmp_save_stream_detectors: 

*May 22 04:12:18.139: CNFSM: history stored state: S_DSMP_VC_RUNNING, container: 
simple_voice_container

*May 22 04:12:18.139: CNFSM: updated current container: rsc_running_container

*May 22 04:12:18.143: CNFSM: next_state:S_DSMP_MODIFY_CAPS_SAVED

*May 22 04:12:18.143: //-1/F401BFC88006/DSMP:():-1/dsmp_is_modify_to_modem_passthru: 

*May 22 04:12:18.143: //-1/F401BFC88006/DSMP:():-1/dsmp_is_static_params_changed: 

*May 22 04:12:18.143: //-1/F401BFC88006/DSMP:():-1/dsmp_is_modify_to_modem_passthru: 

*May 22 04:12:18.143: //-1/F401BFC88006/DSMP:():-1/dsmp_is_dynamic_params_changed: 

*May 22 04:12:18.143: //-1/F401BFC88006/DSMP:():-1/dsmp_is_modify_to_modem_passthru: 

*May 22 04:12:18.143: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_dynamic_params_changed: 

*May 22 04:12:18.147: //-1/F401BFC88006/DSMP:():-1/dsmp_conf_dynamic_params: 

*May 22 04:12:18.147: //-1/F401BFC88006/DSMP:():-1/dsmp_conf_dynamic_params: servic_type=1 
base stream=1

*May 22 04:12:18.147: //-1/F401BFC88006/DSMP:():-1/dsmp_conf_detector_params: 

*May 22 04:12:18.147: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_detector_params: fax: 1

*May 22 04:12:18.147: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmp_conf_detector_params: modem: 1

*May 22 04:12:18.151: CNFSM: restoring history state: S_DSMP_VC_RUNNING

*May 22 04:12:18.151: CNFSM: restoring history container: simple_voice_container

*May 22 04:12:18.151: CNFSM: new_container:simple_voice_container

*May 22 04:12:18.151: CNFSM: next_state:S_DSMP_VC_RUNNING

*May 22 04:12:18.163: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 1, callID = 
24, mode = 3

*May 22 04:12:18.163: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 1, callID = 
24, mode = 3

*May 22 04:12:30.947: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpUpdateRtpMode: sId = 1, callID = 
24, mode = 3

The following is sample output from the debug voip dsmp command, with Cisco IOS Release 12.3(14)T software, when a VoIP call is in transition from connected to the disconnected state:

Router# debug voip dsmp 

*May 22 04:12:30.951: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpDisconnectGwBinder: sIdLegInputTdm1 
= 2, sIdLegOutputPak1 = 4, sIdLegInputPak2 = 1, sIdLegOutputTdm2 = 3 requesterCallID = 24

*May 22 04:12:30.951: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 1, callID 
= 23, ownerContext = 0x00000000

*May 22 04:12:30.951: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 4, callID 
= 23, ownerContext = 0x00000000

*May 22 04:12:30.951: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_DISCONNECT]

*May 22 04:12:30.955: CNFSM: cur_container:simple_voice_container, 
cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_DISCONNECT

*May 22 04:12:30.955: CNFSM: new_container:rsc_running_container

*May 22 04:12:30.955: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_cc_disconnect: 

*May 22 04:12:30.955: //-1/F401BFC88006/DSMP:():-1/dsmp_delist_connection: Connection is 
delisted, delisted LegIn: 2, delisted LegOut: 4, total conn_count is = 1

*May 22 04:12:30.955: //-1/F401BFC88006/DSMP:():-1/dsmp_delist_connection: Connection is 
delisted, delisted LegIn: 1, delisted LegOut: 3, total conn_count is = 0

*May 22 04:12:30.955: CNFSM: next_state:CNFSM_NO_STATE_CHANGE

*May 22 04:12:30.955: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_VC_RUNNING, event:E_DSMP_DELETE_STREAM]

*May 22 04:12:30.959: CNFSM: cur_container:simple_voice_container, 
cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DELETE_STREAM

*May 22 04:12:30.959: CNFSM: new_container:rsc_running_container

*May 22 04:12:30.959: CNFSM: new_container:rsc_main_container

*May 22 04:12:30.959: //-1/F401BFC88006/DSMP:():-1/dsmp_not_last_stream: 

*May 22 04:12:30.959: //-1/F401BFC88006/DSMP:():-1/dsmp_is_last_stream: 

*May 22 04:12:30.959: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_not_last_stream: 

*May 22 04:12:30.959: //-1/F401BFC88006/DSMP:():-1/remove_stream_from_DB: 

*May 22 04:12:30.959: //-1/F401BFC88006/DSMP:():-1/process_software_multicast_streams: 

*May 22 04:12:30.959: CNFSM: next_state:CNFSM_NO_STATE_CHANGE

*May 22 04:12:30.959: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_VC_RUNNING, event:E_DSMP_DELETE_STREAM]

*May 22 04:12:30.959: CNFSM: cur_container:simple_voice_container, 
cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DELETE_STREAM

*May 22 04:12:30.963: CNFSM: new_container:rsc_running_container

*May 22 04:12:30.963: CNFSM: new_container:rsc_main_container

*May 22 04:12:30.963: //-1/F401BFC88006/DSMP:():-1/dsmp_not_last_stream: 

*May 22 04:12:30.963: //-1/F401BFC88006/DSMP:():-1/dsmp_is_last_stream: 

*May 22 04:12:30.963: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_not_last_stream: 

*May 22 04:12:30.963: //-1/F401BFC88006/DSMP:():-1/remove_stream_from_DB: 

*May 22 04:12:30.963: //-1/F401BFC88006/DSMP:():-1/process_software_multicast_streams: 

*May 22 04:12:30.963: CNFSM: next_state:CNFSM_NO_STATE_CHANGE

*May 22 04:12:30.967: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpStopPlay: sIdLegOutput = 3

*May 22 04:12:30.975: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ]

*May 22 04:12:30.975: CNFSM: cur_container:simple_voice_container, 
cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ

*May 22 04:12:30.975: CNFSM: new_container:rsc_running_container

*May 22 04:12:30.975: CNFSM: new_container:rsc_main_container

*May 22 04:12:30.975: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_ignore: 

*May 22 04:12:30.975: CNFSM: next_state:CNFSM_NO_STATE_CHANGE

*May 22 04:12:31.011: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpStopPlay: sIdLegOutput = 3

*May 22 04:12:31.011: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 2, callID 
= 24, ownerContext = 0x0000000C

*May 22 04:12:31.011: //-1/xxxxxxxxxxxx/DSMP:():-1/dsmpDeleteStream: streamID = 3, callID 
= 24, ownerContext = 0x0000000C

*May 22 04:12:31.015: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ]

*May 22 04:12:31.015: CNFSM: cur_container:simple_voice_container, 
cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_CC_STOP_PLAY_REQ

*May 22 04:12:31.015: CNFSM: new_container:rsc_running_container

*May 22 04:12:31.015: CNFSM: new_container:rsc_main_container

*May 22 04:12:31.015: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_ignore: 

*May 22 04:12:31.015: CNFSM: next_state:CNFSM_NO_STATE_CHANGE

*May 22 04:12:31.015: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_VC_RUNNING, event:E_DSMP_DELETE_STREAM]

*May 22 04:12:31.015: CNFSM: cur_container:simple_voice_container, 
cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DELETE_STREAM

*May 22 04:12:31.019: CNFSM: new_container:rsc_running_container

*May 22 04:12:31.019: CNFSM: new_container:rsc_main_container

*May 22 04:12:31.019: //-1/F401BFC88006/DSMP:():-1/dsmp_not_last_stream: 

*May 22 04:12:31.019: //-1/F401BFC88006/DSMP:():-1/dsmp_is_last_stream: 

*May 22 04:12:31.019: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_not_last_stream: 

*May 22 04:12:31.019: //-1/F401BFC88006/DSMP:():-1/remove_stream_from_DB: 

*May 22 04:12:31.019: //-1/F401BFC88006/DSMP:():-1/process_software_multicast_streams: 

*May 22 04:12:31.019: CNFSM: next_state:CNFSM_NO_STATE_CHANGE

*May 22 04:12:31.019: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_VC_RUNNING, event:E_DSMP_DELETE_STREAM]

*May 22 04:12:31.019: CNFSM: cur_container:simple_voice_container, 
cur_state:S_DSMP_VC_RUNNING, event:E_DSMP_DELETE_STREAM

*May 22 04:12:31.019: CNFSM: new_container:rsc_running_container

*May 22 04:12:31.023: CNFSM: new_container:rsc_main_container

*May 22 04:12:31.023: //-1/F401BFC88006/DSMP:():-1/dsmp_not_last_stream: 

*May 22 04:12:31.023: //-1/F401BFC88006/DSMP:():-1/dsmp_is_last_stream: 

*May 22 04:12:31.023: //-1/F401BFC88006/DSMP:():-1/dsmp_is_last_stream: 

*May 22 04:12:31.023: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_rsc_stop: 

*May 22 04:12:31.023: //-1/F401BFC88006/DSMP:():-1/remove_stream_from_DB: 

*May 22 04:12:31.023: //-1/F401BFC88006/DSMP:():-1/process_software_multicast_streams: 

*May 22 04:12:31.023: //-1/F401BFC88006/DSMP:():-1/dsmp_set_state_var: Message to DSP is 
successful

*May 22 04:12:31.023: CNFSM: history stored state: S_DSMP_VC_RUNNING, container: 
simple_voice_container

*May 22 04:12:31.023: CNFSM: updated current container: rsc_main_container

*May 22 04:12:31.027: CNFSM: new_container:rsc_closing_container

*May 22 04:12:31.027: CNFSM: next_state:S_DSMP_RSC_STOPPING

*May 22 04:12:31.027: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_rsc_stopping_close: 

*May 22 04:12:31.027: //-1/F401BFC88006/DSMP:():-1/dsmp_set_state_var: Message to DSP is 
successful

*May 22 04:12:31.027: CNFSM: next_state:S_DSMP_DSPMGR_CLOSING

*May 22 04:12:31.027: //-1/F401BFC88006/DSMP:():-1/dsmp_exec:  

   [state:S_DSMP_DSPMGR_CLOSING, event:E_DSMP_DSPRM_CLOSE_COMPLETE]

*May 22 04:12:31.027: CNFSM: cur_container:rsc_closing_container, 
cur_state:S_DSMP_DSPMGR_CLOSING, event:E_DSMP_DSPRM_CLOSE_COMPLETE

*May 22 04:12:31.027: //-1/F401BFC88006/DSMP:():-1/dsmp_gw_act_dspmgr_closing_complete: 

*May 22 04:12:31.031: //-1/F401BFC88006/DSMP:():-1/remove_stream_from_DB: 

*May 22 04:12:31.031: //-1/F401BFC88006/DSMP:():-1/process_software_multicast_streams: 

*May 22 04:12:31.031: //-1/F401BFC88006/DSMP:():-1/remove_stream_from_DB: 

*May 22 04:12:31.031: //-1/F401BFC88006/DSMP:():-1/process_software_multicast_streams: 

*May 22 04:12:31.031: CNFSM: next_state:CNFSM_NULL_STATE

*May 22 04:12:31.031: //-1/F401BFC88006/DSMP:():-1/dsmp_free_session: 

Related Commands

Command	Description
debug voip dsm	Displays debugging information from the DSM subsystem.
debug voip vtsp	Displays information about the VTSP.

debug voip dspapi

To troubleshoot the digital signal processor (DSP) application programming interface (API), use the debug voip dspapi command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip dspapi [all | command | default | detail | error [call [informational] | software [informational]] | function | inout | notification | response]

no debug voip dspapi

Syntax Description

all	(Optional) Displays all DSP API debugging messages.
command	(Optional) Displays DSP API commands.
default	(Optional) Displays DSP API detail, error, and inout debugging messages. This option also runs if no keywords are added.
detail	(Optional) Displays detailed information about commands sent to the DSP. This command is used in conjunction with other debug voip dspapi commands to show additional details when you use the command, notification, and response keywords.
error	(Optional) Displays DSP API errors.
call	(Optional) Displays call processing errors.
informational	(Optional) Displays minor errors and major errors. Without the informational keyword, only major errors are displayed.
software	(Optional) Displays software processing errors.
function	(Optional) Displays DSP API functions.
inout	(Optional) Displays output for the command, notification, and response keywords.
notification	(Optional) Displays DSP API notification messages.
response	(Optional) Displays DSP API response messages.

Defaults

Debugging is not enabled.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.3(8)T	This command replaces the debug dspapi command.
12.3(14)T	T.38 fax relay call statistics were made available to Call Detail Records (CDRs) through Vendor-Specific Attributes (VSAs) and added to the call log.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

DSP API message events used to communicate with DSPs are intended for use with Connexant (NextPort) and Texas Instruments (54x) DSPs.

---

Caution This command severely impacts performance and should be used only for single-call debug capture.

---

Examples

The following examples show output for variations of the debug voip dspapi command:

•debug voip dspapi Command on the Originating Gateway

•debug voip dspapi inout on the Terminating Gateway

•debug voip dspapi Command Displaying T.38 fax Relay Statistics on the Terminating Gateway

For these examples, the topology shown in Figure 4 is used.

Figure 4 Network Topology for debug voip dspapi Examples

debug voip dspapi Command on the Originating Gateway

Router# debug voip dspapi command

voip dspapi command debugging is ON

Router#

*Apr 18 21:33:48.347: //-1/CD89F6A78020/DSPAPI/[2/1:23]/dsp_init:

*Apr 18 21:33:48.347: //-1/CD89F6A78020/DSPAPI/[2/1:23]/dsp_voice_config_params:

*Apr 18 21:33:48.347:     9 parameters

The following lines show encapsulation settings, jitter, inband signaling, echo cancellation, gain, and other quality of service (QoS) settings:

    [0] ENCAP RTP: Tx SSRC=0, Rx SSRC=0, Tx VPXCC=0, Rx VPXCC=0

    IFP payload type=122, SID support=1, TSE payload=101, Sequence number start=0

    Redundancy=0, ClearChannel payload type=0, Fax payload type=0

    Alaw PCM switchover=0, MuLAW PCM switchover=0

    DTMF payload type=0, NTE receive payload type=101

    Dynamic payload=0, Codec=g711ulaw

    [1] PO_JITTER: mode=2 initial=60(ms) max=200(ms) min=40(ms) fax_nom=300(ms)

    [2] INBAND_SIG: mode=0x1 enable

    [3] ECHO_CANCEL: Flags=0x37, Echo length=64(ms)

    [4] IDLE_CODE_DET: Enable=0, Code=0x0, Duration=6000(ms)

    [5] GAIN: Input=0.0(dB), Output=0.0(dB)

    [6] CNG: 1

    [7] INFO_FIELD_SIZE: 160 bytes

    [8] DIGIT_RELAY: 2

*Apr 18 21:33:48.359: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_voice_get_capabilities:

*Apr 18 21:33:48.359: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_stop_service:

    NONE (0)

*Apr 18 21:33:48.359: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_open_service:

    G729IETF (25)

*Apr 18 21:33:48.359: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_voice_config_params:

The following lines show settings for encapsulation, packet suppression, and voice activity detection (VAD):

*Apr 18 21:33:48.359:     3 parameters

    [0] ENCAP RTP: Tx SSRC=64, Rx SSRC=0, Tx VPXCC=0, Rx VPXCC=0

    IFP payload type=122, SID support=19, TSE payload=101, Sequence number start=3238

    Redundancy=0, ClearChannel payload type=125, Fax payload type=122

    Alaw PCM switchover=8, MuLAW PCM switchover=0

    DTMF payload type=121, NTE receive payload type=101

    Dynamic payload=0, Codec=g729r8

    [1] PAK_SUPPRESS: 0

    [2] VAD: Enable=1, Threshold=-38(dBm)

.

.

.

*Apr 18 21:33:48.363: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_start_service:

    G729IETF (25)

*Apr 18 21:33:48.363: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_voice_config_params:

*Apr 18 21:33:48.363:     1 parameter

    [0] VAD: Enable=1, Threshold=-38(dBm)

*Apr 18 21:33:50.867: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_query_info:

    Request ID=1, Reset Flag=FALSE Q:PO_Delay PO_Error TX RX

.

.

.

*Apr 18 21:34:15.031: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_query_info:

    Request ID=5, Reset Flag=TRUE Q:Error

*Apr 18 21:34:15.031: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_query_info:

    Request ID=5, Reset Flag=TRUE Q:Levels

*Apr 18 21:34:15.031: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_inband_tone_off:

*Apr 18 21:34:15.031: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_stop_service:

    G729IETF (25)

*Apr 18 21:34:15.031: //66/CD89F6A78020/DSPAPI/[2/1:23:66]/dsp_close_service:

    G729IETF (25)

debug voip dspapi inout on the Terminating Gateway

Router# debug voip dspapi inout 

voip dspapi inout debugging is ON

*May  1 19:59:15.579: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_init:

*May  1 19:59:15.579: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_voice_config_params:

*May  1 19:59:15.579:     9 parameters

The following lines show encapsulation settings, jitter, inband signalling, echo cancellation, gain, and other quality of service (QoS) settings:

    [0] ENCAP RTP: Tx SSRC=0, Rx SSRC=0, Tx VPXCC=0, Rx VPXCC=0

    IFP payload type=122, SID support=1, TSE payload=101, Sequence number start=0

    Redundancy=0, ClearChannel payload type=0, Fax payload type=0

    Alaw PCM switchover=0, MuLAW PCM switchover=0

    DTMF payload type=0, NTE receive payload type=101

    Dynamic payload=0, Codec=g711ulaw

    [1] PO_JITTER: mode=2 initial=60(ms) max=200(ms) min=40(ms) fax_nom=300(ms)

    [2] INBAND_SIG: mode=0x1 enable

    [3] ECHO_CANCEL: Flags=0x17, Echo length=8(ms)

    [4] IDLE_CODE_DET: Enable=0, Code=0x0, Duration=6000(ms)

    [5] GAIN: Input=0.0(dB), Output=-6550.6(dB)

    [6] CNG: 1

    [7] INFO_FIELD_SIZE: 160 bytes

    [8] DIGIT_RELAY: 2

.

.

*May  1 19:59:15.587: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_voice_get_capabilities:

*May  1 19:59:15.587: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_stop_service:

    NONE (0)

*May  1 19:59:15.587: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_open_service:

    G729IETF (25)

*May  1 19:59:15.587: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_voice_config_params:

*May  1 19:59:15.587:     3 parameters

    [0] ENCAP RTP: Tx SSRC=0, Rx SSRC=0, Tx VPXCC=0, Rx VPXCC=0

    IFP payload type=122, SID support=19, TSE payload=101, Sequence number start=2977

    Redundancy=0, ClearChannel payload type=125, Fax payload type=122

    Alaw PCM switchover=8, MuLAW PCM switchover=0

    DTMF payload type=121, NTE receive payload type=101

    Dynamic payload=0, Codec=g729r8

    [1] PAK_SUPPRESS: 0

    [2] VAD: Enable=1, Threshold=-38(dBm)

*May  1 19:59:15.587: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_idle_service:

    G729IETF (25)

*May  1 19:59:15.587: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_voice_config_params:

*May  1 19:59:15.587:     3 parameters

    [0] INFO_FIELD_SIZE: 20 bytes

    [1] ENCAP RTP: Tx SSRC=64, Rx SSRC=0, Tx VPXCC=0, Rx VPXCC=0

    IFP payload type=122, SID support=19, TSE payload=101, Sequence number start=2977

    Redundancy=0, ClearChannel payload type=125, Fax payload type=122

    Alaw PCM switchover=8, MuLAW PCM switchover=0

    DTMF payload type=121, NTE receive payload type=101

    Dynamic payload=0, Codec=g729r8

    [2] DIGIT_RELAY: 2

*May  1 19:59:15.587: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_start_service:

    G729IETF (25)

*May  1 19:59:15.587: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_voice_config_params:

*May  1 19:59:15.587:     1 parameter

    [0] VAD: Enable=1, Threshold=-38(dBm)

*May  1 19:59:15.591: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_inband_tone_on:

    Tone ID=1, Direction=2, Num frequencies=2

    Frequency(hz): a=440 b=480, Amplitude(dB): a=-16.0 b=-16.0

    Cadence1(ms): ON=2000 OFF=4000

    Cadence2(ms): ON=0 OFF=0

    Cadence3(ms): ON=0 OFF=0

    Cadence4(ms): ON=0 OFF=0

    Frequency(hz): a2=25667 b2=51816 a3=0 b3=1 a4=24596 b4=52484

    ITO option group: 1

*May  1 19:59:17.195: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_inband_tone_off:

01:19:04: %PS-3-MULTFAIL: There is more than one failure with the  Power System 1; please 
resolve problems immediately

The following statistics repeat for each DSP query. The transmit (tx) and receive (rx) statistics show number of packets, comfort noise settings, duration, and packet status.

*May  1 19:59:18.051: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_query_info:

    Request ID=1, Reset Flag=FALSE Q:PO_Delay PO_Error TX RX

*May  1 19:59:18.051: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_tx_stats:

    Request ID=1, Packets: Voice=113, Signaling=0, ComfortNoise=1

    TX duration=2460(ms): Voice=2260(ms), FAX=0(ms)

*May  1 19:59:18.051: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_rx_stats:

    Request ID=1, Packets: Voice=33, Signalling=0, ComfortNoise=1

    RX duration=840(ms): Voice=640(ms), FAX=0(ms)

    Packets: Bad Sequence=0, Bad Protocol=0, Late=0, Early=1

*May  1 19:59:18.051: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_playout_delay_stats:

    Request ID=1, Current=70(ms), MIN=70(ms), MAX=70(ms)

    Clock offset=80(ms), Inter arrival jitter=67082420(ms)

*May  1 19:59:18.051: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_playout_error_stats:

    Request ID=1

    Concealment: Predictive=0(ms), Interpolative=0(ms), Silence=0(ms)

    Retro Memory update=0(ms), Buffer overflow=10(ms)

    Talkspurt endpoint detection errors=0

*May  1 19:59:19.827: //67/2BA0E0758024/DSPAPI/[4/0/0 (67)]/dsp_query_info:

    Request ID=1, Reset Flag=FALSE Q:PO_Delay PO_Error TX RX

.

.

.

debug voip dspapi Command Displaying T.38 fax Relay Statistics on the Terminating Gateway

This output shows the fax relay statistics.

Router# debug voip dspapi 

voip dspapi debugging is ON

.

.

.

May  7 21:32:16.472 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_playout_error_stats: 

    Request ID=1

    Concealment: Predictive=0(ms), Interpolative=0(ms), Silence=0(ms)

    Retro Memory update=0(na)(ms), Buffer overflow=0(ms)

    Talkspurt endpoint detection errors=0

May  7 21:32:18.996 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_query_info: 

    Request ID=1, Reset Flag=FALSE Q:PO_Delay PO_Error TX RX 

May  7 21:32:18.996 UTC: np_vsmgr_dispatch_voice_rsp(1/3): VOICE_LINK_INFO_RSP_NTF 
Received 

May  7 21:32:18.996 UTC: request_id = 0x01, request_type = 0x0F

May  7 21:32:18.996 UTC: VOICE_TRANSMIT_STATS(1/3): num_voice_packets 36 num_sig_packets 0 
num_cn_packets 1 transmit_duration AD2 end_point_detection 0

May  7 21:32:18.996 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_tx_stats: 

    Request ID=1, Packets: Voice=54, Signaling=0, ComfortNoise=1

    TX duration=2770(ms): Voice=0(ms), FAX=0(na)(ms)

May  7 21:32:18.996 UTC: VOICE_RECEIVE_STATS(1/3): num_voice_packets 20 num_sig_packets 0 
num_cn_packets 2 receive_duration AD2  voice_receive_duration 0 num_pos_packets 0 
num_bph_packets 0 num_late_packets 0 num_early_packets 0

May  7 21:32:18.996 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_rx_stats: 

    Request ID=1, Packets: Voice=32, Signalling=0, ComfortNoise=2

    RX duration=2770(ms): Voice=0(ms), FAX=0(na)(ms)

    Packets: Bad Sequence=0, Bad Protocol=0, Late=0, Early=0

May  7 21:32:18.996 UTC: VOICE_PLAYOUT_DELAY_STATS(1/3): curr_playout_delay 5A 
min_playout_delay 5A max_playout_delay 5A clock offset 2F07E72

May  7 21:32:19.000 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_playout_delay_stats: 

    Request ID=1, Current=90(ms), MIN=90(ms), MAX=90(ms)

    Clock offset=49315442(ms), Inter arrival jitter=0(na)(ms)

May  7 21:32:19.000 UTC: VOICE_PLAYOUT_ERROR(1/3): pred_conceal 0x0 inter_conceal 0x0 
silence_conceal 0x0 buffer_overflow 0x0 endpt_det_error 0x0

May  7 21:32:19.000 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_playout_error_stats: 

    Request ID=1

    Concealment: Predictive=0(ms), Interpolative=0(ms), Silence=0(ms)

    Retro Memory update=0(na)(ms), Buffer overflow=0(ms)

    Talkspurt endpoint detection errors=0

May  7 21:32:21.456 UTC: VOICE_DET_STATUS_CHANGE_NTF(1/3): detector mask: 1 timestamp 
51709BF8

May  7 21:32:21.456 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_voice_det_status_change: 

    Status=1, Timestamp=1366334456, Tone ID=0, Trigger=TRUE

May  7 21:32:21.464 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_voice_config_params:

May  7 21:32:21.464 UTC:     1 parameter 

    [0] PAK_SUPPRESS: 1

May  7 21:32:21.464 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_stop_service: 

    G729IETF (26)

May  7 21:32:21.464 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_config_params: 

    1 parameters

May  7 21:32:21.464 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_open_service: 

    FAX_RELAY (27)

May  7 21:32:21.464 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_is_call_pending: 

    Call is not PENDING

May  7 21:32:21.464 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_set_call_pending: 

    Set PENDING state

May  7 21:32:21.504 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_query_link_info: 

    Request ID=0, Group ID=1

May  7 21:32:21.504 UTC: vsm(1/3): np_vsmgr_voice_state_change() - state IDLE

May  7 21:32:21.504 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_is_call_pending: 

    Call is PENDING

May  7 21:32:21.504 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_reset_call_pending: 

    Reset PENDING state

May  7 21:32:21.504 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_call_status: 

    Status=PENDING_SUCCESS

May  7 21:32:21.504 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_config_params: 

    11 parameters

May  7 21:32:21.504 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_start_service: 

    FAX_RELAY (27)

May  7 21:32:21.504 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_set_call_pending: 

    Set PENDING state

May  7 21:32:22.556 UTC: vsm(1/3): np_vsmgr_voice_state_change() - state ACTIVE

May  7 21:32:22.556 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_is_call_pending: 

    Call is PENDING

May  7 21:32:22.556 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_reset_call_pending: 

    Reset PENDING state

May  7 21:32:22.556 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_call_status: 

    Status=PENDING_SUCCESS

May  7 21:32:22.564 UTC: FAX_RELAY_LINK_INFO_RSP_NTF: slot 1 port 3 timestamp 76082770 
fr-entered (20ms)

May  7 21:32:22.564 UTC: chan_id [3/1:D (8)] np_vsmgr_fax_relay_link_info_response:

May  7 21:32:29.712 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_t30_decode: 

    T30 msg : 0x4

May  7 21:32:30.436 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_t30_decode: 

    T30 msg : 0x2

May  7 21:32:30.784 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_t30_decode: 

    T30 msg : 0x1

May  7 21:32:33.936 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_t30_decode: 

    T30 msg : 0x42

May  7 21:32:34.280 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_t30_decode: 

    T30 msg : 0x41

May  7 21:32:39.676 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_t30_decode: 

    T30 msg : 0x21

May  7 21:32:39.676 UTC: np_fax_relay_t30_decode : Rx Direction

May  7 21:32:39.736 UTC: FARELAY_INIT_HS_MOD : 0x8

May  7 21:33:10.385 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_t30_decode: 

    T30 msg : 0x7D

May  7 21:33:13.073 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_t30_decode: 

    T30 msg : 0x31

May  7 21:33:15.217 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_t30_decode: 

    T30 msg : 0x5F

May  7 21:33:16.073 UTC: FAX_RELAY_DET_STATUS_CHANGE: slot: 1 port: 3 detector mask 0x2

May  7 21:33:16.073 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_det_status: 

    Status=2, Timestamp=716372818

May  7 21:33:16.073 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_query_info: 

    Request ID=5, Reset Flag=FALSE Q:FaxRelay 

May  7 21:33:16.097 UTC: FAX_RELAY_DATA_PUMP_STATS(1/3) - valid:0x3FFC1F55 state_code:0x1 
level:0x18 phase_jitter:0x0 freq_offset:0x0 eqm:0x7FFE jit_depth:0x38B jit_buf_ov:0x0 
tx_paks:0x5A rx_pkts:0x62C inv_pkts:0x0 oos_pkts:0x0 hs_mod:0x8 init_hs_mod:0x8 tx_pgs:0x0 
rx_pgs:0x1 ecm:0x1 nsf_country:0x0 nsf_manuf_len:0x20 
nsf_manuf:0031B8EE80C48511DD0D0000DDDD0000DDDD000000000000000022ED00B0A400 encap:0x1 
pkt_loss_con:0x0

May  7 21:33:16.097 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_fax_relay_stats: 

    Request ID=5, MAX jitter depth=907, MAX net RX qdepth=0(na)

    Jitter buffer overflow=0, Net RX qoverflow=0(na)

    Packets: TX=90 TX drops=0(na)

    Packets: RX=1580 RX loss=0(na), RX invalid=0, RX OOSequence=0

    HS modulation=8, Pages: TX=0 RX=1

    MAX TX In qdepth=0(na), MAX RX Out qdepth=0(na)

    MAX HS buffer usage=0(na), TX In qoverflow=0(na), RX Out qoverflow=0(na)

    FAX: State=1, level=24, Phase jitter=0, Frequency offset=0, EQM=32766

    Initial HS modulation=8, Fax Direction=2, ECM Enabled=1

    NSF Countery Code=0, nsf_manuf_code[32]=0031

    Encapsulation Protocol=1, Pkt Loss Conceal=0

May  7 21:33:16.101 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_stop_service: 

    FAX_RELAY (27)

May  7 21:33:16.101 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_open_service: 

    G729IETF (26)

May  7 21:33:16.101 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_is_call_pending: 

    Call is not PENDING

May  7 21:33:16.101 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_set_call_pending: 

    Set PENDING state

May  7 21:33:16.985 UTC: FAX_RELAY_LINK_INFO_RSP_NTF: slot 1 port 3 timestamp 76518179 
fr-end

May  7 21:33:17.001 UTC: vsm(1/3): np_vsmgr_voice_state_change() - state IDLE

May  7 21:33:17.001 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_is_call_pending: 

    Call is PENDING

May  7 21:33:17.001 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_reset_call_pending: 

    Reset PENDING state

May  7 21:33:17.001 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_call_status: 

    Status=PENDING_SUCCESS

May  7 21:33:17.001 UTC: //8/D6635DD58005/DSPAPI/[1/0:3]/dsp_voice_config_params:

May  7 21:33:17.001 UTC:     4 parameters 

.

.

.

Related Commands

Command	Description
debug voip hpi	Enables debugging for HPI message events.

debug voip dump-file-acct

To display debugging messages related to file accounting flushing processes, use the debug voip dump-file-acct command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip dump-file-acct

no debug voip dump-file-acct

Syntax Description

This command has no arguments or keywords.

Command Default

Debugging of file accounting processes is not enabled.

Command Modes

Privileged EXEC (#)

Command History

Release	Modification
12.4(15)XY	This command was introduced.
12.4(20)T	This command was integrated into Cisco IOS Release 12.4(20)T.

Usage Guidelines

This command displays event and error information about the file accounting processes for flushing the buffer and writing the call detail records (CDRs) to the file.

Examples

The following example displays output from the debug voip dump-file-acct command:

Router# debug voip dump-file-acct

 

*May 10 06:31:10.187: voice_file_acct_write:

*May 10 06:31:10.187: file_acct_write_local: file accounting buffer overflow,dumping to file

*May 10 06:31:10.187: voice_file_acct_initiate_dump_to_file: ctx_id=2, url=flash:cdr

*May 10 06:31:10.187: voice_file_acct_lock, ctx_id=2, refcnt=2

*May 10 06:31:10.187: create_file_acct_buffer: buffer of 63k created from chunk 0x46B5F474

*May 10 06:31:10.187: file_acct_write_local: message (len=640) written to file_acct:

30080 bytes left

*May 10 06:31:10.191: handle_file_acct_dump_request

*May 10 06:31:10.191: handle_file_acct_dump_request: pick up dump request (ctx_id=2)

*May 10 06:31:10.191: open_file_acct_dump_file: url=flash:cdr_ragdenCME1_05_10_2007_06_30_28.191 < == shows url.

*May 10 06:31:10.215: Secondary mode file acct is successful

*May 10 06:31:10.215: handle_file_acct_dump_request :to_write is 29748 <== shows how much is written to.

*May 10 06:31:10.219: : File accounting,write successful to file

*May 10 06:31:10.219: handle_file_acct_dump_request :to_write is 640

*May 10 06:31:10.219: : File accounting,write successful to file

*May 10 06:31:10.323: voice_file_acct_unlock, ctx_id=2 refcnt=1

Related Commands

Command	Description
debug voip fileacct	Displays debugging messages related to generating attributes for file accounting.
gw-accounting	Enables an accounting method for collecting CDRs.
primary	Sets the primary location for storing the CDRs generated for file accounting.

debug voip eddri

To turn on debugging for the event dispatcher and data repository interface (EDDRI), use the debug voip eddri command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip eddri {event | timers | prefix | all}

no debug voip eddri {event | timers | prefix | all}

Syntax Description

event	Turns on debugging for EDDRI events.
timers	Turns on debugging for EDDRI timers.
prefix	Turns on debugging for the prefix database.
all	Turns on debugging all EDDRI activities.

Defaults

Disabled

Command Modes

Privileged EXEC

Command History

Release	Modification
12.3(1)	This command was introduced.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

There is always a performance penalty when using debug commands.

The EDDRI notifies TGREP when an attribute changes on some subsystems. EDDRI interacts with the dial peer subsystem, the trunk group subsystems, call control API (CCAPI) subsystem and the customer relationship management (CRM) subsystem to notify changes in particular attributes. EDDRI is responsible for creating the prefix database.

Examples

The following example shows sample output from the debug voip eddri command:

21:00:53: eddri_interesting_ac_pt: new AC_curr 22 FD_curr -5 SD_curr -5

21:00:53: eddri_interesting_ac_pt: percent trigger diff 4

21:00:53: eddri_interesting_ac_pt: Interesting Point 

21:00:53: eddri_send_prefix_event_to_clients : reason 0x40 num_prefix 1

With the send prefix event the available circuits value and the triggers for reporting are updated.

21:00:53: eddri_send_prefix_event_to_clients attr 0xFF ev_id 1 qid 0x64209230 reason 0x40

eddri_dequeue_event : dequeue event 

21:00:53: eddri_interesting_ac_pt : tc 23 IAC 22 lwm 5 hwm 50 pct_trigger 2 oneMinusW 933

21:00:53: eddri_interesting_ac_pt: old AC_curr 23 FD_curr 0 SD_curr 0

21:00:53: eddri_interesting_ac_pt: new AC_curr 22 FD_curr -5 SD_curr -5

21:00:53: eddri_interesting_ac_pt: percent trigger diff 4

21:00:53: eddri_interesting_ac_pt: Interesting Point 

21:00:53: eddri_send_prefix_event_to_clients : reason 0x40 num_prefix 1

Related Commands

Command	Description
debug tgrep error	Turns on debugging for any errors in functioning.
debug tgrep events	Turns on debugging for main events occurring throughout the subsystem.
debug tgrep fsm	Turns on debugging for FSM activity.
debug tgrep io	Turns on debugging for detailed socket level activities.
debug tgrep messages	Turns on debugging for the movement of TGREP messages.
debug tgrep msgdump	Turns on debugging for the dump of the details of TGREP messages.
debug tgrep timer-event	Turns on debugging for events that are related to the timer.
debug tgrep timers	Turns on debugging for timer activity.
debug tgrep tripr	Turns on debugging for the TRIP Reporter.
show voice eddri prefix	Shows applicable prefixes for the EDDRI.

debug voip enum

To view Voice over IP (VoIP) telephone number mapping (ENUM) information, use the debug voip enum command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip enum {detail | summary}

no debug voip enum {detail | summary}

Syntax Description

detail	Displays detailed output.
summary	Displays summary output.

Defaults

Disabled

Command Modes

Privileged EXEC

Command History

Release	Modification
12.2(11)T	This command was introduced.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

Disable console logging and use buffered logging before using the debug voip enum command. Using the debug voip enum command generates a large volume of debugs, which can affect router performance.

Examples

The following shows sample output from the debug voip enum detail command:

The output shows the match number as 5108891234, enum table as 10. Rule 1 in table 10 matched the pattern and after applying the replacement rule, the resulting string is 5108891234. The enum query is sent out for the domain 4.3.2.1.9.8.8.0.1.5.e164.cisco.com. The output then shows the matching Naming Authority Pointer (NAPTR) records obtained in the response. The records are then processed and the final URLs (contact lists) are shown toward the end.

Router# debug voip enum detail

enum_resolve_domain:match_num 5108891234 table_indx 10

enum_resolve_domain:rule 1 result string 5108891234

generate_enum_search_string :search string 4.3.2.1.9.8.8.0.1.5.e164.cisco.com

enum_dns_query:name = 4.3.2.1.9.8.8.0.1.5.e164.cisco.com type = 35, ns_server = 0

order 100 pref 10 service sip+E2U flag U

regexp /^.*$/sip:5108891234@1.8.50.14/ replacement 

order 200 pref 10 service h323+E2U flag U

regexp /^.*$/h323:5555@1.5.1.1/ replacement 

num_elem = 2

NAPTR Record :order 100 pref 10 service sip+E2U

               flags U regexp /^.*$/sip:5108891234@1.8.50.14/

               replacement 

NAPTR Record :order 200 pref 10 service h323+E2U

               flags U regexp /^.*$/h323:5555@1.5.1.1/

               replacement 

decode_naptr_record :re_string ^.*$

decode_naptr_record :re_substitution_string sip:5108891234@1.8.50.14

decode_naptr_record :re_flags_string 

U_FLAG case, stopping query

new_e164_user sip:5108891234@1.8.50.14

decode_naptr_record :re_string ^.*$

decode_naptr_re

tahoe13#cord :re_substitution_string h323:5555@1.5.1.1

decode_naptr_record :re_flags_string 

U_FLAG case, stopping query

new_e164_user h323:5555@1.5.1.1

contact_list :

              sip:5108891234@1.8.50.14

contact_list :

              h323:5555@1.5.1.1

enum_resolve_domain:contact_list 64558450

A sample output of the debug voip enum summary command is shown below.

The output shows the matching number, the enum table used and the rule in the table that matched the number along with the resulting string. Note that this output is a subset of the output from debug voip enum detail command.

Router# debug voip enum summary

enum_resolve_domain:match_num 5108891234 table_indx 10

enum_resolve_domain:rule 1 result string 5108891234

Table 374 provides an alphabetical listing of the debug voip enum command fields and a description of each field.

Table 374 debug voip enum Field Descriptions 

Field	Description
contact_list	Final list of URLs that the gateway will try to contact as an attempt to place the call.
flag	Flag value of a NAPTR record as defined in RFC 2915.
match_num	Number to be used for matching against the enum match table.
name	Fully qualified domain name sent out to Domain Name System (DNS) server
ns_server	Address of the DNS server. If 0, the DNS server configured on the gateway is used.
num_elem	Number of records received in the response.
order	Order in the record, as defined in RFC 2915.
pref	Preference of the record, as defined in RFC 2915.
regexp	Regular expression of the record, as defined in RFC 2915.
replacement	Replacement string of the record, as defined in RFC 2915.
re_flags_string	Flag indicating whether matching and replacement should be case sensitive: •i = Case insensitive •otherwise = Case sensitive
re_string	The first part of the regexp, delimited by "/". This is used to match the incoming string. Refer to RFC 2915.
re_substitution_string	The second part of regexp, delimited by "/".
result string	String that results when match_num is taken through the enum match table for a match. This string will be used to form a FQDN.
rule	Rule number that matched match_num in the enum match table.
search string	String sent out to the DNS server.
service	Service field of the NAPTR record. Refer to RFC 2915.
table_indx	Index of the enum match table picked for this call.
type	Type of record requested in the query: 35 = NAPTR 33 = DNS Service (SRV)

Related Commands

Command	Description
rule (ENUM configuration)	Defines the rule pattern for an ENUM match table.
show voice enum-match-table	Displays the ENUM match table rules.
test enum	Tests the ENUM match table rules.
voice enum-match-table	Initiates the ENUM match table definition.

debug voip event-log

To enable debugging of the event log module, use the debug voip event-log command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip event-log

no debug voip event-log

Syntax Description

This command has no arguments or keywords.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.3(8)T	This command was introduced.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Examples

The following is sample output from the debug voip event-log command:

Router# debug voip event-log 

voip event-log debugging is on

Router#

*Jul 18 22:22:45.417: voice_elog_open: ctx_id=1F, size=4, url=

*Jul 18 22:22:45.417: lock_elog, ctx_id=1F, refcnt=1

*Jul 18 22:22:45.417: voice_elog_write:

*Jul 18 22:22:45.417: elog_write_local: message (len=143) written to elog:

1F:1058566965:584:INFO: Call setup indication received, called = 4085550198,  calling = 
52927, echo canceller = enable, direct inward dialing 3953 bytes left

*Jul 18 22:22:45.417: voice_elog_write:

*Jul 18 22:22:45.417: elog_write_local: message (len=38) written to elog:

1F:1058566965:585:INFO: Dialpeer = 1 3915 bytes left

*Jul 18 22:22:45.421: voice_elog_open: ctx_id=B, size=4, url=

*Jul 18 22:22:45.421: lock_elog, ctx_id=B, refcnt=1

*Jul 18 22:22:45.421: voice_elog_write:

*Jul 18 22:22:45.421: elog_write_local: message (len=114) written to elog:

B:1058566965:586:INFO: Session started for App-type = generic, URL = 
tftp://demo/scripts/master/generic.vxml 3982 bytes left

*Jul 18 22:22:45.421: voice_elog_write:

*Jul 18 22:22:45.421: elog_write_local: message (len=69) written to elog:

B:1058566965:587:INFO: Incoming Telephony call received, LegID = 1F 3913 bytes left

*Jul 18 22:22:45.421: voice_elog_write:

*Jul 18 22:22:45.421: elog_write_local: message (len=89) written to elog:

B:1058566965:588:INFO: LegID = 1F: Calling = 4085550198, called = 52927, dial peer = 1 
3824 bytes left

*Jul 18 22:22:45.421: voice_elog_write:

*Jul 18 22:22:45.421: elog_write_local: message (len=66) written to elog:

B:1058566965:589:INFO: LegID = 1F: Leg State = LEG_INCCONNECTED 3758 bytes left

*Jul 18 22:22:45.433: voice_elog_write:

*Jul 18 22:22:45.437: elog_write_local: message (len=42) written to elog:

1F:1058566965:590:INFO: Digit collection 3873 bytes left

*Jul 18 22:22:45.437: voice_elog_write:

*Jul 18 22:22:45.437: elog_write_local: message (len=57) written to elog:

1F:1058566965:591:INFO: Call connected using codec None 3816 bytes left

*Jul 18 22:22:45.437: voice_elog_write:

*Jul 18 22:22:45.437: elog_write_local: message (len=85) written to elog:

B:1058566965:592:INFO: Playing prompt #1: tftp://172.19.139.245/audio/ch_welcome.au 3673 
bytes left

Router#

*Jul 18 22:22:55.942: voice_elog_write:

*Jul 18 22:22:55.942: elog_write_local: message (len=51) written to elog:

B:1058566975:593:ERR : Prompt play setup failure.  3622 bytes left

*Jul 18 22:22:55.942: voice_elog_write:

*Jul 18 22:22:55.942: elog_write_local: message (len=65) written to elog:

B:1058566975:594:INFO: Script received event = "error.badfetch" 3557 bytes left

*Jul 18 22:22:56.918: voice_elog_write:

*Jul 18 22:22:56.918: elog_write_local: message (len=98) written to elog: 1F:1058

Router#

566976:595:INFO: Inform application call disconnected (cause = normal call clearing (16)) 
3718 bytes left

*Jul 18 22:22:56.918: voice_elog_write:

*Jul 18 22:22:56.918: elog_write_local: message (len=78) written to elog:

B:1058566976:596:INFO: Script received event = "telephone.disconnect.hangup"

 3479 bytes left

*Jul 18 22:22:56.922: voice_elog_write:

*Jul 18 22:22:56.922: elog_write_local: message (len=89) written to elog:

B:1058566976:597:INFO: LegID = 1F: Call disconnected, cause = normal call clearing (16) 
3390 bytes left

*Jul 18 22:22:56.922: voice_elog_write:

*Jul 18 22:22:56.922: elog_write_local: message (len=79) written to elog:

1F:1058566976:598:INFO: Call disconnected (cause = normal call clearing (16))

 3639 bytes left

*Jul 18 22:22:56.930: voice_elog_write:

*Jul 18 22:22:56.930: elog_write_local: message (len=39) written to elog:

1F:1058566976:599:INFO: Call released

 3600 bytes left

*Jul 18 22:22:56.930: voice_elog_close, ctx_id=1F voice_elog_close, ctx_id=19

*Jul 18 22:22:56.930: unlock_elog, ctx_id=19, refcnt=0

*Jul 18 22:22:56.930: delete_elog, ctx_id=19

*Jul 18 22:22:56.930: voice_elog_write:

*Jul 18 22:22:56.930: elog_write_local: message (len=59) written to elog:

B:1058566976:600:INFO: Session done, terminating cause = 3331 bytes left

*Jul 18 22:22:56.930: voice_elog_close, ctx_id=B

Related Commands

Command	Description
call application event-log	Enables event logging for voice application instances.
debug voip ais	Enables debugging of the AIS database.

debug voip fastpath

To turn on debugging to monitor VoIP fastpath activity, use the debug voip fastpath command in privileged EXEC mode. To turn off VoIP fastpath debugging, use the no form of this command.

debug voip fastpath [invalidate] [slot/port]

no debug voip fastpath [invalidate] [slot/port]

Syntax Description

invalidate	(Optional) Turns on debugging for VoIP fastpath cache invalidation.
slot/port	(Optional) Slot and port to be debugged. Slash mark is required.

Command Default

VoIP fastpath debugging does not occur.

Command Modes

Privileged EXEC (#)

Command History

Release	Modification
12.4(21)	This command was introduced on the Cisco AS5400XM and AS5350XM.

Usage Guidelines

The debug voip fastpath command displays the details on every packet that is being switched via fastpath. The debug voip fastpath invalidate command displays the details of cache invalidation and cache update. The debug voip fastpath command and its options are interchangeable with the debug voice fastpath command.

VoIP fastpath is enabled by default. In order to disable it, issue the no voip-fastpath enable command in global configuration mode.

When VoIP fastpath is enabled, the IP address and User Datagram Protocol (UDP) port number information for the logical channel that is opened for a specific call are cached. VoIP fastpath prevents the RTP stream from reaching the application layer. Instead, the packets are forwarded at a lower layer to help reduce CPU utilization in high call-volume scenarios.

When supplementary services such as hold or transfer are used, VoIP fastpath causes the router to stream the audio to the cached IP address and UDP port. The new logical channel information (generated after a call on hold is resumed or after a transfer is completed) is disregarded. Traffic must go to the application layer constantly so that redefinition of the logical channel is taken into account and audio is streamed to the new IP address and UDP port pair. Therefore, be sure to disable VoIP fastpath in order to support supplementary services.

---

Note The debug voip fastpath command should be enabled only when there is light traffic on the gateway. Enabling this command can affect the functionality of the gateway.

---

Examples

The following example shows how to turn on VoIP fastpath debugging, shows how to use the show debug command to display what debugging functions are enabled, and provides sample output for the debugging function:

Router# debug voip fastpath

Fastpath related debugging is on

Router# show debug

 fastpath: 

  Fastpath related debugging is on 

Router# 

*Nov 14 08:22:35.971: NP VPD(2/01): pak sent via fastpath,part=0x652DEE80 ret=0x000003 
len=32

*Nov 14 08:22:35.987: NP VPD(2/01): pak sent via fastpath,part=0x652DEEC0 ret=0x000003 
len=32

*Nov 14 08:22:36.011: NP VPD(2/01): pak sent via fastpath,part=0x652DEF00 ret=0x000003 
len=32

*Nov 14 08:22:36.031: NP VPD(2/01): pak sent via fastpath,part=0x652DEF40 ret=0x000003 
len=32

*Nov 14 08:22:36.051: NP VPD(2/01): pak sent via fastpath,part=0x652DEF80 ret=0x000003 
len=32

*Nov 14 08:22:36.071: NP VPD(2/01): pak sent via fastpath,part=0x652DEFC0 ret=0x000003 
len=32

*Nov 14 08:22:36.095: NP VPD(2/01): pak sent via fastpath,part=0x652DF000 ret=0x000003 
len=32

*Nov 14 08:22:36.111: NP VPD(2/01): pak sent via fastpath,part=0x652DF040 ret=0x000003 
len=32

*Nov 14 08:22:36.131: NP VPD(2/01): pak sent via fastpath,part=0x652DF080 ret=0x000003 
len=32

*Nov 14 08:22:36.151: NP VPD(2/01): pak sent via fastpath,part=0x652DF0C0 ret=0x000003 
len=32

*Nov 14 08:22:36.171: NP VPD(2/01): pak sent via fastpath,part=0x652DF100 ret=0x000003 
len=32

*Nov 14 08:22:36.195: NP VPD(2/01): pak sent via fastpath,part=0x652DF140 ret=0x000003 
len=32

*Nov 14 08:22:36.207: NP VPD(2/01): pak sent via fastpath,part=0x652DF180 ret=0x000003 
len=32

*Nov 14 08:22:36.231: NP VPD(2/01): pak sent via fastpath,part=0x652DF1C0 ret=0x000003 
len=32 

*Nov 14 08:22:36.251: NP VPD(2/01): pak sent via fastpath,part=0x652DF200 ret=0x000003 
len=32

*Nov 14 08:22:36.271: NP VPD(2/01): pak sent via fastpath,part=0x652DF240 ret=0x000003 
len=32

*Nov 14 08:22:36.291: NP VPD(2/01): pak sent via fastpath,part=0x652DF280 ret=0x000003 
len=32

*Nov 14 08:22:36.315: NP VPD(2/01): pak sent via fastpath,part=0x652DF2C0 ret=0x000003 
len=32

*Nov 14 08:22:36.331: NP VPD(2/01): pak sent via fastpath,part=0x652DF300 ret=0x000003 
len=32

*Nov 14 08:22:36.351: NP VPD(2/01): pak sent via fastpath,part=0x652DF340 ret=0x000003 
len=32

*Nov 14 08:22:36.371: NP VPD(2/01): pak sent via fastpath,part=0x652DF380 ret=0x000003 
len=32

*Nov 14 08:22:36.391: NP VPD(2/01): pak sent via fastpath,part=0x652DF3C0 ret=0x000003 
len=32

The following example shows how to use the debug voip fastpath slot/port command to debug slot 2, port 13 on the router:

Router# debug voip fastpath 2/013

Fastpath related debugging is on 

*Nov 14 08:28:00.623: NP VPD(2/13): pak sent via fastpath,part=0x652DFFC0 ret=0x000003 
len=32 

*Nov 14 08:28:00.643: NP VPD(2/13): pak sent via fastpath,part=0x652E0000 ret=0x000003 
len=32 

*Nov 14 08:28:00.659: NP VPD(2/13): pak sent via fastpath,part=0x652E0080 ret=0x000003 
len=32 

*Nov 14 08:28:00.831: NP VPD(2/13): pak sent via fastpath,part=0x652E0280 ret=0x000003 
len=32 

*Nov 14 08:28:00.855: NP VPD(2/13): pak sent via fastpath,part=0x652E0300 ret=0x000003 
len=32 

*Nov 14 08:28:00.867: NP VPD(2/13): pak sent via fastpath,part=0x652E0380 ret=0x000003 
len=32 

*Nov 14 08:28:01.031: NP VPD(2/13): pak sent via fastpath,part=0x652E0540 ret=0x000003 
len=32 

*Nov 14 08:28:01.051: NP VPD(2/13): pak sent via fastpath,part=0x652E0580 ret=0x000003 
len=32 

*Nov 14 08:28:01.075: NP VPD(2/13): pak sent via fastpath,part=0x652E0640 ret=0x000003 
len=32 

*Nov 14 08:28:01.231: NP VPD(2/13): pak sent via fastpath,part=0x652E0840 ret=0x000003 
len=32 

*Nov 14 08:28:01.251: NP VPD(2/13): pak sent via fastpath,part=0x652E07C0 ret=0x000003 
len=32 

*Nov 14 08:28:01.271: NP VPD(2/13): pak sent via fastpath,part=0x652E0900 ret=0x000003 
len=32 

*Nov 14 08:28:01.439: NP VPD(2/13): pak sent via fastpath,part=0x652E0AC0 ret=0x000003 
len=32 

*Nov 14 08:28:01.463: NP VPD(2/13): pak sent via fastpath,part=0x652E0B40 ret=0x000003 
len=32 

*Nov 14 08:28:01.483: NP VPD(2/13): pak sent via fastpath,part=0x652E0BC0 ret=0x000003 
len=32 

The following example shows how to enable debugging for fastpath cache invalidation on slot 2, port 17, and shows how to display sample output for the debugging function:

Router# debug voip fastpath invalidate 2/17 

 Fastpath cache invalidation related  debugging is on

Router# show voip call summary

PORT           CODEC    VAD VTSP STATE            VPM STATE

============== ======== === ==================== ======================

6/4:0.20        g729r8    y  S_CONNECT             CSM_OC6_CONNECTED       

6/4:0.21        g729r8    y  S_CONNECT             CSM_OC6_CONNECTED       

Router# show spe | i a

Country code config : default T1 (u Law)

Country code setting: e1-default

Port state: (s)shutdown   (r)recovery  (t)test  (a)active call

            (b)busiedout  (d)download  (B)bad   (p)busyout pending

Call type : (m)modem      (d)digital   (v)voice (f)fax-relay       (_)not in use

Summary   :

Ports     : Total  540  In-use     2  Free   514  Disabled    24

Calls     : Modem    0  Digital    0  Voice    2  Fax-relay    0

                     SPE          SPE     SPE  SPE   Port         Call

SPE#    Port #       State        Busyout Shut Crash State        Type

2/02    0012-0017    ACTIVE             0    0     0 _____a       _____v      

2/03    0018-0023    ACTIVE             0    0     0 a_____       v_____      

Router# show logging 

Syslog logging: enabled (274 messages dropped, 20 messages rate-limited,

                0 flushes, 0 overruns, xml disabled, filtering disabled)

    Console logging: disabled

    Monitor logging: level debugging, 0 messages logged, xml disabled,

                     filtering disabled

    Buffer logging: level debugging, 1018 messages logged, xml disabled,

                    filtering disabled

    Logging Exception size (8192 bytes)

    Count and timestamp logging messages: disabled

    Trap logging: level informational, 133 message lines logged

Log Buffer (1000000 bytes):

*Nov 14 08:40:36.499:  NP VPD (2/17): Cached header parameter values: header size : 28, 
payload size : 13, ssrc : 0x24DB1F03, udp chksum : 0x0

*Nov 14 08:40:36.499:  NP VPD (2/17): Cached IP/UDP pkt details: dest-ip: 31.31.31.1   
src-ip: 31.31.31.3   dport: 0x4070  sport: 0x43A6

*Nov 14 08:40:40.851:  NP VPD (2/17): Cached header parameter values: header size : 28, 
payload size : 32, ssrc : 0x24DB1F03, udp chksum : 0x0

*Nov 14 08:40:40.851:  NP VPD (2/17): Cached IP/UDP pkt details: dest-ip: 31.31.31.1   
src-ip: 31.31.31.3   dport: 0x4070  sport: 0x43A6

*Nov 14 08:40:40.939:  NP VPD (2/17): Cache being cleared due to change in payload size 
old payload size : 32  new rx payload size : 13 cached ssrc : 24DB1F03

Related Commands

Command	Description
debug voice fastpath	Turns on debugging to monitor voice fastpath packets.
show voice call	Displays the call status information for voice ports.
voice fastpath enable	Turns on voice fastpath.

debug voip fileacct

To display debugging messages related to voice attributes for file accounting, use the debug voip fileacct command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip fileacct

no debug voip fileacct

Syntax Description

This command has no arguments or keywords.

Command Default

Debugging of file accounting is not enabled.

Command Modes

Privileged EXEC (#)

Command History

Release	Modification
12.4(15)XY	This command was introduced.
12.4(20)T	This command was integrated into Cisco IOS Release 12.4(20)T.

Usage Guidelines

This command displays details about the attributes captured in call detail records (CDRs) and their values for the file accounting feature.

Examples

The following example displays output from the debug voip fileacct command:

Router# debug voip fileacct

 

*May 10 06:27:43.719: : add attr:47A815E4 clid(21) 4 5000

*May 10 06:27:43.719: new list: 0x4792614C prev list: 0x47A815D0

*May 10 06:27:43.719: : add attr:47926160 dnis(22) 0

*May 10 06:27:43.719: new list: 0x47C3A2C0 prev list: 0x4792614C

*May 10 06:27:43.719: : add attr:47C3A2D4 subscriber(106) 11 RegularLine

*May 10 06:27:43.719: new list: 0x4517FC04 prev list: 0x47C3A2C0

*May 10 06:27:43.719: : add attr:4517FC18 override_session_time(67) 4 0(0) Telephony Leg

*May 10 06:27:43.719: new list: 0x478C0CA4 prev list: 0x4517FC04

*May 10 06:27:43.719: : add attr:478C0CB8 h323-ivr-out(68) 14 Tariff:Unknown

*May 10 06:27:43.719: new list: 0x477EAFFC prev list: 0x478C0CA4

*May 10 06:27:43.719: : add attr:477EB010 h323-voice-quality(70) 1 0

*May 10 06:27:43.719: new list: 0x4783EF80 prev list: 0x477EAFFC

*May 10 06:27:43.719: : add attr:4783EF94 gw-rxd-cgn(94) 28 ton:0,npi:0,pi:0,si:0,#:5000

*May 10 06:27:43.719: list is 466C17A8, list->featurename is 0,feat id is 11205

*May 10 06:27:43.719: fcur is 466C17A8, attr is "TWC","05/10/2007 06:27:43.695","5000","",0,11205,6510EBF8 FDF611DB A527DA52 74E8B890,2BD8,"","","",""

*May 10 06:27:43.719: : del attr47B8E814 callID(1) 4 11224(2BD8)

*May 10 06:27:43.719: : del attr45250054 cdr type(2) 4 0(0)

*May 10 06:27:43.719: : del attr452C52F0 leg type(3) 4 1(1)

*May 10 06:27:43.719: : del attr47914064 h323-conf-id(4) 35 6510EBF8 FDF611DB A527DA52 74E8B890

Related Commands

Command	Description
debug voip dump-file-acct	Displays debugging messages related to file accounting flushing processes.
gw-accounting	Enables an accounting method for collecting CDRs.
primary	Sets the primary location for storing the CDRs generated for file accounting.

debug voip h221

To debug telephony call control information, use the debug voip h221 command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip h221 [all | default | error [call [informational] | software [informational]] | function | individual | inout | raw [decode]]

no debug voip h221

Syntax Description

all	(Optional) Enables all H.221 debugging, except the raw option.
default	(Optional) Activates function, inout, error call, and software debugging.
error	(Optional) Enables H.221 call error and software error debugging.
error [call]	(Optional) Enables H.221 major call processing error debugs related to the H.221 subsystem.
error [call [informational]]	(Optional) Enables H.221 major and informational call processing error debugs related to the H.221 subsystem.
error [software]	(Optional) Enables H.221 major software error debugs related to the H.221 subsystem.
error [software [informational]]	(Optional) Enables H.221 major and informational software error debugs related to the H.221 subsystem.
function	(Optional) Enables procedure tracing.
individual	(Optional) Activates individual H.221 debugging.
inout	(Optional) Enables subsystem inout debugging.
raw	(Optional) Displays raw BAS messages.
raw [decode]	(Optional) Decodes raw BAS data.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.4(11)T	This command was introduced.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

This command enables debugging for H.221 message events (voice telephony call control information).

---

Note This command provides the same results as the debug voice h221 command.

---

---

Caution We recommend that you log the output from the debug voip h221 all command to a buffer, rather than sending the output to the console; otherwise, the size of the output could severely impact the performance of the gateway.

---

Use the debug voip h221 individual x command, (where x is an index number for a debug category), to activate a single debug, selected by index number instead of entering a group of debug commands. See Table 375 for a list of debug categories and corresponding index numbers.

Table 375 Indexes and Categories for the debug voip h221 individual command

Index Number	Debug Category
1, 2, 30, 31, 32	Secondary number exchange
5, 6, 14, 15, 16, 22	Audio mode/caps
7, 10, 12, 13, 17, 28	Video mode/caps
8, 9, 23	B-channel mode/caps
11, 24, 33	Miscellaneous command exchange
18	Bandwidth calculations
19, 20, 21	DSP configuration
3, 4, 25, 27, 42, 43	General caps/internal
26	Non-standard caps/command
29	Loop request
34, 35, 36, 37, 38, 39, 40, 41	BAS squelch

Examples

The raw keyword displays the raw BAS information coming from or to the DSP. It is displayed in a hexadecimal octet format. The decode option decodes the BAS information into a readable English format.

The following is sample output from the debug voip h221 raw decode command:

  BAS=81:1 0 0 0 0 0 0 1: AUDIO CAPS=g711 a-law

  BAS=82:1 0 0 0 0 0 1 0: AUDIO CAPS=g711 u-law

  BAS=84:1 0 0 0 0 1 0 0: AUDIO CAPS=g722 48k

  BAS=85:1 0 0 0 0 1 0 1: AUDIO CAPS=g728

  BAS=F9:1 1 1 1 1 0 0 1: H.242 MBE start indication

  BAS=02:0 0 0 0 0 0 1 0: H.242 MBE length=2

  BAS=0A:0 0 0 0 1 0 1 0: H.242 MBE type=H.263 caps

  BAS=8A:1 - - - - - - -: Always 1

  BAS=8A:- 0 0 0 1 - - -: H.263 MPI=1

  BAS=8A:- - - - - 0 1 -: H.263 FORMAT=h.263_cif

  BAS=8A:- - - - - - - 0: No additional options

Related Commands

Command	Description
debug voip ccapi	Enables debugging for the call control application programming interface (CCAPI) contents.
debug voip rtp	Enables debugging for Real-Time Transport Protocol (RTP) named event packets.

debug voip h324

To debug video call control information, use the debug voip h324 command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip h324 [all | function | inout | default | individual [number] | message | error [software [informational] | call [informational]]]

Syntax Description

all	(Optional) Enables all H.324 debugging except raw and raw decode.
default	(Optional) Activates function, inout, error call, and software debugging.
error	(Optional) Enables H.324 call error and software error debugging.
error [call]	(Optional) Enables H.324 major call processing error debugs related to the H.324 subsystem.
error [call [informational]]	(Optional) Enables H.324 major and informational call processing error debugs related to the H.324 subsystem.
error [software]	(Optional) Enables H.324 major software error debugs related to the H.324 subsystem.
error [software [informational]]	(Optional) Enables H.324 major and informational software error debugs related to the H.324 subsystem.
function	(Optional) Enables procedure tracing.
individual	(Optional) Activates individual H.324 debugging.
inout	(Optional) Enables subsystem inout debugging.
message	(Optional) Enables H.245 message display to/from H.324. Only displays message types, for message detail, use debug h245 asn1.
number	Index number. Number of debug category. See

Command Modes

Privileged EXEC (#)

Command History

Release	Modification
12.4(22)T	This command was introduced.

Usage Guidelines

This command enables debugging for H.324 message events (video call control information).

---

Note This command is the same as the debug voice h324 command.

---

---

Caution We recommend that you log the output from the debug voip h324 all command to a buffer, rather than sending the output to the console; otherwise, the size of the output could severely impact the performance of the gateway.

---

Use the debug voip h324 individual index-number command, where index number is a debug category, to activate a single debug.

This is helpful when trying to see a specific problem, without having a large number of debug output being generated. For example, the user could select the command debug voip h324 individual 4 to see calls where no video caps arrived from the IP side of the call (SIP to H.324 direction). Multiple debug output can be activated using this command, one at a time. These are not additional debug output to the ones enabled by the command debug voip h324 all, just another way to selectively see specific information, without generating large amounts of debug output. See Table 376 for a list of debug categories and corresponding index numbers.

Table 376 Index Numbers and Descriptions for the debug voip h324 Command

Index Number	Description
1	Shows incoming H.245 message type
2	Shows MSD master/slave determination upon receiving MSD from peer
3	Warns that no audio caps were found from IP leg (not necessarily an error).
4	Warns that no video caps were found from IP leg (not necessarily an error).
5	Shows MSD master/slave determination when sending MSDack.
6	Displays media type being sent (audio/video), when sending MES message.
7	Displays H.223 parameters when sending TCS.
8	Displays OLC information, when sending audio OLC.
9	Displays OLC information, when sending video OLC.
10	Displays OLCack information, when sending OLCack.
11	Displays OLCrej information, when sending OLCrej.
12	Displays digit begin sent, when sending USER INPUT message.
13-15	Displays internal status bits of h245 messages sent/received in the h324 subsystem. No user data is provided.
16	Displays master/slave determination when MSDack is received.
17	Displays media type when MESack is received.
18	Displays media type when MESrej is received.
19	Displays OLC information, when receiving audio OLC.
20	Displays OLC information, when receiving video OLC.
21	Displays media type when OLCack is received.
22	Displays media type when OLCrej is received.
23	Displays message type, when an H.245 miscellaneous message is received (for example FastVideoUpdate).
24	Displays digit being received, when receiving USER INPUT message.
25	Displays message type, when an H.245 miscellaneous message is sent (for example FastVideoUpdate).
26	Displays outgoing message command type. No user data is provided with this debug.
27	Displays the initial H.223 mux level received from the peer, reported by the DSP.
28	Displays information about either OLCack or OLCrej being sent in response to an OLC request.
29	Displays the audio codec being opened with the IP leg.
30	Displays the video codec being opened with the IP leg. Should always be the same as the video codec with the H.324 leg.
31	Displays when Cisco IOS is sending the DSP either the H.223 multiplex table, or AL information. No user data is provided.
32	Indicates the digit being sent to the IP leg, through the RFC 2833 procedure.
33-34	Displays the parameters being sent to the DSP to configure either audio or video.
35	Displays information about the H.223 multiplex table being sent to the DSP.
36	Displays information about the H.223 AL configuration being sent to the DSP.
37-38	Indicates message arriving from IP leg. No user data is provided.
39	Displays information when receiving VENDOR ID message. This may show the type of equipment being connected to on the H.324 leg, if the peer adds the information to the message.
40	Displays the new H.223 multiplex level being configured.
41	Displays the new H.223 maximum PDU size being configured.
42	Indicates when the internal video capability memory has been released. No user data is provided.
43	Indicates when an empty capability set (ECS) has arrived from the IP leg of the call.
44	Indicates when a new capability set has arrived from the IP leg after an ECS has arrived.
45	Displays the dynamic payload number from the IP leg (H.324 to IP direction).

debug voip hpi

To enable debugging for Host Port Interface (HPI) message events, use the debug voip hpi command in privileged EXEC mode. To disable debugging output, use the no form of this command.

debug voip hpi [all | command | default | detail | error [call [informational] | software [informational]] | function | inout | notification | response | stats | checker]

no debug voip hpi

Syntax Description

all	(Optional) Displays all HPI debugging messages.
command	(Optional) Displays commands that are being sent to the 54x DSP.
default	(Optional) Displays HPI detail, error, and inout debugging messages and also runs if no keywords are added.
detail	(Optional) Displays detailed information about commands for the HPI. This command is used in conjunction with other debug voip hpi commands to show additional details when you use the command, notification, and response keywords.
error	(Optional) Displays HPI error messages.
call	(Optional) Displays call processing errors.
informational	(Optional) Displays minor and major errors. Without the informational keyword, only major errors are displayed.
software	(Optional) Displays software processing errors.
function	(Optional) Displays HPI functions.
inout	(Optional) Displays the output for the command, notification, response, and stats keywords.
notification	(Optional) Displays notification messages that are sent from the 54x DSP (for example, tone detection notification).
response	(Optional) Displays responses to commands that are sent by the 54x DSP (for example, responses to statistic requests).
stats	(Optional) Displays HPI statistics.
checker	(Optional) Displays HPI checker operations.

Defaults

Debugging is not enabled.

Command Modes

Privileged EXEC

Command History

Release	Modification
12.3(8)T	This command replaces the debug hpi command.
12.3(14)T	The checker keyword was added.
12.2SX	This command is supported in the Cisco IOS Release 12.2SX train. Support in a specific 12.2SX release of this train depends on your feature set, platform, and platform hardware.

Usage Guidelines

This command enables debugging for HPI message events, which are used to communicate with digital signal processors (DSPs).

Use the debug voip hpi all command to view gateway DSP modem relay termination codes. The DSP-to-host messages for the modem relay termination indicate to the host the modem relay session termination time, physical or link layer, and other probable causes for disconnection. On receiving this indication from the DSP, the host can disconnect the call or place the channel in the modem passthrough state.

Examples

The following is sample output from the debug voip hpi all command for an incoming ISDN call:

Router# debug voip hpi all 

01:28:44: //-1/xxxxxxxxxxxx/HPI/[]/hpi_dspmgr_open: 

The following event shows that the HPI has identified the call, as shown by the GUID, but the call leg has not been specified, as shown by the -1 value in the CallEntry ID:

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_dspmgr_open: 

    Allocated DSP resource: dsp_intf=0x64AF0EEC hpi_cdb=0x64ACED34 ret=1

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_dspmgr_open: 

    Exit Line # 9411

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_init: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_init: 

    Open channel

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_init: 

    Packet details:

      Packet Length (16), Channel Id (1), Packet Id (74)

      ALawULawSelect=A Law Associated SignalingChannel (128)

      Timeslot=0 SerialPort=0

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/caplog_hpi_msg_log: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_voice_config_params: 

For each packet-related event, information about the packet is shown following the event. The following two events show the Real-Time Protocol (RTP) packet:

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_encap_config: 

    RTP information

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_encap_config: 

    Packet details:

      Packet Length (38), Channel Id (1), Packet Id (92) TransportProtocol=2

      t_ssrc=0x00 r_ssrc=0x00 t_vpxcc=0x0 r_vpxcc=0x0

      sid_support=0 tse_payload=101 seq_num=0x0 redundancy=0

      cc_payload_type=0 fax_payload_type=0 alaw_pcm_switchover=0

      mulaw_pcm_switchover=0 dtmf_payload_type=0 nte_rcv_payload_type=101

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/caplog_hpi_msg_log: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_set_playout_config: 

    Packet details:

      Packet Length (18), Channel Id (1), Packet Id (76)

      Mode=1, Initial=60, Min=40, Max=200, fax_nom=300

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/caplog_hpi_msg_log: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_inband_sig: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_fax_enable: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_fax_enable: 

    Enable FAX

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_fax_enable: 

    Packet details:

      Packet Length (8), Channel Id (1), Packet Id (67)

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/caplog_hpi_msg_log: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_echo_cancel: 

    Packet details:

      Packet Length (14), Channel Id (1), Packet Id (66)

      flags=0x00000B00, Threshold=-21, SuppressorCoverage=7

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/caplog_hpi_msg_log: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_idle_code_det: 

    Packet details:

      Packet Length (14), Channel Id (1), Packet Id (116)

      Enable (FALSE), Code=0x00000000, Duration (6000 ms)

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/caplog_hpi_msg_log: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_set_gain: 

    Packet details:

      Packet Length (12), Channel Id (1), Packet Id (91)

      Gain: In=0, Out=0

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/caplog_hpi_msg_log: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_cng_config: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_info_field_size_config: 

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_digit_relay_config: 

    Exit Line # 4162

01:28:44: //-1/3FE022AC8009/HPI/[2/0:23]/hpi_dspmgr_update_callid: 

At this point, the HPI identifies the call leg, as shown by the CallEntry ID changing from -1 to 11.

01:28:44: //11/3FE022AC8009/HPI/[2/0:23:11]/hpi_voice_get_capabilities: 

    Exit Line # 5073

01:28:44: //11/3FE022AC8009/HPI/[2/0:23:11]/hpi_stop_service: 

01:28:44: //11/3FE022AC8009/HPI/[2/0:23:11]/hpi_idle_service: 

    Packet details:

      Packet Length (8), Channel Id (1), Packet Id (68)

01:28:44: //11/3FE022AC8009/HPI/[2/0:23:11]/caplog_hpi_msg_log: 

01:28:44: //11/3FE022AC8009/HPI/[2/0:23:11]/hpi_open_service: 

    Setting codec g729r8

01:28:44: //11/3FE022AC8009/HPI/[2/0:23:11]/hpi_set_codec: 

01:28:44: //11/3FE022AC8009/HPI/[2/0:23:11]/hpi_dsprm_callback: 

01:28:44: //11/3FE022AC8009/HPI/[2/0:23:11]/hpi_voice_config_params: 

01:28:44: //11/3FE022AC8009/HPI/[2/0:23:11]/hpi_digit_relay_config: 

    Exit Line # 4162