DTMF intermittent issue using Cisco CUBE router for the outbound calls only

sarwarm123 · ‎07-22-2016

Hi All,

I am experiencing the DTMF intermittent issue on our sip trunk for the outbound calls only. We have CUCM 9.x & Cisco CUBE router IOS 15.0, ISP is using RFC2833

Call Flow for the outboud calls are like that

IPPhone --> SCCP --> CUCM --> H323 --> CUBE --> SIP -- ITSP

Issue is intermittent. From the same phone If I dial same external number, it works sometime and sometime doesn't. When I debug the cube router "debug ccsip message" & "debug voip rtp session name-event 101"

I could see RTP payload 101 is missing in the not working calls. I think this payload is missing from the service provider end but service provider saying your PBX is not sending the payload

Please see attached debugs

debug ccsip messages

voip rtp session named-event 101

debug voip ccapi inout

DialPeer Config

dial-peer voice 101 voip
description *** Inbound Calls from ITSP ***
translation-profile incoming STD
session protocol sipv2
session target sip-server
incoming called-number .
voice-class codec 1
dtmf-relay rtp-nte digit-drop
ip qos dscp cs4 media
ip qos dscp cs3 signaling
!
dial-peer voice 2 voip
description *** Outbound LANDLINE calls to ITSP ***
destination-pattern 0[1-37].........
session protocol sipv2
session target sip-server
voice-class codec 1
voice-class sip asserted-id pai
voice-class sip profiles 4
voice-class sip bind control source-interface GigabitEthernet0/0
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte digit-drop
ip qos dscp cs4 media
ip qos dscp cs3 signaling
no vad

dial-peer voice 6 voip
description *** Outbound LANDLINE calls to ITSP 10 Digits Number Only ***
destination-pattern 0[1-3]........
session protocol sipv2
session target sip-server
voice-class codec 1
voice-class sip asserted-id pai
voice-class sip profiles 4
voice-class sip bind control source-interface GigabitEthernet0/0
voice-class sip bind media source-interface GigabitEthernet0/0
dtmf-relay rtp-nte digit-drop
ip qos dscp cs4 media
ip qos dscp cs3 signaling
no vad

Jitender Bhandari · ‎07-22-2016

Hi

Non working takes Dial-peer=2

