09-24-2011 10:32 AM - edited 03-21-2019 04:42 AM
Hello,
I have an issue with a UC560, when we do call foward all to external numbers, sometimes it works and sometimes it doesn't. ISP obviously says it is not them. I have the same issue with 8.1.0 and tried upgrading to 8.2.0 and it still continues. Any idea's. Below is the debug that I captured.
Syslog logging: enabled (0 messages dropped, 2 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled)
No Active Message Discriminator.
No Inactive Message Discriminator.
Console logging: disabled
Monitor logging: disabled
Buffer logging: level debugging, 457 messages logged, xml disabled,
filtering disabled
Exception Logging: size (4096 bytes)
Count and timestamp logging messages: disabled
Persistent logging: disabled
No active filter modules.
Trap logging: level informational, 585 message lines logged
Logging to 10.1.1.252 (udp port 514, audit disabled,
link up),
585 message lines logged,
0 message lines rate-limited,
0 message lines dropped-by-MD,
xml disabled, sequence number disabled
filtering disabled
Log Buffer (5000000 bytes):
000863: Sep 24 11:24:33.747: %SYS-5-CONFIG_I: Configured from http by admin on 10.1.1.9
000864: Sep 24 11:24:33.983: %SYS-5-CONFIG_I: Configured from http by admin on 10.1.1.9
000865: Sep 24 11:24:34.247: %SYS-5-CONFIG_I: Configured from http by admin on 10.1.1.9
000866: Sep 24 11:24:45.079: //-1/EE26BFE3801B/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=9157903967
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=5699
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=-1
cisco-rdnplan=-1
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
000867: Sep 24 11:24:45.079: //-1/EE26BFE3801B/CCAPI/cc_api_call_setup_ind_common:
Interface=0x88958FAC, Call Info(
Calling Number=9157903967,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5699(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,
Incoming Dial-peer=3034, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=ALL_T1E1, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
000868: Sep 24 11:24:45.079: //-1/EE26BFE3801B/CCAPI/ccCheckClipClir:
In: Calling Number=9157903967(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
000869: Sep 24 11:24:45.079: //-1/EE26BFE3801B/CCAPI/ccCheckClipClir:
Out: Calling Number=9157903967(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
000870: Sep 24 11:24:45.079: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000871: Sep 24 11:24:45.079: :cc_get_feature_vsa malloc success
000872: Sep 24 11:24:45.079: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000873: Sep 24 11:24:45.079: cc_get_feature_vsa count is 3
000874: Sep 24 11:24:45.079: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000875: Sep 24 11:24:45.079: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339167792,feature_id:291
000876: Sep 24 11:24:45.079: //183/EE26BFE3801B/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=9157903967(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5699(TON=Unknown, NPI=Unknown))
000877: Sep 24 11:24:45.079: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000878: Sep 24 11:24:45.079: :Inside cc_build_feature_vsa
000879: Sep 24 11:24:45.079: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000880: Sep 24 11:24:45.079: feature call basic
000881: Sep 24 11:24:45.079: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000882: Sep 24 11:24:45.079: cc_build_feature_vsa attr is fn:TWC,ft:09/24/2011 11:24:45.079,cgn:9157903967,cdn:5699,frs:0,fid:291,fcid:EE26BFE3E60811E0801B588D93B8A91,legID:B7
000883: Sep 24 11:24:45.079: //183/EE26BFE3801B/CCAPI/cc_process_call_setup_ind:
Event=0x87C6B398
000884: Sep 24 11:24:45.079: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 5699
000885: Sep 24 11:24:45.079: //183/EE26BFE3801B/CCAPI/ccCallSetContext:
Context=0x8B7ED058
000886: Sep 24 11:24:45.083: //183/EE26BFE3801B/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 183 with tag 3034 to app "_ManagedAppProcess_Default"
000887: Sep 24 11:24:45.083: //183/EE26BFE3801B/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
000888: Sep 24 11:24:45.087: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
type=6 redirecting_number=300
000889: Sep 24 11:24:45.087: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectNumber:
type=6 Original Called Number=300, Called Number=300, Calling Number=9157903967, Calling DN=-1 Calling Id=183,
Redirect Number=95432800, Redirect Reason=15
000890: Sep 24 11:24:45.087: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
type=6 redirecting_number=300
000891: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000892: Sep 24 11:24:45.091: :cc_get_feature_vsa malloc success
000893: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000894: Sep 24 11:24:45.091: cc_get_feature_vsa count is 4
000895: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000896: Sep 24 11:24:45.091: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339168240,feature_id:292
000897: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
000898: Sep 24 11:24:45.091: feature_vsa is 8B6CDE28,featname is 0
000899: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000900: Sep 24 11:24:45.091: :cc_get_feature_vsa malloc success
000901: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000902: Sep 24 11:24:45.091: cc_get_feature_vsa count is 5
000903: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000904: Sep 24 11:24:45.091: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339166896,feature_id:293
000905: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
000906: Sep 24 11:24:45.091: : Generating new featurevsa,inserting it
000907: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
000908: Sep 24 11:24:45.091: feature call forward featname is 1
000909: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
000910: Sep 24 11:24:45.091: :cc_free_feature_vsa freeing 8B6CDFE8
000911: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
000912: Sep 24 11:24:45.091: vsacount in free is 4
000913: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000914: Sep 24 11:24:45.091: :cc_get_feature_vsa malloc success
000915: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000916: Sep 24 11:24:45.091: cc_get_feature_vsa count is 5
000917: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000918: Sep 24 11:24:45.091: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339168240,feature_id:294
000919: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
000920: Sep 24 11:24:45.091: feature call forward featname is 1
000921: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
000922: Sep 24 11:24:45.091: :cc_free_feature_vsa freeing 8B6CDFE8
000923: Sep 24 11:24:45.091: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
000924: Sep 24 11:24:45.091: vsacount in free is 4
000925: Sep 24 11:24:45.091: //183/EE26BFE3801B/CCAPI/ccGetTBCTCap:
TGRM TBCT Enabled; TBCT Capability=0
000926: Sep 24 11:24:45.091: //183/EE26BFE3801B/CCAPI/ccGetTBCTCap:
TGRM TBCT Enabled; TBCT Capability=0
000927: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=62, Params=0x8B7EE450, Progress Indication=NULL(0)
000928: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/cc_fill_tg_params:
Not a cic call
000929: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/ccCallSetupRequest:
Trunk Group Select Interface Success;
Interface=0x88958FAC, Selected Interface=10, Selected DSL=0
000930: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/ccCheckClipClir:
In: Calling Number=9155325699(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
000931: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/ccCheckClipClir:
Out: Calling Number=9155325699(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
000932: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/ccCallSetupRequest:
Destination Pattern=9[2-9]......, Called Number=5432800, Digit Strip=TRUE
000933: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/ccCallSetupRequest:
Calling Number=9155325699(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5432800(TON=Unknown, NPI=Unknown),
Redirect Number=300, Display Info=
Account Number=, Final Destination Flag=TRUE,
Guid=EE29CD44-E608-11E0-8219-A6396B2D96AD, Outgoing Dial-peer=62
000934: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=9155325699
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=5432800
cisco-desttype=0
cisco-destplan=0
cisco-rdie=73
cisco-rdn=300
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=15 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
000935: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x88958FAC, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=9155325699,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5432800(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=62, Call Count On=FALSE,
Source Trkgrp Route Label=ALL_T1E1, Target Trkgrp Route Label=ALL_T1E1, tg_label_flag=2, Application Call Id=)
000936: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000937: Sep 24 11:24:45.095: :cc_get_feature_vsa malloc success
000938: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000939: Sep 24 11:24:45.095: cc_get_feature_vsa count is 5
000940: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000941: Sep 24 11:24:45.095: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339168240,feature_id:295
000942: Sep 24 11:24:45.095: //184/EE26BFE3801B/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
000943: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/ccCallSetupRequest:
Trunk Group Call Setting;
Call Entry(Retry Count=0, Voice Class Cause Code=0)
000944: Sep 24 11:24:45.095: //184/EE26BFE3801B/CCAPI/ccCallSetContext:
Context=0x8B7EE400
000945: Sep 24 11:24:45.095: //183/EE26BFE3801B/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=62
000946: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
000947: Sep 24 11:24:45.095: feature call forward featname is 1
000948: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
000949: Sep 24 11:24:45.095: feature_vsa is 8B6CDFE8,featname is 0
000950: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000951: Sep 24 11:24:45.095: :cc_get_feature_vsa malloc success
000952: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000953: Sep 24 11:24:45.095: cc_get_feature_vsa count is 6
000954: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
000955: Sep 24 11:24:45.095: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339168464,feature_id:296
000956: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
000957: Sep 24 11:24:45.095: : Generating new featurevsa,inserting it
000958: Sep 24 11:24:45.095: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
000959: Sep 24 11:24:45.095: feature call forward featname is 1
000960: Sep 24 11:24:45.099: //183/EE26BFE3801B/CCAPI/cc_api_modify_tgt_cid_call_active_record:
Target Trkgrp Route Label=ALL_T1E1, tg_label_flag=TRUE, Call Id=0xB7
000961: Sep 24 11:24:45.123: //183/EE26BFE3801B/CCAPI/cc_api_call_facility:
Interface=0x88958FAC, Call Id=183
000962: Sep 24 11:24:45.123: //184/EE26BFE3801B/CCAPI/ccCallFacility:
Call Id=184
000963: Sep 24 11:24:45.143: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000964: Sep 24 11:24:45.143: :Inside cc_build_feature_vsa
000965: Sep 24 11:24:45.143: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000966: Sep 24 11:24:45.143: feature call forward
000967: Sep 24 11:24:45.143: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000968: Sep 24 11:24:45.143: cfna attr is fn:CFA,ft:09/24/2011 11:24:45.091,frs:0,fid:294,fcid:EE26BFE3E60811E0801B588D93B8A91,legID:B8,frson:1,fdcnt:1,fwder:300,fwdee:9157903967,fwdto:95432800,frm:300
000969: Sep 24 11:24:45.143: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000970: Sep 24 11:24:45.143: :Inside cc_build_feature_vsa
000971: Sep 24 11:24:45.143: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000972: Sep 24 11:24:45.143: feature call basic
000973: Sep 24 11:24:45.143: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000974: Sep 24 11:24:45.143: cc_build_feature_vsa attr is fn:TWC,ft:09/24/2011 11:24:45.095,cgn:9155325699,cdn:5432800,frs:0,fid:295,fcid:EE26BFE3E60811E0801B588D93B8A91,legID:B8
000975: Sep 24 11:24:45.143: //184/EE26BFE3801B/CCAPI/cc_api_call_proceeding:
Interface=0x88958FAC, Progress Indication=NULL(0)
000976: Sep 24 11:24:48.079: //183/EE26BFE3801B/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x88958FAC, Call Id=183
000977: Sep 24 11:24:48.079: //183/EE26BFE3801B/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=FALSE, Cause Value=16, Retry Count=0)
000978: Sep 24 11:24:48.079: //184/EE26BFE3801B/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
000979: Sep 24 11:24:48.083: //184/EE26BFE3801B/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=FALSE, Cause Value=16)
000980: Sep 24 11:24:48.083: //184/EE26BFE3801B/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
000981: Sep 24 11:24:48.083: //183/EE26BFE3801B/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
000982: Sep 24 11:24:48.083: //183/EE26BFE3801B/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
000983: Sep 24 11:24:48.083: //183/EE26BFE3801B/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
000984: Sep 24 11:24:48.099: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000985: Sep 24 11:24:48.099: :Inside cc_build_feature_vsa
000986: Sep 24 11:24:48.099: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000987: Sep 24 11:24:48.099: feature call forward
000988: Sep 24 11:24:48.099: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
000989: Sep 24 11:24:48.099: cfna attr is fn:CFA,ft:09/24/2011 11:24:45.091,frs:0,fid:294,fcid:EE26BFE3E60811E0801B588D93B8A91,legID:B8,frson:1,fdcnt:1,fwder:300,fwdee:9157903967,fwdto:95432800,frm:300
000990: Sep 24 11:24:48.103: //183/EE26BFE3801B/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x88958FAC, Tag=0x0, Call Id=183,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
000991: Sep 24 11:24:48.103: //183/EE26BFE3801B/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
000992: Sep 24 11:24:48.103: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
000993: Sep 24 11:24:48.103: :cc_free_feature_vsa freeing 8B6CDAA8
000994: Sep 24 11:24:48.103: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
000995: Sep 24 11:24:48.103: vsacount in free is 5
000996: Sep 24 11:24:48.103: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
000997: Sep 24 11:24:48.103: vsacount in free is 4
000998: Sep 24 11:24:48.107: //184/EE26BFE3801B/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x88958FAC, Tag=0x0, Call Id=184,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
000999: Sep 24 11:24:48.107: //184/EE26BFE3801B/CCAPI/cc_api_call_disconnect_done:
tg for this call is ALL_T1E1 success/fail is 1
001000: Sep 24 11:24:48.107: //184/EE26BFE3801B/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
001001: Sep 24 11:24:48.107: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001002: Sep 24 11:24:48.107: :cc_free_feature_vsa freeing 8B6CE0C8
001003: Sep 24 11:24:48.107: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001004: Sep 24 11:24:48.107: vsacount in free is 3
001005: Sep 24 11:24:48.107: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001006: Sep 24 11:24:48.107: vsacount in free is 2
001007: Sep 24 11:25:01.283: //-1/F7CF4805801C/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=9157903967
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=5699
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=-1
cisco-rdnplan=-1
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
001008: Sep 24 11:25:01.283: //-1/F7CF4805801C/CCAPI/cc_api_call_setup_ind_common:
Interface=0x88958FAC, Call Info(
Calling Number=9157903967,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5699(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,
Incoming Dial-peer=3034, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=ALL_T1E1, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
001009: Sep 24 11:25:01.283: //-1/F7CF4805801C/CCAPI/ccCheckClipClir:
In: Calling Number=9157903967(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001010: Sep 24 11:25:01.283: //-1/F7CF4805801C/CCAPI/ccCheckClipClir:
Out: Calling Number=9157903967(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001011: Sep 24 11:25:01.283: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001012: Sep 24 11:25:01.283: :cc_get_feature_vsa malloc success
001013: Sep 24 11:25:01.283: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001014: Sep 24 11:25:01.283: cc_get_feature_vsa count is 3
001015: Sep 24 11:25:01.283: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001016: Sep 24 11:25:01.283: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339168240,feature_id:297
001017: Sep 24 11:25:01.283: //185/F7CF4805801C/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=9157903967(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5699(TON=Unknown, NPI=Unknown))
001018: Sep 24 11:25:01.283: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001019: Sep 24 11:25:01.283: :Inside cc_build_feature_vsa
001020: Sep 24 11:25:01.283: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001021: Sep 24 11:25:01.283: feature call basic
001022: Sep 24 11:25:01.283: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001023: Sep 24 11:25:01.283: cc_build_feature_vsa attr is fn:TWC,ft:09/24/2011 11:25:01.283,cgn:9157903967,cdn:5699,frs:0,fid:297,fcid:F7CF4805E60811E0801C588D93B8A91,legID:B9
001024: Sep 24 11:25:01.283: //185/F7CF4805801C/CCAPI/cc_process_call_setup_ind:
Event=0x87C6B398
001025: Sep 24 11:25:01.283: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 5699
001026: Sep 24 11:25:01.287: //185/F7CF4805801C/CCAPI/ccCallSetContext:
Context=0x8B7F4648
001027: Sep 24 11:25:01.287: //185/F7CF4805801C/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 185 with tag 3034 to app "_ManagedAppProcess_Default"
001028: Sep 24 11:25:01.287: //185/F7CF4805801C/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
001029: Sep 24 11:25:01.291: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
type=6 redirecting_number=300
001030: Sep 24 11:25:01.291: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectNumber:
type=6 Original Called Number=300, Called Number=300, Calling Number=9157903967, Calling DN=-1 Calling Id=185,
Redirect Number=95432800, Redirect Reason=15
001031: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
type=6 redirecting_number=300
001032: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001033: Sep 24 11:25:01.295: :cc_get_feature_vsa malloc success
001034: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001035: Sep 24 11:25:01.295: cc_get_feature_vsa count is 4
001036: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001037: Sep 24 11:25:01.295: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339168464,feature_id:298
001038: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
001039: Sep 24 11:25:01.295: feature_vsa is 8B6CDFE8,featname is 0
001040: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001041: Sep 24 11:25:01.295: :cc_get_feature_vsa malloc success
001042: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001043: Sep 24 11:25:01.295: cc_get_feature_vsa count is 5
001044: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001045: Sep 24 11:25:01.295: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339167792,feature_id:299
001046: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
001047: Sep 24 11:25:01.295: : Generating new featurevsa,inserting it
001048: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
001049: Sep 24 11:25:01.295: feature call forward featname is 1
001050: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001051: Sep 24 11:25:01.295: :cc_free_feature_vsa freeing 8B6CE0C8
001052: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001053: Sep 24 11:25:01.295: vsacount in free is 4
001054: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001055: Sep 24 11:25:01.295: :cc_get_feature_vsa malloc success
001056: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001057: Sep 24 11:25:01.295: cc_get_feature_vsa count is 5
001058: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001059: Sep 24 11:25:01.295: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339168464,feature_id:300
001060: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
001061: Sep 24 11:25:01.295: feature call forward featname is 1
001062: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001063: Sep 24 11:25:01.295: :cc_free_feature_vsa freeing 8B6CE0C8
001064: Sep 24 11:25:01.295: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001065: Sep 24 11:25:01.295: vsacount in free is 4
001066: Sep 24 11:25:01.295: //185/F7CF4805801C/CCAPI/ccGetTBCTCap:
TGRM TBCT Enabled; TBCT Capability=0
001067: Sep 24 11:25:01.295: //185/F7CF4805801C/CCAPI/ccGetTBCTCap:
TGRM TBCT Enabled; TBCT Capability=0
001068: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=62, Params=0x8B7F6DE8, Progress Indication=NULL(0)
001069: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/cc_fill_tg_params:
Not a cic call
001070: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/ccCallSetupRequest:
Trunk Group Select Interface Success;
Interface=0x88958FAC, Selected Interface=12, Selected DSL=0
001071: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/ccCheckClipClir:
In: Calling Number=9155325699(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001072: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/ccCheckClipClir:
Out: Calling Number=9155325699(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001073: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/ccCallSetupRequest:
Destination Pattern=9[2-9]......, Called Number=5432800, Digit Strip=TRUE
001074: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/ccCallSetupRequest:
Calling Number=9155325699(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5432800(TON=Unknown, NPI=Unknown),
Redirect Number=300, Display Info=
Account Number=, Final Destination Flag=TRUE,
Guid=F7D25566-E608-11E0-8221-A6396B2D96AD, Outgoing Dial-peer=62
001075: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=9155325699
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=5432800
cisco-desttype=0
cisco-destplan=0
cisco-rdie=73
cisco-rdn=300
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=15 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0
001076: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x88958FAC, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=9155325699,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=5432800(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=62, Call Count On=FALSE,
Source Trkgrp Route Label=ALL_T1E1, Target Trkgrp Route Label=ALL_T1E1, tg_label_flag=2, Application Call Id=)
001077: Sep 24 11:25:01.299: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001078: Sep 24 11:25:01.299: :cc_get_feature_vsa malloc success
001079: Sep 24 11:25:01.299: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001080: Sep 24 11:25:01.299: cc_get_feature_vsa count is 5
001081: Sep 24 11:25:01.299: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001082: Sep 24 11:25:01.299: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339168464,feature_id:301
001083: Sep 24 11:25:01.299: //186/F7CF4805801C/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
001084: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/ccCallSetupRequest:
Trunk Group Call Setting;
Call Entry(Retry Count=0, Voice Class Cause Code=0)
001085: Sep 24 11:25:01.299: //186/F7CF4805801C/CCAPI/ccCallSetContext:
Context=0x8B7F6D98
001086: Sep 24 11:25:01.299: //185/F7CF4805801C/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=62
001087: Sep 24 11:25:01.303: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
001088: Sep 24 11:25:01.303: feature call forward featname is 1
001089: Sep 24 11:25:01.303: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
001090: Sep 24 11:25:01.303: feature_vsa is 8B6CE0C8,featname is 0
001091: Sep 24 11:25:01.303: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001092: Sep 24 11:25:01.303: :cc_get_feature_vsa malloc success
001093: Sep 24 11:25:01.303: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001094: Sep 24 11:25:01.303: cc_get_feature_vsa count is 6
001095: Sep 24 11:25:01.303: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
001096: Sep 24 11:25:01.303: :FEATURE_VSA attributes are: feature_name:0,feature_time:2339166896,feature_id:302
001097: Sep 24 11:25:01.303: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
001098: Sep 24 11:25:01.303: : Generating new featurevsa,inserting it
001099: Sep 24 11:25:01.303: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
001100: Sep 24 11:25:01.303: feature call forward featname is 1
001101: Sep 24 11:25:01.303: //185/F7CF4805801C/CCAPI/cc_api_modify_tgt_cid_call_active_record:
Target Trkgrp Route Label=ALL_T1E1, tg_label_flag=TRUE, Call Id=0xB9
001102: Sep 24 11:25:01.323: //185/F7CF4805801C/CCAPI/cc_api_call_facility:
Interface=0x88958FAC, Call Id=185
001103: Sep 24 11:25:01.323: //186/F7CF4805801C/CCAPI/ccCallFacility:
Call Id=186
001104: Sep 24 11:25:01.347: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001105: Sep 24 11:25:01.347: :Inside cc_build_feature_vsa
001106: Sep 24 11:25:01.347: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001107: Sep 24 11:25:01.347: feature call forward
001108: Sep 24 11:25:01.347: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001109: Sep 24 11:25:01.347: cfna attr is fn:CFA,ft:09/24/2011 11:25:01.295,frs:0,fid:300,fcid:F7CF4805E60811E0801C588D93B8A91,legID:BA,frson:1,fdcnt:1,fwder:300,fwdee:9157903967,fwdto:95432800,frm:300
001110: Sep 24 11:25:01.347: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001111: Sep 24 11:25:01.347: :Inside cc_build_feature_vsa
001112: Sep 24 11:25:01.347: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001113: Sep 24 11:25:01.347: feature call basic
001114: Sep 24 11:25:01.347: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001115: Sep 24 11:25:01.347: cc_build_feature_vsa attr is fn:TWC,ft:09/24/2011 11:25:01.299,cgn:9155325699,cdn:5432800,frs:0,fid:301,fcid:F7CF4805E60811E0801C588D93B8A91,legID:BA
001116: Sep 24 11:25:01.347: //186/F7CF4805801C/CCAPI/cc_api_call_proceeding:
Interface=0x88958FAC, Progress Indication=NULL(0)
001117: Sep 24 11:25:04.423: //185/F7CF4805801C/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x88958FAC, Call Id=185
001118: Sep 24 11:25:04.423: //185/F7CF4805801C/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=FALSE, Cause Value=16, Retry Count=0)
001119: Sep 24 11:25:04.423: //186/F7CF4805801C/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
001120: Sep 24 11:25:04.423: //186/F7CF4805801C/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=FALSE, Cause Value=16)
001121: Sep 24 11:25:04.423: //186/F7CF4805801C/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
001122: Sep 24 11:25:04.423: //185/F7CF4805801C/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
001123: Sep 24 11:25:04.423: //185/F7CF4805801C/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
001124: Sep 24 11:25:04.423: //185/F7CF4805801C/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
001125: Sep 24 11:25:04.443: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001126: Sep 24 11:25:04.443: :Inside cc_build_feature_vsa
001127: Sep 24 11:25:04.443: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001128: Sep 24 11:25:04.443: feature call forward
001129: Sep 24 11:25:04.443: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:
001130: Sep 24 11:25:04.443: cfna attr is fn:CFA,ft:09/24/2011 11:25:01.295,frs:0,fid:300,fcid:F7CF4805E60811E0801C588D93B8A91,legID:BA,frson:1,fdcnt:1,fwder:300,fwdee:9157903967,fwdto:95432800,frm:300
001131: Sep 24 11:25:04.447: //185/F7CF4805801C/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x88958FAC, Tag=0x0, Call Id=185,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
001132: Sep 24 11:25:04.447: //185/F7CF4805801C/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
001133: Sep 24 11:25:04.447: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001134: Sep 24 11:25:04.447: :cc_free_feature_vsa freeing 8B6CDE28
001135: Sep 24 11:25:04.447: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001136: Sep 24 11:25:04.447: vsacount in free is 5
001137: Sep 24 11:25:04.447: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001138: Sep 24 11:25:04.447: vsacount in free is 4
001139: Sep 24 11:25:04.451: //186/F7CF4805801C/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x88958FAC, Tag=0x0, Call Id=186,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
001140: Sep 24 11:25:04.451: //186/F7CF4805801C/CCAPI/cc_api_call_disconnect_done:
tg for this call is ALL_T1E1 success/fail is 1
001141: Sep 24 11:25:04.451: //186/F7CF4805801C/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
001142: Sep 24 11:25:04.451: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001143: Sep 24 11:25:04.451: :cc_free_feature_vsa freeing 8B6CDAA8
001144: Sep 24 11:25:04.451: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001145: Sep 24 11:25:04.451: vsacount in free is 3
001146: Sep 24 11:25:04.451: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
001147: Sep 24 11:25:04.451: vsacount in free is 2
09-24-2011 10:36 PM
I narrowed it down to this error. The funny thing is that 50% of the calls work. I am calling the ISP to see if they can troubleshoot further
Cause i = 0x82E562 - Message not compatible with call state
Maybe someone has some ideas
09-25-2011 03:23 PM
Hi Mark,
Is this an T1/E1 based system?
If it is can you please do a "debug isdn q.931" and provide the debugs of this event.
Cheers,
David.
Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: