H323 with CUCM 7

[Lulzim Islami]

Hi everyone,

I'm using CUCM 7.1.5 with Cisco 3825 router as a gateway. I have decided to migrate from MGCP to H323, but I'm having some issues with dial-peers.

I'm able to make calls but unable to receive, I'm getting busy fast tone. On CUCM side route groups, route list, and route pattern are configured correctly. Bellow is the configuration from the router, and info about my E1 line.

Number assigned from my Telco provider - 038554466 (038 is the area code)

Range of extensions in mu CUCM - 1000 - 8999

1 E1 line

Any help provided will be appreciated.

!
voice class h323 1
h225 timeout tcp establish 4
!

!
controller E1 2/0
pri-group timeslots 1-31
!
controller E1 2/1
!

interface GigabitEthernet0/1
ip address 10.117.3.254 255.255.255.0
duplex auto
speed auto
media-type rj45
h323-gateway voip interface
h323-gateway voip bind srcaddr 10.117.3.254
!
interface Serial2/0:15
no ip address
encapsulation hdlc
isdn switch-type primary-net5
isdn incoming-voice voice
no cdp enable
!

dial-peer voice 1 pots
answer-address 038554466....
destination-pattern .T
direct-inward-dial
port 2/0:15
forward-digits all
!
dial-peer voice 4 voip
destination-pattern ....
voice-class h323 1
session target ipv4:10.150.10.2
dtmf-relay h245-alphanumeric
codec g711alaw
no vad
!

Debug from debug voip dialpeer

3825voice#
Nov 10 09:29:06.139: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=038549411, Called Number=, Voice-Interface=0x68323958,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
Nov 10 09:29:06.139: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=1
Nov 10 09:29:06.139: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=038549411, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_FAX
Nov 10 09:29:06.139: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
Nov 10 09:29:06.139: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=, Peer Info Type=DIALPEER_INFO_SPEECH
Nov 10 09:29:06.139: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
Nov 10 09:29:06.139: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
   Result=NO_MATCH(-1)
Nov 10 09:29:06.143: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=, Peer Info Type=DIALPEER_INFO_SPEECH
Nov 10 09:29:06.143: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
  
3825voice#No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
Nov 10 09:29:06.143: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=NO_MATCH(-1)
Nov 10 09:29:06.143: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
Nov 10 09:29:06.147: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
3825voice#

[kvancoil]

Hi Valdet,

From the debugs there is no matching outgoing dial-peer for the incoming number; your outgoing dial-peer has "destination-pattern ...." so probably the incoming called number does not match this. If you don't know the number format you are getting from the provider, could you get the below debugs please:

debug voip ccapi inout

debug isdn q931

If you know the incoming called number from the provider, we can just adapt the destination-pattern.

Thanks,

Kristof

[Lulzim Islami]

Hi Kristof,

Here is the debug output. Thanks,

3825voice#debug voip ccapi inout
voip ccapi inout debugging is on
3825voice#
3825voice#debug isdn q931
debug isdn q931 is              ON.
3825voice#term mon
3825voice#
Nov 10 11:31:15.709: ISDN Se2/0:15 Q931: RX <- SETUP pd = 8  callref = 0x14E6
        Bearer Capability i = 0x9090A3
                Standard = CCITT
                Transfer Capability = 3.1kHz Audio
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0xA9839F
                Exclusive, Channel 31
        Progress Ind i = 0x8283 - Origination address is non-ISDN 
        Calling Party Number i = 0x0083, '038549411'
                Plan:Unknown, Type:Unknown
Nov 10 11:31:15.709: //-1/DD05CB9780BB/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=038549411
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=3
   dest=
   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

Nov 10 11:31:15.709: //-1/DD05CB9780BB/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x68323958, Call Info(
   Calling Number=038549411,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed),
   Called Number=(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,
   Incoming Dial-peer=1, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
Nov 10 11:31:15.709: //-1/DD05CB9780BB/CCAPI/ccCheckClipClir:
   In: Calling Number=038549411(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed)
Nov 10 11:31:15.709: //-1/DD05CB9780BB/CCAPI/ccCheckClipClir:
   Out: Calling Number=038549411(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed)
Nov 10 11:31:15.709: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Nov 10 11:31:15.709: :cc_get_feature_vsa malloc success
Nov 10 11:31:15.709: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Nov 10 11:31:15.709:  cc_get_feature_vsa count is 1
Nov 10 11:31:15.709: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Nov 10 11:31:15.709: :FEATURE_VSA attributes are: feature_name:0,feature_time:1746041464,feature_id:365
Nov 10 11:31:15.709: //365/DD05CB9780BB/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=038549411(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed),
   Called Number=(TON=Unknown, NPI=Unknown))