030424: Jul 22 13:02:54.645: //7724/80D815999647/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x13C0C234, Interface Type=3, Destination=, Mode=0x0,
   Call Params(Calling Number=44043,(Calling Name=Muhammad Sarwar)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=03031231177(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=2, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
030425: Jul 22 13:02:54.645: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

working takes Dial-peer=6

028463: Jul 22 13:02:09.625: //7712/0064437E9147/CCAPI/ccCallSetupRequest:
   Calling Number=44043(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=03031231177(TON=Unknown, NPI=Unknown),
   Redirect Number=, Display Info=Muhammad Sarwar
   Account Number=Muhammad Sarwar, Final Destination Flag=TRUE,
   Guid=0064437E-5119-2179-9147-F0070AD1D139, Outgoing Dial-peer=6

and we can see the DTMF

028823: Jul 22 13:02:16.250: //7710/4CEF231D84A8/CCAPI/cc_relay_digit_end_for_3way_conference:
   Check DTMF relay digit end for 3way conf
028824: Jul 22 13:02:16.658: //7712/0064437E9147/CCAPI/cc_api_call_digit_begin:
   Consume mask is not set. Relaying Digit 1 to dstCallId 0x1E21
028825: Jul 22 13:02:16.658: //7712/0064437E9147/CCAPI/cc_relay_digit_begin_for_3way_conference:
   Check DTMF relay digit begin for 3way conf
028826: Jul 22 13:02:16.734: //7712/0064437E9147/CCAPI/cc_api_call_digit_end:
   Consume mask is not set. Relaying Digit 1 to dstCallId 0x1E21

Check for config on both dial-peer.

HTH

JB

Jitender Bhandari · ‎07-22-2016

In NON workign call, in 183 Session Progress we dont see any telephone-event from provider

030437: Jul 22 13:02:54.781: //7725/80D815999647/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Require: 100rel
Via: SIP/2.0/UDP 10.160.134.98:5060;branch=z9hG4bKB0E3588

RSeq: 1
To: <sip:03031231177@10.128.0.1>;tag=3678181374-736963
From: "Muhammad Sarwar" <sip:44043@10.160.134.98>;tag=606AB00-CA3
Call-ID: 70473427-4F4311E6-84C5B464-C9AE0C71@10.160.134.98
CSeq: 101 INVITE
Allow: UPDATE,PRACK,INFO,NOTIFY,REGISTER,OPTIONS,BYE,INVITE,ACK,CANCEL
Contact: <sip:03031231177@10.128.0.1:5060>
Content-Type: application/sdp
Accept: application/sdp
Content-Length: 163

v=0
o=MSX9 912241996822078092 1 IN IP4 10.128.0.1
s=sip call
c=IN IP4 10.28.10.2
t=0 0
a=sendrecv
m=audio 11200 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=sendrecv

IN working we see 101 telephone-event/8000 from provider

028478: Jul 22 13:02:09.705: //7713/0064437E9147/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Require: 100rel
Via: SIP/2.0/UDP 10.160.134.98:5060;branch=z9hG4bKB0811F0C
RSeq: 1
To: <sip:03031231177@10.128.0.1>;tag=3678181329-663707
From: "Muhammad Sarwar" <sip:44043@10.160.134.98>;tag=605FB28-1024
Call-ID: 5571ACE9-4F4311E6-84B0B464-C9AE0C71@10.160.134.98
CSeq: 101 INVITE
Allow: UPDATE,PRACK,INFO,NOTIFY,REGISTER,OPTIONS,BYE,INVITE,ACK,CANCEL
Contact: <sip:03031231177@10.128.0.1:5060>
Content-Type: application/sdp

Accept: application/sdp
Content-Length: 218

v=0
o=MSX9 35548512995581696 1 IN IP4 10.128.0.1
s=sip call
c=IN IP4 10.28.10.2
t=0 0
a=sendrecv
m=audio 10994 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv

What does your service provider say.

HTH

JB

sarwarm123 · ‎07-22-2016

That's exactly I have mentioned in my post that in non working I don't see rtp payload 101 is missing. Service provider says its your pbx not sending payload

I have also posted above configure of the both dial-peers. There is no difference accept one take 10 digits and other allow 11 digits

Jitender Bhandari · ‎07-22-2016

In the INVITE for NON-working call as well as working call we see "Allow-Events: telephone-event"

NON-Working

030435: Jul 22 13:02:54.649: //7725/80D815999647/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:03031231177@10.128.0.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.160.134.98:5060;branch=z9hG4bKB0E3588
From: "Muhammad Sarwar" <sip:44043@10.160.134.98>;tag=606AB00-CA3
To: <sip:03031231177@10.128.0.1>
Date: Fri, 22 Jul 2016 13:02:54 GMT
Call-ID: 70473427-4F4311E6-84C5B464-C9AE0C71@10.160.134.98

Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 2161644953-2115576185-2521299719-0181522745
User-Agent: Cisco-SIPGateway/IOS-15.2.4.M2
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1469192574
Contact: <sip:44043@10.160.134.98:5060>
Expires: 180
Allow-Events: telephone-event
P-Asserted-Identity: "Muhammad Sarwar" <sip:44043@10.160.134.98>
Content-Length: 0
Privacy: id

Working

028476: Jul 22 13:02:09.629: //7713/0064437E9147/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:03031231177@10.128.0.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.160.134.98:5060;branch=z9hG4bKB0811F0C
From: "Muhammad Sarwar" <sip:44043@10.160.134.98>;tag=605FB28-1024
To: <sip:03031231177@10.128.0.1>
Date: Fri, 22 Jul 2016 13:02:09 GMT
Call-ID: 5571ACE9-4F4311E6-84B0B464-C9AE0C71@10.160.134.98
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 0006570878-1360601465-2437410823-0181522745
User-Agent: Cisco-SIPGateway/IOS-15.2.4.M2
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1469192529

Contact: <sip:44043@10.160.134.98:5060>
Expires: 180
Allow-Events: telephone-event
P-Asserted-Identity: "Muhammad Sarwar" <sip:44043@10.160.134.98>
Content-Length: 0
Privacy: id

JB

sarwarm123 · ‎07-22-2016

Jitender Bhandari thankyou for your reply. That's correct Allow Events are in both working and non working calls.

They only difference we see "a=rtpmap:101 telephone-event/8000" missing in the non working calls. You have also pointed out.

So what is the solution? Service provider saying it is your PBX which is not sending rtp payload 101

And also they said "I have reviewed your trace, can you confirm why in your initial SDP you don't sent RTP payload type 101 within your media attributes, as this would then be passed on to the onward carrier"

Mohammed al Baqari · ‎07-23-2016

Hi,

Please try to for rtp nte using the following command on your dialpeers:

voice-class sip dtmf-relay force rtp-nte

Also, try to change from delayed offer to early offer using the command on your dial-peers

voice-class sip early-offer forced

sarwarm123 · ‎07-25-2016

Mohammed thanks for your reply. You suggested to apply command early-offer forced. Do we not need this command when CUCM registered with CUBE router as SIP trunk. But in my case I am using a H323

Mohammed al Baqari · ‎07-25-2016

I missed that. why don't you switch to sip-sip integration. I know H323-SIP isn't seemless. There are lot of restrictions and I think bugs.

If you can switch sip-sip that will be worth trying.

sarwarm123 · ‎07-25-2016

Mohammed, I can't, we experienced some issues in the start hence reason we have to use h323

I don't think its bug. Could be some thing missing in config which is mismatching with telco settings

Mohammed al Baqari · ‎07-25-2016

Ok, then try to change dtmf-relay on inbound dial-peer to h245-alpha. CUBE can interwork h245-alpha to rtp-nte

sarwarm123 · ‎07-25-2016

Tried but doesn't make any difference

sarwarm123 · ‎07-27-2016

Hi All, issue has been resolved by changing the cube mode from h323 to sip with callmanger and now it is sip to sip

However now I am experiencing a wired issue. when I am trying to debug voice ccapi inout I cannot log what I used to see before where I can easily identify what is out going dial peer and what is incoming dial-peer. After changes I cannot see old log as below example

Logs before change (Example)

032227: Jul 25 13:21:02.580: //-1/77F9781C9D97/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=+442476392352
----- ccCallInfo IE subfields -----
cisco-ani=sip:+442476392352@10.18.10.1
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=sip:01926353749@10.160.134.198;user=phone
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

032228: Jul 25 13:21:02.584: //-1/77F9781C9D97/CCAPI/cc_api_call_setup_ind_common:
Interface=0x13C0C234, Call Info(
Calling Number=sip:+442476392352@10.128.0.1,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=sip:01926353749@10.60.34.98;user=phone(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=101, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=18416

After Changes

Log Buffer (10000 bytes):
538D11E6-A105B464-C9AE0C71, tag=0x0)
637449: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/ccConferenceCreate:
Conference Id=0xFFFFFFFF, Call Id1=32966, Call Id2=32967, Tag=0x0
637450: Jul 28 00:05:19.027: //32966/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637451: Jul 28 00:05:19.027: cc_api_get_xcode_stream : 4819
637452: Jul 28 00:05:19.027: //32967/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637453: Jul 28 00:05:19.027: cc_api_get_xcode_stream : 4819
637454: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/ccConferenceCreate:

637455: Jul 28 00:05:19.027: ccConferenceCreate: ret1=0, codecMask1=2, bytes1=160, negot1=1, dtmf1=6
ret2=0, codecMask2=2, bytes2=160, negot2=1, dtmf2=6,
tx_dynamic_pt1=8, rx_dynamic_pt1=8, codec_mode1=0, params_bitmap1 =0
tx_dynamic_pt2=8, rx_dynamic_pt2=8, codec_mode2=0, params_bitmap2 =0
637456: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/ccGetMediaClassTag:
media class tag 0
637457: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
637458: Jul 28 00:05:19.027: //32967/F66BB8800012/CCAPI/ccGetMediaClassTag:
media class tag 0
637459: Jul 28 00:05:19.027: //32967/F66BB8800012/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
637460: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
637461: Jul 28 00:05:19.027: //32967/F66BB8800012/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
637462: Jul 28 00:05:19.027: //32966/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
xcoder inserted for preferred features w/ mask 0x0
637463: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/ccConferenceCreate:
delay media to slow start case, codec negotation is not done
637464: Jul 28 00:05:19.027: //32966/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637465: Jul 28 00:05:19.027: cc_api_get_xcode_stream : 4819
637466: Jul 28 00:05:19.027: //32966/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637467: Jul 28 00:05:19.027: cc_api_get_xcode_stream : 4819
637468: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/cc_api_bridge_done:
Conference Id=0x2ACF, Source Interface=0x13C0C234, Source Call Id=32966,
Destination Call Id=32967, Disposition=0x0, Tag=0x0
637469: Jul 28 00:05:19.027: //32967/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637470: Jul 28 00:05:19.027: cc_api_get_xcode_stream : 4819
637471: Jul 28 00:05:19.027: //32967/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637472: Jul 28 00:05:19.027: cc_api_get_xcode_stream : 4819
637473: Jul 28 00:05:19.027: //32967/F66BB8800012/CCAPI/cc_api_bridge_done:
Conference Id=0x2ACF, Source Interface=0x13C0C234, Source Call Id=32967,
Destination Call Id=32966, Disposition=0x0, Tag=0x0
637474: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/cc_generic_bridge_done:
Conference Id=0x2ACF, Source Interface=0x13C0C234, Source Call Id=32967,
Destination Call Id=32966, Disposition=0x0, Tag=0x0
637475: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x2ACF, Destination Call Id=32967)
637476: Jul 28 00:05:19.027: //32967/F66BB8800012/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x2ACF, Destination Call Id=32966)
637477: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/ccConferenceCreate:

637478: Jul 28 00:05:19.027: confID:0x2ACF; callEntry1 callID1:0x80C6, type:3; callEntry2 callID2:0x80C7, type:3

637479: Jul 28 00:05:19.027: //32966/F66BB8800012/CCAPI/cc_process_notify_bridge_done:
Conference Id=0x2ACF, Call Id1=32966, Call Id2=32967
637480: Jul 28 00:05:21.667: //32967/F66BB8800012/CCAPI/cc_api_event_indication:
Event=98, Call Id=32967
637481: Jul 28 00:05:21.667: //32967/F66BB8800012/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
637482: Jul 28 00:05:21.667: //32967/F66BB8800012/CCAPI/cc_api_call_cut_progress:
Interface=0x13C0C234, Progress Indication=NULL(0), Signal Indication=NOT PRESENT(255),
Cause Value=0
637483: Jul 28 00:05:21.667: //32967/F66BB8800012/CCAPI/cc_api_call_cut_progress:
Call Entry(Responsed=TRUE)
637484: Jul 28 00:05:21.667: //32966/F66BB8800012/CCAPI/ccCallProgIndProgress:
Progress Indication=NULL(0)
637485: Jul 28 00:05:22.403: //32967/F66BB8800012/CCAPI/cc_api_call_connected:
Interface=0x13C0C234, Data Bitmask=0x1, Progress Indication=NULL(0),
Connection Handle=0
637486: Jul 28 00:05:22.403: //32967/F66BB8800012/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
637487: Jul 28 00:05:22.403: //32967/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

637488: Jul 28 00:05:22.403: call_info mainst_callID:0x80C7, peer_callID:0x80C6, confID:0x2ACF, spi_type:4, media_flo_thru:1, media_passthru:1, num_streams:1, swmtpmsp_present:0

637489: Jul 28 00:05:22.407: //32966/F66BB8800012/CCAPI/ccCallConnect:
Progress Indication=NULL(0), Data Bitmask=0x1
637490: Jul 28 00:05:22.407: //32966/F66BB8800012/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
637491: Jul 28 00:05:22.415: //32966/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

637492: Jul 28 00:05:22.415: call_info mainst_callID:0x80C6, peer_callID:0x80C7, confID:0x2ACF, spi_type:4, media_flo_thru:1, media_passthru:1, num_streams:1, swmtpmsp_present:0

637493: Jul 28 00:05:22.415: //32966/F66BB8800012/CCAPI/cc_api_event_indication:
Event=200, Call Id=32966
637494: Jul 28 00:05:22.415: //32966/F66BB8800012/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
637495: Jul 28 00:05:26.316: //32967/F66BB8800012/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=FALSE, Tone=Null,
Tone Direction=Sum Network, Params=0x0, Call Id=32967
637496: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x13C0C234, Call Id=32966
637497: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
637498: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/ccConferenceDestroy:
Conference Id=0x2ACF, Tag=0x0
637499: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/ccConferenceDestroy:

637500: Jul 28 00:05:26.316: confID:0x2ACF; callEntry1 callID1:0x80C6, type:3; callEntry2 callID2:0x80C7, type:3

637501: Jul 28 00:05:26.316: //32966/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637502: Jul 28 00:05:26.316: cc_api_get_xcode_stream : 4819
637503: Jul 28 00:05:26.316: //32967/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637504: Jul 28 00:05:26.316: cc_api_get_xcode_stream : 4819
637505: Jul 28 00:05:26.316: //32966/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637506: Jul 28 00:05:26.316: cc_api_get_xcode_stream : 4819
637507: Jul 28 00:05:26.316: //32966/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637508: Jul 28 00:05:26.316: cc_api_get_xcode_stream : 4819
637509: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x2ACF, Source Interface=0x13C0C234, Source Call Id=32966,
Destination Call Id=32967, Disposition=0x0, Tag=0x0
637510: Jul 28 00:05:26.316: //32967/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637511: Jul 28 00:05:26.316: cc_api_get_xcode_stream : 4819
637512: Jul 28 00:05:26.316: //32966/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637513: Jul 28 00:05:26.316: cc_api_get_xcode_stream : 4819
637514: Jul 28 00:05:26.316: //32967/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637515: Jul 28 00:05:26.316: cc_api_get_xcode_stream : 4819
637516: Jul 28 00:05:26.316: //32967/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

637517: Jul 28 00:05:26.316: cc_api_get_xcode_stream : 4819
637518: Jul 28 00:05:26.316: //32967/F66BB8800012/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x2ACF, Source Interface=0x13C0C234, Source Call Id=32967,
Destination Call Id=32966, Disposition=0x0, Tag=0x0
637519: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/cc_generic_bridge_done:
Conference Id=0x2ACF, Source Interface=0x13C0C234, Source Call Id=32967,
Destination Call Id=32966, Disposition=0x0, Tag=0x0
637520: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
637521: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
637522: Jul 28 00:05:26.316: //32967/F66BB8800012/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
637523: Jul 28 00:05:26.316: //32967/F66BB8800012/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
637524: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x13C0C234, Tag=0x0, Call Id=32966,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
637525: Jul 28 00:05:26.316: //32966/F66BB8800012/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
637526: Jul 28 00:05:26.316: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

637527: Jul 28 00:05:26.316: :cc_free_feature_vsa freeing 313FE98
637528: Jul 28 00:05:26.316: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

637529: Jul 28 00:05:26.316: vsacount in free is 1
637530: Jul 28 00:05:26.332: //32967/F66BB8800012/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x13C0C234, Tag=0x0, Call Id=32967,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
637531: Jul 28 00:05:26.332: //32967/F66BB8800012/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
637532: Jul 28 00:05:26.332: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

637533: Jul 28 00:05:26.332: :cc_free_feature_vsa freeing 3141718
637534: Jul 28 00:05:26.332: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Router config is some thing like that

voice-card 0
dspfarm
dsp services dspfarm
!
!
no voice hunt unassigned-number
no voice hunt dest-out-of-order
no voice hunt invalid-number
!
voice service voip
ip address trusted list
ipv4 xxxx 255.255.255.255
address-hiding
mode border-element
media anti-trombone
allow-connections sip to sip
redirect ip2ip
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711alaw
h323
sip
header-passing
error-passthru
asserted-id pai
no update-callerid
early-offer forced
sip-profiles 100
!
voice class codec 1
codec preference 1 g711alaw
!
voice class h323 1
h225 timeout tcp establish 3
!
voice class sip-profiles 4
request INVITE sip-header Privacy add "Privacy: id"
!
voice class sip-profiles 100
request INVITE sip-header SIP-Req-URI modify "; SIP/2.0" ";user=phone SIP/2.0"
request REINVITE sip-header SIP-Req-URI modify "; SIP/2.0" ";user=phone SIP/2.0"

csdb tcp synwait-time 30
csdb tcp idle-time 3600
csdb tcp finwait-time 5
csdb tcp reassembly max-memory 1024
csdb tcp reassembly max-queue-length 16
csdb udp idle-time 30
csdb icmp idle-time 10
csdb session max-session 65535

call treatment on
call threshold global cpu-avg low 68 high 75
call threshold global total-mem low 75 high 85
call threshold global total-calls low 80 high 95
call spike 100