Call Manager 9.1. Gateway = H.323 (Cisco 2911); with 8 POTS lines
I'm reverting calls back to their original gateway, which I SWEAR I have not modified since January. The calls hit the GW, but then I get a busy signal. What's worse, is that the only dial-peer match that I see is for an INCOMING call - which makes no sense. Why would an outbound call match an incoming dial peer?
I ran the "debug voice ccapi inout" command on the GW, and this is my output. The calling number is 0737926, the called number is: 1XXX2352022. What am I not seeing?
073-Glendale#debug voice ccapi inout
voip ccapi inout debugging is on
073-Glendale#debug vpm signal
Voice Port Module signaling debugging is enabled
073-Glendale#term mon
073-Glendale#
Apr 11 16:02:04: //-1/803349A31A00/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=0737926
----- ccCallInfo IE subfields -----
cisco-ani=0737926
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=1XXX2352022
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
Apr 11 16:02:04: //-1/803349A31A00/CCAPI/cc_api_call_setup_ind_common:
Interface=0x226AF5BC, Call Info(
Calling Number=0737926,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=1XXX2352022(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=131, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=7281
Apr 11 16:02:04: //-1/803349A31A00/CCAPI/ccCheckClipClir:
In: Calling Number=0737926(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
Apr 11 16:02:04: //-1/803349A31A00/CCAPI/ccCheckClipClir:
Out: Calling Number=0737926(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Apr 11 16:02:04: :cc_get_feature_vsa malloc success
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Apr 11 16:02:04: cc_get_feature_vsa count is 1
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Apr 11 16:02:04: :FEATURE_VSA attributes are: feature_name:0,feature_time:611628216,feature_id:7281
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=0737926(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=1XXX2352022(TON=Unknown, NPI=Unknown))
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_process_call_setup_ind:
Event=0x3D730330
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 1XXX2352022
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/ccCallSetContext:
Context=0x3F2C05CC
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 7281 with tag 131 to app "_ManagedAppProcess_Default"
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
Apr 11 16:02:04: : updating existing feature vsa
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
Apr 11 16:02:04: feature call basic
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/ccCallDisconnect:
Cause Value=3, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/ccCallDisconnect:
Cause Value=3, Call Entry(Responsed=TRUE, Cause Value=3)
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x226AF5BC, Tag=0x0, Call Id=7281,
Call Entry(Disconnect Cause=3, Voice Class Cause Code=0, Retry Count=0)
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Apr 11 16:02:04: :cc_free_feature_vsa freeing 2474B4B0
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Apr 11 16:02:04: vsacount in free is 0
073-Glendale#no debug all
All possible debugging has been turned off
I'm reverting calls back to their original gateway, which I SWEAR I have not modified since January. The calls hit the GW, but then I get a busy signal. What's worse, is that the only dial-peer match that I see is for an INCOMING call - which makes no sense. Why would an outbound call match an incoming dial peer?
I ran the "debug voice ccapi inout" command on the GW, and this is my output. The calling number is 0737926, the called number is: 1XXX2352022. What am I not seeing?
073-Glendale#debug voice ccapi inout
voip ccapi inout debugging is on
073-Glendale#debug vpm signal
Voice Port Module signaling debugging is enabled
073-Glendale#term mon
073-Glendale#
Apr 11 16:02:04: //-1/803349A31A00/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=0737926
----- ccCallInfo IE subfields -----
cisco-ani=0737926
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=1XXX2352022
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
Apr 11 16:02:04: //-1/803349A31A00/CCAPI/cc_api_call_setup_ind_common:
Interface=0x226AF5BC, Call Info(
Calling Number=0737926,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=1XXX2352022(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=131, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=7281
Apr 11 16:02:04: //-1/803349A31A00/CCAPI/ccCheckClipClir:
In: Calling Number=0737926(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
Apr 11 16:02:04: //-1/803349A31A00/CCAPI/ccCheckClipClir:
Out: Calling Number=0737926(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Apr 11 16:02:04: :cc_get_feature_vsa malloc success
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Apr 11 16:02:04: cc_get_feature_vsa count is 1
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
Apr 11 16:02:04: :FEATURE_VSA attributes are: feature_name:0,feature_time:611628216,feature_id:7281
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=0737926(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=1XXX2352022(TON=Unknown, NPI=Unknown))
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_process_call_setup_ind:
Event=0x3D730330
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 1XXX2352022
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/ccCallSetContext:
Context=0x3F2C05CC
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 7281 with tag 131 to app "_ManagedAppProcess_Default"
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
Apr 11 16:02:04: : updating existing feature vsa
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:
Apr 11 16:02:04: feature call basic
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/ccCallDisconnect:
Cause Value=3, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/ccCallDisconnect:
Cause Value=3, Call Entry(Responsed=TRUE, Cause Value=3)
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x226AF5BC, Tag=0x0, Call Id=7281,
Call Entry(Disconnect Cause=3, Voice Class Cause Code=0, Retry Count=0)
Apr 11 16:02:04: //7281/803349A31A00/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Apr 11 16:02:04: :cc_free_feature_vsa freeing 2474B4B0
Apr 11 16:02:04: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
Apr 11 16:02:04: vsacount in free is 0
073-Glendale#no debug all
All possible debugging has been turned off