Nov 10 11:31:15.713: //365/DD05CB9780BB/CCAPI/cc_process_call_setup_ind:
   Event=0x7065B990
Nov 10 11:31:15.713: //365/DD05CB9780BB/CCAPI/ccCallSetContext:
   Context=0x6695E394
Nov 10 11:31:15.713: //365/DD05CB9780BB/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 365 with tag 1 to app "_ManagedAppProcess_Default"
Nov 10 11:31:15.713: //365/DD05CB9780BB/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
Nov 10 11:31:15.713: ISDN Se2/0:15 Q931: TX -> CALL_PROC pd = 8  callref = 0x94E6
        Channel ID i = 0xA9839F
                Exclusive, Channel 31
Nov 10 11:31:15.713: //365/DD05CB9780BB/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Nov 10 11:31:15.713: //365/DD05CB9780BB/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
Nov 10 11:31:15.713: //365/DD05CB9780BB/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
Nov 10 11:31:15.717: ISDN Se2/0:15 Q931: TX -> DISCONNECT pd = 8  callref = 0x94E6
        Cause i = 0x8090 - Normal call clearing
Nov 10 11:31:15.781: ISDN Se2/0:15 Q931: RX <- RELEASE pd = 8  callref = 0x14E6
Nov 10 11:31:15.781: ISDN Se2/0:15 Q931: TX -> RELEASE_COMP pd = 8  callref = 0x94E6
Nov 10 11:31:15.781: //365/DD05CB9780BB/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x68323958, Tag=0x0, Call Id=365,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
Nov 10 11:31:15.781: //365/DD05CB9780BB/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Nov 10 11:31:15.781: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Nov 10 11:31:15.781: :cc_free_feature_vsa freeing 68127A70
Nov 10 11:31:15.781: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
3825voice#
Nov 10 11:31:15.781:  vsacount in free is 0
3825voice#

[kvancoil]

Hi Valdet,

We don't receive a called number in the setup from the provider (I assume this connects to Telco?):

"

Nov 10 11:31:15.709: ISDN Se2/0:15 Q931: RX <- SETUP pd = 8  callref =  0x14E6
        Bearer Capability i = 0x9090A3
                 Standard = CCITT
                Transfer Capability = 3.1kHz Audio
                 Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
         Channel ID i = 0xA9839F
                Exclusive, Channel 31
         Progress Ind i = 0x8283 - Origination address is non-ISDN 
         Calling Party Number i = 0x0083, '038549411'
                 Plan:Unknown, Type:Unknown

"

Can you verify that switch type and other configuratino of your E1 line is correct? Did you configure your GW to synchronize with the clock from the Telco (network-clock-participate and network-clock-select):

http://www.cisco.com/en/US/docs/ios/12_3/vvf_r/vrg_n1_ps1839_TSD_Products_Command_Reference_Chapter.html#wp1001437

You need to configure this to avoid for slips, can you show me the output of 'show controllers e1'?

If all this seems to be correct it might be good to check with Telco why they are not sending the called number in the setup.

Cheers,

Kristof

[Lulzim Islami]

Hi Kristof,

Here is the output from show controllers E1?

3825voice#show controllers e1
E1 2/0 is up.
  Applique type is Channelized E1 - balanced
  No alarms detected.
  alarm-trigger is not set
  Version info Firmware: 20070321, FPGA: 255, spm_count = 0
  Framing is CRC4, Line Code is HDB3, Clock Source is Line.
  Current port master clock:recovered from controller 2/0
  Data in current interval (822 seconds elapsed):
     0 Line Code Violations, 0 Path Code Violations
     0 Slip Secs, 0 Fr Loss Secs, 0 Line Err Secs, 0 Degraded Mins
     0 Errored Secs, 0 Bursty Err Secs, 0 Severely Err Secs, 0 Unavail Secs
  Total Data (last 95 15 minute intervals):
     0 Line Code Violations, 0 Path Code Violations,
     36 Slip Secs, 10 Fr Loss Secs, 0 Line Err Secs, 0 Degraded Mins,
     2 Errored Secs, 0 Bursty Err Secs, 0 Severely Err Secs, 670 Unavail Secs

