cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1312
Views
0
Helpful
2
Replies

Call Forward All to External working only sometimes

mark
Level 1
Level 1

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

2 Replies 2

mark
Level 1
Level 1

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

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.

Cheers, David Trad. **When you rate a persons post, you are indicating a thank you or that it helped, but at the same time you are also helping to maintain the community spirit - You don't have to rate posts and you wont be looked down upon :) *
Getting Started

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: