Inbound SIP Calls Not Going to VM

[CChambleeJr]

Hello all.  I am having trouble getting inbound SIP calls to reach voice mail boxes after call-forward noans.  I have two working CME routers, each as a stand-alone PBX.  I have a SIP trunk configured between them, and can place calls from one CME to the other over the SIP trunk.  I have CUE configured on one of the CMEs, and have mailboxes configured for its three VoIP extensions, 5001, 5002 and 5003.  The VM extension is 2000.  When placing calls from extension 5001 to 5002, the VM picks up the call after three rings, as expected.  But if I place a call from one of the extensions on the other CME over the SIP trunk and dial 5002, extension 5002 will ring three times, then go back idle.  The calling station on the other CME will continue to give ringback until I release the call.  Where should I look for the problem?

[CChambleeJr]

Running config...

CME-172-16-1-1#show run
Building configuration...

Current configuration : 8768 bytes
!
! Last configuration change at 16:52:39 central Tue Feb 23 2016 by Chuck
version 15.1
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname CME-172-16-1-1
!
boot-start-marker
boot-end-marker
!
!
! card type command needed for slot/vwic-slot 0/2
enable password *********
!
no aaa new-model
!
clock timezone central -6 0
clock summer-time central recurring
no network-clock-participate slot 1
no network-clock-participate slot 2
--More--  !
dot11 syslog
ip source-route
!
ip cef
!
!
no ip dhcp use vrf connected
!
ip dhcp pool VOICE
network 172.16.100.0 255.255.255.0
default-router 172.16.100.254
option 150 ip 172.16.1.1
!
!
no ip domain lookup
no ipv6 cef
!
multilink bundle-name authenticated
!
!
!
!
!
--More--  voice-card 0
!
voice-card 1
!
voice-card 2
!
!
!
!
!
!
!
crypto pki token default removal timeout 0
!
!
!
!
license udi pid CISCO3825 sn FTX1224A105
username cisco privilege 15 password 0 cisco
username Chuck privilege 0 password 0 *********
!
redundancy
!
!
--More--  !
!
!
!
!
!
!
!
interface GigabitEthernet0/0
no ip address
duplex auto
speed auto
media-type sfp
negotiation auto
!
interface GigabitEthernet0/0.1
encapsulation dot1Q 1 native
ip address 172.16.1.1 255.255.255.0
!
interface GigabitEthernet0/0.100
encapsulation dot1Q 100
ip address 172.16.100.1 255.255.255.0
!
interface GigabitEthernet0/0.101
--More--   encapsulation dot1Q 101
ip address 192.168.1.2 255.255.255.0
!
interface Service-Engine0/0
ip unnumbered GigabitEthernet0/0.1
service-module ip address 172.16.1.2 255.255.255.0
service-module ip default-gateway 172.16.1.1
!
interface GigabitEthernet0/1
description SIP trunk to CME-2
ip address 10.0.185.70 255.255.255.0
duplex full
speed 100
media-type rj45
!
ip forward-protocol nd
ip http server
ip http authentication local
no ip http secure-server
ip http path flash:/GUI
!
!
ip route 172.16.1.2 255.255.255.255 Service-Engine0/0
ip route 172.16.2.0 255.255.255.0 172.16.1.254
--More--  !
!
!
!
!
!
tftp-server flash:term06.default.loads
tftp-server flash:term41.default.loads
tftp-server flash:term42.default.loads
tftp-server flash:term62.default.loads
tftp-server flash:term45.default.loads
tftp-server flash:term65.default.loads
tftp-server flash:term70.default.loads
tftp-server flash:term71.default.loads
tftp-server flash:Desktops/320x212x12/CampusNight.png
tftp-server flash:Desktops/320x212x12/CiscoFountain.png
tftp-server flash:Desktops/320x212x12/MorroRock.png
tftp-server flash:Desktops/320x212x12/NantucketFlowers.png
tftp-server flash:Desktops/320x212x12/TN-CampusNight.png
tftp-server flash:Desktops/320x212x12/TN-CiscoFountain.png
tftp-server flash:Desktops/320x212x12/TN-Fountain.png
tftp-server flash:Desktops/320x212x12/TN-MorroRock.png
tftp-server flash:Desktops/320x212x12/TN-NantucketFlowers.png
tftp-server flash:Desktops/320x212x12/Fountain.png
--More--  tftp-server flash:Desktops/320x212x12/CiscoLogo.png
tftp-server flash:Desktops/320x212x12/TN-CiscoLogo.png
tftp-server flash:Desktops/320x212x12/List.xml
tftp-server flash:Desktops/320x216x16/List.xml
tftp-server flash:Desktops/320x212x16/List.xml
tftp-server flash:term61.default.loads
tftp-server flash:term75.default.loads
tftp-server flash:P00307020400.bin
tftp-server flash:P00307020400.loads
tftp-server flash:P00307020400.sb2
tftp-server flash:P00307020400.sbn
tftp-server flash:P0030801SR02.sbn
tftp-server flash:P0030801SR02.loads
tftp-server flash:P0030801SR02.sb2
tftp-server flash:P0030801SR02.bin
tftp-server flash:P00308010200.sbn
tftp-server flash:P00308010200.loads
tftp-server flash:P00308010200.sb2
tftp-server flash:P00308010200.bin
tftp-server flash:P00308000400.bin
tftp-server flash:P00308000400.loads
tftp-server flash:P00308000400.sb2
tftp-server flash:P00308000400.sbn
tftp-server flash:term31.default.loads
--More--  tftp-server flash:SCCP31.9-4-2SR1-1S.loads
tftp-server flash:jar31sccp.9-4-2ES9.sbn
tftp-server flash:dsp31.9-4-2ES9.sbn
tftp-server flash:cvm31sccp.9-4-2ES9.sbn
tftp-server flash:cnu31.9-4-2ES9.sbn
tftp-server flash:apps31.9-4-2ES9.sbn
!
control-plane
!
!
voice-port 1/0/0
connection plar 5001
caller-id enable
!
voice-port 1/0/1
connection plar 5002
caller-id enable
!
voice-port 1/0/2
connection plar 5003
caller-id enable
!
voice-port 1/0/3
!
--More--  voice-port 2/0/0
ring cadence pattern01
station-id name Charles
station-id number 5011
caller-id enable
!
voice-port 2/0/1
!
!
!
mgcp profile default
!
!
dial-peer voice 1 voip
destination-pattern 2...
session protocol sipv2
session target ipv4:172.16.1.2
dtmf-relay sip-notify
codec g711ulaw
no vad
!
dial-peer voice 11 pots
destination-pattern 9[2-9].........
port 1/0/3
--More--   prefix 99,
!
dial-peer voice 21 pots
destination-pattern 5011
port 2/0/0
!
dial-peer voice 31 voip
description Outbound SIP to CME-2 - Local
destination-pattern 4...
session protocol sipv2
session target ipv4:10.0.185.71
session transport tcp
dtmf-relay rtp-nte
!
dial-peer voice 32 voip
description Incoming from CME-2
destination-pattern 5...
session protocol sipv2
session target ipv4:10.0.185.71
session transport tcp
dtmf-relay rtp-nte
!
dial-peer voice 33 voip
description Outbound SIP to Avaya Lab
--More--   destination-pattern 300....
session protocol sipv2
session target ipv4:10.0.185.235
session transport tcp
dtmf-relay rtp-nte
!
dial-peer voice 34 voip
description Incoming SIP from Avaya
destination-pattern 5...
session protocol sipv2
session target ipv4:10.0.185.235
session transport tcp
dtmf-relay rtp-nte
!
!
!
!
telephony-service
max-ephones 30
max-dn 45
ip source-address 172.16.1.1 port 2000
auto assign 1 to 10 type 7912
auto assign 1 to 10 type 7941
auto assign 1 to 10 type 7940
--More--   auto assign 1 to 10 type 7960
auto assign 1 to 10 type 7961
auto assign 1 to 10 type 7965
auto assign 1 to 10 type 7945
auto assign 1 to 10 type 7942
auto assign 1 to 10 type 7962
auto assign 1 to 10 type 7970
auto assign 1 to 10 type 7971
auto assign 1 to 10 type 7975
system message B5298 Voice Lab 1
cnf-file location flash:
load 7912 term06.default.loads
load 7960-7940 P00308000400
load 7941 term41.default.loads
load 7942 term42.default.loads
load 7945 term45.default.loads
load 7961 term61.default.loads
load 7962 term62.default.loads
load 7965 term65.default.loads
load 7970 term70.default.loads
load 7971 term71.default.loads
load 7975 term75.default.loads
time-zone 8
voicemail 2000
--More--   max-conferences 4 gain -6
web admin system name cisco password cisco
dn-webedit
time-webedit
transfer-system full-consult
secondary-dialtone 9
create cnf-files version-stamp Jan 01 2002 00:00:00
!
!
ephone-dn 1 dual-line
number 5001
label Chuck
name Chuck
preference 1 secondary 2
call-forward busy 2000
call-forward noan 2000 timeout 12
!
!
ephone-dn 2 dual-line
number 5002
label Greg
name Greg
call-forward busy 2000
call-forward noan 2000 timeout 12
--More--  !
!
ephone-dn 3 dual-line
number 5003
label Sam
name Sam
call-forward busy 2000
call-forward noan 2000 timeout 12
!
!
ephone-dn 4 dual-line
number 1004
call-forward busy 2000
call-forward noan 2000 timeout 12
!
!
ephone-dn 5 dual-line
number 1005
call-forward busy 2000
call-forward noan 2000 timeout 12
!
!
ephone-dn 6 dual-line
number 1006
--More--   call-forward busy 2000
call-forward noan 2000 timeout 12
!
!
ephone-dn 7 dual-line
number 1007
call-forward busy 2000
call-forward noan 2000 timeout 12
!
!
ephone-dn 8 dual-line
number 1008
call-forward busy 2000
call-forward noan 2000 timeout 12
!
!
ephone-dn 9 dual-line
number 1009
call-forward busy 2000
call-forward noan 2000 timeout 12
!
!
ephone-dn 10 dual-line
number 1010
--More--   call-forward busy 2000
call-forward noan 2000 timeout 12
!
!
ephone-dn 21
number 8000....
mwi on
!
!
ephone-dn 22
number 8001....
mwi off
!
!
ephone 1
device-security-mode none
mac-address 0012.0138.3FD2
type 7960
button 1:2
!
!
!
ephone 2
device-security-mode none
--More--   mac-address 0016.C815.61DD
type 7960
button 1:1
!
!
!
ephone 3
device-security-mode none
mac-address 0016.468A.DD37
type 7960
button 1:3
!
!
!
ephone 4
device-security-mode none
mac-address 001B.D423.FB79
max-calls-per-button 2
type 7931
button 1:4 2:1 3:2 4:3
!
!
!
ephone 5
--More--   device-security-mode none
mac-address 0017.5A5B.2A74
type 7960
button 1:5
!
!
!
ephone 6
device-security-mode none
mac-address 000C.8533.CD16
type 7960
button 1:6
!
!
!
!
line con 0
line aux 0
line 194
no activation-character
no exec
transport preferred none
transport input all
transport output lat pad telnet rlogin lapb-ta mop udptn v120 ssh
--More--  line vty 0 4
exec-timeout 60 0
login local
transport input all
line vty 5 15
exec-timeout 60 0
login
transport input all
!
scheduler allocate 20000 1000
ntp server 172.16.1.4
ntp server 172.16.1.9
end