Thanks

[kvancoil]

Hi Valdet,

Looking at the output your clock is not configured properly as you have slips: '36 Slip Secs" Can you please configure the clock particite and clock select to clear those?

Thanks,

Kristof

[Walter Solano]

Hello Valdet

I checked the debugs that you provide us and it seams like the Telco is not sending the called number in the setup message and that could be the reason why the calls are falling. Could you please try to enable Overlap Receiving Flag for H.323 service parameter in the CUCM, if after that you still having the issue please capture the following debugs: debug voice ccapi, debug h225 asn1, debug isdn Q931 and send us the a full show run and show isdn status.

HTH

Please rate this post if was helpful

Walter Solano

[kvancoil]

Hi Walter,

Changing the service parameter on CUCM will not help us to get a called number on ISDN level, if Telco is indeed using overlap sending we need to configure overlap receiving on the gateway under the serial interface: "isdn overlap-receiving"

Valdet, do you know if the Telco is sending the called number using overlap-sending? Please also make the adjustments to the clock synchronisation as mentioned in my previous post.

Cheers,

Kristof

[Lulzim Islami]

Hi Kristof,

I made the adjustment to the clock syncronisation and slips are gone now. After configuring overlap receiving I'm able to receive calls now.

Support from my telco provider is very limited, I'm stuck in finding backdoors to resolve the issues. I still see the TON and many informations as unknown, if you have any idea please let me know.

Here is the debug output from the call.

3825voice#
3825voice#
Nov 10 14:53:51.918: ISDN Se2/0:15 Q931: RX <- SETUP pd = 8  callref = 0x14EE
        Bearer Capability i = 0x9090A3
                Standard = CCITT
                Transfer Capability = 3.1kHz Audio
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0xA9838A
                Exclusive, Channel 10
        Progress Ind i = 0x8283 - Origination address is non-ISDN 
        Calling Party Number i = 0x0083, '038549411'
                Plan:Unknown, Type:Unknown
Nov 10 14:53:51.922: ISDN Se2/0:15 Q931: TX -> SETUP_ACK pd = 8  callref = 0x94EE
        Channel ID i = 0xA9838A
                Exclusive, Channel 10
Nov 10 14:53:52.030: ISDN Se2/0:15 Q931: RX <- INFORMATION pd = 8  callref = 0x14EE
        Called Party Number i = 0x81, '1'
                Plan:ISDN, Type:Unknown
Nov 10 14:53:52.034: //-1/2AB0136D80C3/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=038549411
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=3
   dest=1
   cisco-desttype=0
   cisco-destplan=1
   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

Nov 10 14:53:52.034: //-1/2AB0136D80C3/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x68323958, Call Info(
   Calling Number=038549411,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed),
   Called Number=1(TON=Unknown, NPI=ISDN),
   Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,
   Incoming Dial-peer=2, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
Nov 10 14:53:52.034: //-1/2AB0136D80C3/CCAPI/ccCheckClipClir:
   In: Calling Number=038549411(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed)
Nov 10 14:53:52.034: //-1/2AB0136D80C3/CCAPI/ccCheckClipClir:
   Out: Calling Number=038549411(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed)
Nov 10 14:53:52.034: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Nov 10 14:53:52.034: :cc_get_feature_vsa malloc success
Nov 10 14:53:52.034: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Nov 10 14:53:52.034:  cc_get_feature_vsa count is 1
Nov 10 14:53:52.034: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Nov 10 14:53:52.034: :FEATURE_VSA attributes are: feature_name:0,feature_time:1746041464,feature_id:402
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=038549411(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed),
   Called Number=1(TON=Unknown, NPI=ISDN))
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/cc_process_call_setup_ind:
   Event=0x7065B990
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/ccCallSetContext:
   Context=0x6695A9D4
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 402 with tag 2 to app "_ManagedAppProcess_Default"
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/ccCallSetupAck:
   Call Id=402
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/cc_api_set_transfer_info:
   Transfer Number=, Transfer Reason=0x0
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=TRUE, Tone=Dial Tone,
   Tone Direction=Network, Params=0x0, Call Id=402
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/ccSetDigitTimeouts:
   Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/ccSetDigitTimeouts:
   Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
Nov 10 14:53:52.034: //402/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:
   (callID=0x192, digit_event=0x1, enable=TRUE, consume=FALSE)
3825voice#
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=402
Nov 10 14:53:52.034: //402/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x68323958, callID=0x192, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x68323958, Call Id=402
Nov 10 14:53:52.034: //402/2AB0136D80C3/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms))
Nov 10 14:53:52.226: ISDN Se2/0:15 Q931: RX <- INFORMATION pd = 8  callref = 0x14EE
        Called Party Number i = 0x81, '1'
                Plan:ISDN, Type:Unknown
Nov 10 14:53:52.226: //402/2AB0136D80C3/CCAPI/cc_api_call_info:
   Info Digits=1, Info Complete=FALSE,
   Interface=0x68323958, Data Bitmask=0x0, Call Id=402
Nov 10 14:53:52.418: ISDN Se2/0:15 Q931: RX <- INFORMATION pd = 8  callref = 0x14EE
        Called Party Number i = 0x81, '1'
                Plan:ISDN, Type:Unknown
Nov 10 14:53:52.422: //402/2AB0136D80C3/CCAPI/cc_api_call_info:
   Info Digits=1, Info Complete=FALSE,
   Interface=0x68323958, Data Bitmask=0x0, Call Id=402
Nov 10 14:53:52.618: ISDN Se2/0:15 Q931: RX <- INFORMATION pd = 8  callref = 0x14EE
        Called Party Number i = 0x81, '7'
                Plan:ISDN, Type:Unknown
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/cc_api_call_info:
   Info Digits=7, Info Complete=FALSE,
   Interface=0x68323958, Data Bitmask=0x0, Call Id=402
Nov 10 14:53:52.618: //402/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:
   (callID=0x192, digit_event=0x0, enable=FALSE, consume=FALSE)
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=402
Nov 10 14:53:52.618: //402/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x68323958, callID=0x192, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x68323958, Call Id=402
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms))
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=4, Params=0x6695C704, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/ccCheckClipClir:
   In: Calling Number=038549411(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed)
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/ccCheckClipClir:
   Out: Calling Number=038549411(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed)
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/ccCallSetupRequest:
   Destination Pattern=...., Called Number=1117, Digit Strip=FALSE
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/ccCallSetupRequest:
   Calling Number=038549411(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed),
   Called Number=1117(TON=Unknown, NPI=ISDN),
   Redirect Number=, Display Info=
   Account Number=, Final Destination Flag=FALSE,
   Guid=2AB0136D-EC11-11DF-80C3-001D70381690, Outgoing Dial-peer=4
Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=038549411
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=3
   dest=1117
   cisco-desttype=0
   cisco-destplan=1
   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

Nov 10 14:53:52.618: //402/2AB0136D80C3/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x67D6D490, Interface Type=1, Destination=, Mode=0x0,
   Call Params(Calling Number=038549411,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Network, Presentation=Allowed),
   Called Number=1117(TON=Unknown, NPI=ISDN), Calling Translated=FALSE,
   Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=4, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
Nov 10 14:53:52.618: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Nov 10 14:53:52.618: :cc_get_feature_vsa malloc success
Nov 10 14:53:52.618: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Nov 10 14:53:52.618:  cc_get_feature_vsa count is 2
Nov 10 14:53:52.618: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
  
Nov 10 14:53:52.618: :FEATURE_VSA attributes are: feature_name:0,feature_time:1746041248,feature_id:403
Nov 10 14:53:52.622: //403/2AB0136D80C3/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1
Nov 10 14:53:52.622: //403/2AB0136D80C3/CCAPI/ccCallSetContext:
   Context=0x6695C6B4
Nov 10 14:53:52.622: //402/2AB0136D80C3/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=4
Nov 10 14:53:52.726: //403/2AB0136D80C3/CCAPI/cc_api_set_called_ccm_detected:
   CallInfo(called ccm detected=TRUE ccmVersion 3)
Nov 10 14:53:52.726: //403/2AB0136D80C3/CCAPI/cc_api_call_proceeding:
   Interface=0x67D6D490, Progress Indication=NULL(0)