CME-172-16-1-1#

*******

debug voice ccapi inout:

(call-forward to VM extension occurs at 18:05:15)

CME-172-16-1-1#debug voice ccapi inout
voip ccapi inout debugging is on
CME-172-16-1-1#terminal mon
CME-172-16-1-1#
*Feb 24 18:05:03.622: //-1/9484DF2282AC/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=4003
----- ccCallInfo IE subfields -----
cisco-ani=4003
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=5001
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

*Feb 24 18:05:03.622: //-1/9484DF2282AC/CCAPI/cc_api_call_setup_ind_common:
Interface=0x69D1B604, Call Info(
Calling Number=4003,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5001(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=31, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=219
*Feb 24 18:05:03.622: //-1/9484DF2282AC/CCAPI/ccCheckClipClir:
In: Calling Number=4003(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Feb 24 18:05:03.622: //-1/9484DF2282AC/CCAPI/ccCheckClipClir:
Out: Calling Number=4003(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Feb 24 18:05:03.622: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:03.622: :cc_get_feature_vsa malloc success
*Feb 24 18:05:03.622: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:03.622: cc_get_feature_vsa count is 1
*Feb 24 18:05:03.622: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:03.622: :FEATURE_VSA attributes are: feature_name:0,feature_time:1763335232,feature_id:322
*Feb 24 18:05:03.622: //219/9484DF2282AC/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=4003(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5001(TON=Unknown, NPI=Unknown))
*Feb 24 18:05:03.622: //219/9484DF2282AC/CCAPI/cc_process_call_setup_ind:
Event=0x6A46B308
*Feb 24 18:05:03.622: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 5001
*Feb 24 18:05:03.622: //219/9484DF2282AC/CCAPI/ccCallSetContext:
Context=0x6CC0DDEC
*Feb 24 18:05:03.622: //219/9484DF2282AC/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 219 with tag 31 to app "_ManagedAppProcess_Default"
*Feb 24 18:05:03.622: //219/9484DF2282AC/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
*Feb 24 18:05:03.626: //219/9484DF2282AC/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=20001, Params=0x6CC0C97C, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
*Feb 24 18:05:03.626: //219/9484DF2282AC/CCAPI/ccCheckClipClir:
In: Calling Number=4003(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Feb 24 18:05:03.626: //219/9484DF2282AC/CCAPI/ccCheckClipClir:
Out: Calling Number=4003(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Feb 24 18:05:03.626: //219/9484DF2282AC/CCAPI/ccCallSetupRequest:
Destination Pattern=5001$, Called Number=5001, Digit Strip=TRUE
*Feb 24 18:05:03.626: //219/9484DF2282AC/CCAPI/ccCallSetupRequest:
Calling Number=4003(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5001(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=SECRET1003
Account Number=4003, Final Destination Flag=TRUE,
Guid=9484DF22-DA53-11E5-82AC-D0DA08C41CEE, Outgoing Dial-peer=20001
*Feb 24 18:05:03.626: //219/9484DF2282AC/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=4003
----- ccCallInfo IE subfields -----
cisco-ani=4003
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=5001
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

*Feb 24 18:05:03.626: //219/9484DF2282AC/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x7222B574, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=4003,(Calling Name=SECRET1003)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5001(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20001, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Feb 24 18:05:03.626: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:03.626: :cc_get_feature_vsa malloc success
*Feb 24 18:05:03.626: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:03.626: cc_get_feature_vsa count is 2
*Feb 24 18:05:03.626: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:03.626: :FEATURE_VSA attributes are: feature_name:0,feature_time:1763335456,feature_id:323
*Feb 24 18:05:03.626: //220/9484DF2282AC/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
*Feb 24 18:05:03.626: //220/9484DF2282AC/CCAPI/ccCallSetContext:
Context=0x6CC0C92C
*Feb 24 18:05:03.626: //219/9484DF2282AC/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=20001
*Feb 24 18:05:03.626: //220/9484DF2282AC/CCAPI/cc_api_update_call_info:
Interface=0x7222B574, Call Id=0xDC
*Feb 24 18:05:03.630: //220/9484DF2282AC/CCAPI/cc_api_call_proceeding:
Interface=0x7222B574, Progress Indication=NULL(0)
*Feb 24 18:05:03.630: //220/9484DF2282AC/CCAPI/cc_api_call_alert:
Interface=0x7222B574, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*Feb 24 18:05:03.630: //220/9484DF2282AC/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
*Feb 24 18:05:03.630: //219/9484DF2282AC/CCAPI/ccCallAlert:
Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*Feb 24 18:05:03.630: //219/9484DF2282AC/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, Alert Sent=TRUE)
*Feb 24 18:05:03.630: //220/9484DF2282AC/CCAPI/ccCallFeature:
Feature Type=25, Call Id=220
*Feb 24 18:05:03.634: //219/9484DF2282AC/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=219
*Feb 24 18:05:15.630: //220/9484DF2282AC/CCAPI/cc_api_call_disconnected:
Cause Value=19, Interface=0x7222B574, Call Id=220
*Feb 24 18:05:15.630: //220/9484DF2282AC/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=19, Retry Count=0)
*Feb 24 18:05:15.630: //219/9484DF2282AC/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
*Feb 24 18:05:15.630: //220/9484DF2282AC/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
type=6 redirecting_number=5001
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectNumber:
type=6 Original Called Number=5001, Called Number=5001, Calling Number=4003, Calling DN=-1 Calling Id=219,
Redirect Number=2000, Redirect Reason=2
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
type=6 redirecting_number=5001
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.630: :cc_get_feature_vsa malloc success
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.630: cc_get_feature_vsa count is 3
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.630: :FEATURE_VSA attributes are: feature_name:0,feature_time:1763335680,feature_id:324
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Feb 24 18:05:15.630: feature_vsa is 691A5C38,featname is 0
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.630: :cc_get_feature_vsa malloc success
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.630: cc_get_feature_vsa count is 4
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.630: :FEATURE_VSA attributes are: feature_name:0,feature_time:1763335008,feature_id:325
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Feb 24 18:05:15.630: : Generating new featurevsa,inserting it
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Feb 24 18:05:15.630: feature call forward featname is 3
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Feb 24 18:05:15.630: feature_vsa is 691A5D18,featname is 0
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.630: :cc_get_feature_vsa malloc success
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.630: cc_get_feature_vsa count is 5
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.630: :FEATURE_VSA attributes are: feature_name:0,feature_time:1763334784,feature_id:326
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Feb 24 18:05:15.630: : Generating new featurevsa,inserting it
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Feb 24 18:05:15.630: feature call forward featname is 3
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.630: :cc_free_feature_vsa freeing 691A5DF8
*Feb 24 18:05:15.630: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.630: vsacount in free is 4
*Feb 24 18:05:15.630: //220/9484DF2282AC/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=19)
*Feb 24 18:05:15.630: //220/9484DF2282AC/CCAPI/ccCallDisconnect:
Cause Value=19, Call Entry(Responsed=TRUE, Cause Value=19)
*Feb 24 18:05:15.630: //220/9484DF2282AC/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
*Feb 24 18:05:15.634: //220/9484DF2282AC/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7222B574, Tag=0x0, Call Id=220,
Call Entry(Disconnect Cause=19, Voice Class Cause Code=0, Retry Count=0)
*Feb 24 18:05:15.634: //220/9484DF2282AC/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Feb 24 18:05:15.634: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.634: :cc_free_feature_vsa freeing 691A5A78
*Feb 24 18:05:15.634: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.634: vsacount in free is 3
*Feb 24 18:05:15.634: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.634: vsacount in free is 2
*Feb 24 18:05:15.634: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.634: :cc_get_feature_vsa malloc success
*Feb 24 18:05:15.634: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.638: cc_get_feature_vsa count is 3
*Feb 24 18:05:15.638: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Feb 24 18:05:15.638: :FEATURE_VSA attributes are: feature_name:0,feature_time:1763335456,feature_id:327
*Feb 24 18:05:15.638: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Feb 24 18:05:15.638: feature call forward featname is 3
*Feb 24 18:05:15.638: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.638: :cc_free_feature_vsa freeing 691A5D18
*Feb 24 18:05:15.638: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.638: vsacount in free is 2
*Feb 24 18:05:15.638: //219/9484DF2282AC/CCAPI/cc_api_get_ccm_detected:
CallInfo(ccm detected=FALSE)
*Feb 24 18:05:15.638: //219/9484DF2282AC/CCAPI/ccCallForwardTo:
Call Forward Number=2000, Call Forward Cause Reason=2, Call Id=219
*Feb 24 18:05:15.638: //219/9484DF2282AC/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x69D1B604, Call Id=219
*Feb 24 18:05:15.638: //219/9484DF2282AC/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
*Feb 24 18:05:15.638: //219/9484DF2282AC/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
*Feb 24 18:05:15.638: //219/9484DF2282AC/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
*Feb 24 18:05:15.646: //219/9484DF2282AC/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x69D1B604, Tag=0x0, Call Id=219,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
*Feb 24 18:05:15.646: //219/9484DF2282AC/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Feb 24 18:05:15.646: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.646: :cc_free_feature_vsa freeing 691A5B58
*Feb 24 18:05:15.646: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.646: vsacount in free is 1
*Feb 24 18:05:15.646: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Feb 24 18:05:15.646: vsacount in free is 0