Nov 10 14:53:52.726: //402/2AB0136D80C3/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
Nov 10 14:53:52.726: ISDN Se2/0:15 Q931: TX -> CALL_PROC pd = 8  callref = 0x94EE
Nov 10 14:53:52.730: //403/2AB0136D80C3/CCAPI/cc_api_set_called_ccm_detected:
   CallInfo(called ccm detected=TRUE ccmVersion 3)
Nov 10 14:53:52.730: //403/2AB0136D80C3/CCAPI/cc_api_set_delay_xport:
   CallInfo(delay xport=TRUE)
Nov 10 14:53:52.730: //403/2AB0136D80C3/CCAPI/cc_api_call_alert:
   Interface=0x67D6D490, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
Nov 10 14:53:52.730: //403/2AB0136D80C3/CCAPI/cc_api_call_alert:
   Call Entry(Retry Count=0, Responsed=TRUE)
Nov 10 14:53:52.730: //402/2AB0136D80C3/CCAPI/ccCallAlert:
   Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
Nov 10 14:53:52.730: //402/2AB0136D80C3/CCAPI/ccCallAlert:
   Call Entry(Responsed=TRUE, AlertSent=TRUE)
Nov 10 14:53:52.730: ISDN Se2/0:15 Q931: TX -> ALERTING pd = 8  callref = 0x94EE
Nov 10 14:53:52.926: //403/2AB0136D80C3/CCAPI/cc_api_set_called_ccm_detected:
  
3825voice#CallInfo(called ccm detected=TRUE ccmVersion 3)
Nov 10 14:53:52.926: //403/2AB0136D80C3/CCAPI/cc_api_call_notify:
   Data Bitmask=0x5, Interface=0x67D6D490, Call Id=403
Nov 10 14:53:52.926: //403/2AB0136D80C3/CCAPI/cc_api_get_ssCTreRoutingNotSupported:
   CallInfo(ssCTreRoutingNotSupported=FALSE)
Nov 10 14:53:52.926: //403/2AB0136D80C3/CCAPI/cc_api_get_ccm_detected:
   CallInfo(ccm detected=TRUE)
Nov 10 14:53:52.926: //402/2AB0136D80C3/CCAPI/ccCallNotify:
   Data Bitmask=0x5, Call Id=402
3825voice#
Nov 10 14:53:58.190: //403/2AB0136D80C3/CCAPI/cc_api_call_connected:
   Interface=0x67D6D490, Data Bitmask=0x0, Progress Indication=NULL(0),
   Connection Handle=0
Nov 10 14:53:58.190: //403/2AB0136D80C3/CCAPI/cc_api_call_connected:
   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
Nov 10 14:53:58.190: //403/2AB0136D80C3/CCAPI/cc_api_set_called_ccm_detected:
   CallInfo(called ccm detected=TRUE ccmVersion 3)
Nov 10 14:53:58.190: //403/2AB0136D80C3/CCAPI/cc_api_call_notify:
   Data Bitmask=0x5, Interface=0x67D6D490, Call Id=403
Nov 10 14:53:58.190: //402/2AB0136D80C3/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=FALSE, Tone=Null,
   Tone Direction=Network, Params=0x0, Call Id=402
Nov 10 14:53:58.190: //402/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
   (confID=0x707D264C, callID1=0x192, callID2=0x193, tag=0x0)
Nov 10 14:53:58.190: //402/2AB0136D80C3/CCAPI/ccConferenceCreate:
   Conference Id=0x707D264C, Call Id1=402, Call Id2=403, Tag=0x0
Nov 10 14:53:58.190: //402/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
   Conference Id=0x4D, Source Interface=0x68323958, Source Call Id=402,
   Destination Call Id=403, Disposition=0x0, Tag=0xFFFFFFFF
Nov 10 14:53:58.190: //403/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
  
Nov 10 14:53:58.190: cc_api_get_xcode_stream : 4369
Nov 10 14:53:58.190: //403/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
   Conference Id=0x4D, Source Interface=0x67D6D490, Source Call Id=403,
   Destination Call Id=402, Disposition=0x0, Tag=0x0
Nov 10 14:53:58.190: //402/2AB0136D80C3/CCAPI/cc_generic_bridge_done:
   Conference Id=0x4D, Source Interface=0x67D6D490, Source Call Id=403,
   Destination Call Id=402, Disposition=0x0, Tag=0x0
Nov 10 14:53:58.190: //402/2AB0136D80C3/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x4D, Destination Call Id=403)
Nov 10 14:53:58.190: //403/2AB0136D80C3/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x4D, Destination Call Id=402)
Nov 10 14:53:58.190: //402/2AB0136D80C3/CCAPI/cc_api_caps_ind:
   Destination Interface=0x67D6D490, Destination Call Id=403, Source Call Id=402,
   Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,
   Modem=0x2, Codec Bytes=20, Signal Type=3)
Nov 10 14:53:58.190: //402/2AB0136D80C3/CCAPI/cc_api_caps_ind:
   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
   Playout Max=250(ms), Fax Nom=300(ms))
Nov 10 14:53:58.190: //402/2AB0136D80C3/CCAPI/cc_process_notify_bridge_done:
   Conference Id=0x4D, Call Id1=402, Call Id2=403
Nov 10 14:53:58.190: //403/2AB0136D80C3/CCAPI/cc_api_caps_ind:
   Destination Interface=0x68323958, Destination Call Id=402, Source Call Id=403,
   Caps(Codec=0x1, Fax Rate=0x2, Vad=0x1,
   Modem=0x0, Codec Bytes=80, Signal Type=2)
Nov 10 14:53:58.190: //403/2AB0136D80C3/CCAPI/cc_api_caps_ind:
   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
   Playout Max=250(ms), Fax Nom=300(ms))
Nov 10 14:53:58.190: //403/2AB0136D80C3/CCAPI/cc_api_caps_ack:
   Destination Interface=0x68323958, Destination Call Id=402, Source Call Id=403,
   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=80, Signal Type=2, Seq Num Start=8469)
Nov 10 14:53:58.194: //402/2AB0136D80C3/CCAPI/cc_api_caps_ack:
   Destination Interface=0x67D6D490, Destination Call Id=403, Source Call Id=402,
   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=80, Signal Type=2, Seq Num Start=8469)
Nov 10 14:53:58.194: //403/2AB0136D80C3/CCAPI/cc_api_get_ssCTreRoutingNotSupported:
   CallInfo(ssCTreRoutingNotSupported=FALSE)
Nov 10 14:53:58.194: //403/2AB0136D80C3/CCAPI/cc_api_get_ccm_detected:
   CallInfo(ccm detected=TRUE)
Nov 10 14:53:58.194: //402/2AB0136D80C3/CCAPI/ccCallNotify:
   Data Bitmask=0x5, Call Id=402
Nov 10 14:53:58.194: //402/2AB0136D80C3/CCAPI/cc_api_voice_mode_event:
   Call Id=402
Nov 10 14:53:58.194: //402/2AB0136D80C3/CCAPI/cc_api_voice_mode_event:
   Call Entry(Context=0x6695A9D4)
Nov 10 14:53:58.194: //402/2AB0136D80C3/CCAPI/ccCallConnect:
   Progress Indication=NULL(0), Data Bitmask=0x0
Nov 10 14:53:58.194: //402/2AB0136D80C3/CCAPI/ccCallConnect:
   Call Entry(Connected=TRUE, Responsed=TRUE)
Nov 10 14:53:58.194: ISDN Se2/0:15 Q931: TX -> CONNECT pd = 8  callref = 0x94EE
Nov 10 14:53:58.234: ISDN Se2/0:15 Q931: RX <- CONNECT_ACK pd = 8  callref = 0x14EE
3825voice#
Nov 10 14:53:58.238: %ISDN-6-CONNECT: Interface Serial2/0:9 is now connected to 038549411 N/A
3825voice#
Nov 10 14:54:02.810: ISDN Se2/0:15 Q931: RX <- DISCONNECT pd = 8  callref = 0x14EE
        Cause i = 0x8090 - Normal call clearing
        Progress Ind i = 0x8288 - In-band info or appropriate now available 
        Progress Ind i = 0x8283 - Origination address is non-ISDN
Nov 10 14:54:02.810: %ISDN-6-CONNECT: Interface Serial2/0:9 is now connected to 038549411 N/A
Nov 10 14:54:02.810: %ISDN-6-DISCONNECT: Interface Serial2/0:9  disconnected from 038549411 , call lasted 4 seconds
Nov 10 14:54:02.810: ISDN Se2/0:15 Q931: call_disc: PI received in disconnect; Postpone sending RELEASE for callid 0xC3
Nov 10 14:54:02.810: //402/2AB0136D80C3/CCAPI/cc_api_call_disc_prog_ind:
   Cause Value=16, Progress Indication=INBAND(8), Interface=0x68323958
Nov 10 14:54:02.810: //402/2AB0136D80C3/CCAPI/cc_api_call_disc_prog_ind:
   Event(Progress Indication=0x0), Call Entry(Responsed=TRUE)
Nov 10 14:54:02.810: //402/2AB0136D80C3/CCAPI/ccConferenceDestroy:
   Conference Id=0x4D, Tag=0x0
Nov 10 14:54:02.810: //402/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
   Conference Id=0x4D, Source Interface=0x68323958, Source Call Id=402,
   Destination Call Id=403, Disposition=0x0, Tag=0x0
Nov 10 14:54:02.810: //403/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
   Conference Id=0x4D, Source Interface=0x67D6D490, Source Call Id=403,
   Destination Call Id=402, Disposition=0x0, Tag=0x0
Nov 10 14:54:02.810: //402/2AB0136D80C3/CCAPI/cc_generic_bridge_done:
   Conference Id=0x4D, Source Interface=0x67D6D490, Source Call Id=403,
   Destination Call Id=402, Disposition=0x0, Tag=0x0
Nov 10 14:54:02.814: //402/2AB0136D80C3/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
Nov 10 14:54:02.814: //402/2AB0136D80C3/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
Nov 10 14:54:02.814: //402/2AB0136D80C3/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
Nov 10 14:54:02.814: //403/2AB0136D80C3/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
Nov 10 14:54:02.814: //403/2AB0136D80C3/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
Nov 10 14:54:02.814: //403/2AB0136D80C3/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
Nov 10 14:54:02.814: //403/2AB0136D80C3/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x67D6D490, Tag=0x0, Call Id=403,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
Nov 10 14:54:02.814: //403/2AB0136D80C3/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Nov 10 14:54:02.814: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Nov 10 14:54:02.814: :cc_free_feature_vsa freeing 68127998
Nov 10 14:54:02.814: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Nov 10 14:54:02.814:  vsacount in free is 1
Nov 10 14:54:02.818: //402/2AB0136D80C3/CCAPI/cc_api_call_disc_cause_update:
   Cause Value=16
Nov 10 14:54:02.818: //402/2AB0136D80C3/CCAPI/cc_api_call_disc_cause_update:
   Call Entry(Disconnect Cause=16)
Nov 10 14:54:02.818: //402/2AB0136D80C3/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x68323958, Tag=0x0, Call Id=402,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
Nov 10 14:54:02.818: //402/2AB0136D80C3/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
Nov 10 14:54:02.818: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Nov 10 14:54:02.818: :cc_free_feature_vsa freeing 68127A70
Nov 10 14:54:02.818: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
  
Nov 10 14:54:02.818:  vsacount in free is 0
Nov 10 14:54:02.818: ISDN Se2/0:15 Q931: TX -> RELEASE pd = 8  callref = 0x94EE
3825voice#
Nov 10 14:54:02.838: ISDN Se2/0:15 Q931: RX <- RELEASE_COMP pd = 8  callref = 0x14EE
3825voice#

[Lulzim Islami]

Well, it turned up another issue, I can receive a calls, but

sometimes I'm not able to receive calls from Cell Phones. I'm getting the message from my telco

provider that the number does not exist. I'm attaching the running config, debug from call made from Lan

d Line and Cell phone.

Thanks

[kvancoil · Accepted solution]

Hi Valdet,

Slips are gone which is good (this can cause quality problems or issues with faxes); the issue with the called number was because telco is indeed sending the called party using overlap-sending. You don't need to worry about the 'unknown' fields, that is not mandatory and is something which the telco decides.

I checked the debugs and for the failing call it is using dial-peer 1 for outgoing: "Outgoing Dial-peer=1". So basically it is sending the call back to the Telco. For the successfull call it is using dial-peer 4 for the outgoing, which is what you want to achieve. To understand how dial-peers are matched you can check the below document: http://www.cisco.com/en/US/tech/tk652/tk90/technologies_tech_note09186a008010fed1.shtml

Basically we need to make it the most specific match, you could change your destination-pattern on dial-peer 4 for example to:

destination-pattern [1-8]...

Cheers,

Kristof