i,

ergin.sezgin · ‎04-01-2016

Hi,

I used call manager express 10.5. I want outgoing call to PSTN via FXO port but SIP 486 Busy Here. Config and debug attached.

Incoming calls working not problem but outgoing call not working.

Example call:

1214 local sip phone

(9)0506132XXXX external phone ( 9 prefix )

VoiceEncapPeer12
   peer type = voice, system default peer = FALSE, information type = voice,
   description = `Sehirlerarasi Aramalar',
   tag = 12, destination-pattern = `90[2-5].........',
   voice reg type = 0, corresponding tag = 0,
   allow watch = FALSE
   answer-address = `', preference=1,
   CLID Restriction = None
   CLID Network Number = `'
   CLID Second Number sent
   CLID Override RDNIS = disabled,
   rtp-ssrc mux = system
   source carrier-id = `',   target carrier-id = `',
   source trunk-group-label = `',   target trunk-group-label = `',
   numbering Type = `unknown'
   group = 12, Admin state is up, Operation state is up,
   Outbound state is up,
   incoming called-number = `.',
connections/maximum = 0/unlimited,
   DTMF Relay = disabled,
   URI classes:
        Destination =
   Destination route-string = None
   huntstop = disabled,
   in bound application associated: 'DEFAULT'
   out bound application associated: ''
   dnis-map =
   permission :both
   incoming COR list:maximum capability
   outgoing COR list:minimum requirement
   Translation profile (Incoming):
   Translation profile (Outgoing):outbound
   incoming call blocking:
   translation-profile = `'
   disconnect-cause = `no-service'
   advertise 0x40 capacity_update_timer 25 addrFamily 4 oldAddrFamily 4
   mailbox selection policy: none
   type = pots, prefix = `',
   forward-digits 11
   session-target = `', voice-port = `0/0/0',
   direct-inward-dial = enabled,
   digit_strip = disabled,
   register E.164 number with H323 GK and/or SIP Registrar = TRUE
   fax rate = system,   payload size = 20 bytes
   supported-language = ''
   preemption level = `routine'
   bandwidth:
        maximum = 64 KBits/sec, minimum = 64 KBits/sec
   voice class called-number:
        inbound = `', outbound = `'
   dial tone generation after remote onhook = enabled
   mobility=0, snr=, snr_noan=, snr_delay=0, snr_timeout=0
   snr calling-number local=disabled, snr ring-stop=disabled, snr answer-too-soon timer=0
   Time elapsed since last clearing of voice call statistics never
   Connect Time = 0, Charged Units = 0,
   Successful Calls = 0, Failed Calls = 0, Incomplete Calls = 0
   Accepted Calls = 0, Refused Calls = 0,
   Last Disconnect Cause is "",
        Last Disconnect Text is "",
   Last Setup Time = 0.
   Last Disconnect Time = 0.
Matched: 90532XXXXX   Digits: 3   Matched pattern: 90[2-5]......... Preference: 1
Target:

VoiceEncapPeer15
   peer type = voice, system default peer = FALSE, information type = voice,
   description = `Sehirlerarasi Aramalar',
   tag = 15, destination-pattern = `90[2-5].........',
   voice reg type = 0, corresponding tag = 0,
   allow watch = FALSE
   answer-address = `', preference=2,
   CLID Restriction = None
   CLID Network Number = `'
   CLID Second Number sent
   CLID Override RDNIS = disabled,
   rtp-ssrc mux = system
   source carrier-id = `',   target carrier-id = `',
   source trunk-group-label = `',   target trunk-group-label = `',
   numbering Type = `unknown'
   group = 15, Admin state is up, Operation state is up,
   Outbound state is up,
   incoming called-number = `.',
connections/maximum = 0/unlimited,
   DTMF Relay = disabled,
   URI classes:
        Destination =
   Destination route-string = None
   huntstop = disabled,
   in bound application associated: 'DEFAULT'
   out bound application associated: ''
   dnis-map =
   permission :both
   incoming COR list:maximum capability
   outgoing COR list:minimum requirement
   Translation profile (Incoming):
   Translation profile (Outgoing):outbound
   incoming call blocking:
   translation-profile = `'
   disconnect-cause = `no-service'
   advertise 0x40 capacity_update_timer 25 addrFamily 4 oldAddrFamily 4
   mailbox selection policy: none
   type = pots, prefix = `',
   forward-digits 11
   session-target = `', voice-port = `0/0/1',
   direct-inward-dial = enabled,
   digit_strip = disabled,
   register E.164 number with H323 GK and/or SIP Registrar = TRUE
   fax rate = system,   payload size = 20 bytes
   supported-language = ''
   preemption level = `routine'
   bandwidth:
        maximum = 64 KBits/sec, minimum = 64 KBits/sec
   voice class called-number:
        inbound = `', outbound = `'
   dial tone generation after remote onhook = enabled
   mobility=0, snr=, snr_noan=, snr_delay=0, snr_timeout=0
   snr calling-number local=disabled, snr ring-stop=disabled, snr answer-too-soon timer=0
   Time elapsed since last clearing of voice call statistics never
        Connect Time = 0, Charged Units = 0,
   Successful Calls = 0, Failed Calls = 0, Incomplete Calls = 0
   Accepted Calls = 0, Refused Calls = 0,
   Last Disconnect Cause is "",
   Last Disconnect Text is "",
   Last Setup Time = 0.
   Last Disconnect Time = 0.
Matched: 90532XXXXX   Digits: 3   Matched pattern: 90[2-5]......... Preference: 2
Target:

acampbell · ‎04-01-2016

i,

You are matching the dial peers 12 & 15 -- Good

Both are failing due to disconnect-cause = `no-service'

Looks more like you have an FXO to service provider issue

Try
debug vpm signal

This should show the FXO siezing and sending digits to line etc

This link will help you
http://www.cisco.com/c/en/us/support/docs/voice/h323/14081-voip-debugcalls.html

Regards
Alex

Regards, Alex. Please rate useful posts.

ergin.sezgin · ‎04-01-2016

I showed the incoming fxo but ı can try outgoing call not showing true fxo.

Dial pattern is matched.

dial-peer voice 16 pots
destination-pattern 0532549XXXX
no digit-strip
port 0/0/1
forward-digits 11

voice-port 0/0/1

description Outbound call Port
no battery-reversal
signal groundStart
no echo-cancel enable
cptone TR
caller-id enable

voice-port 0/0/3

description Inbound Call Port

no battery-reversal
signal groundStart
no echo-cancel enable
cptone TR
caller-id enable

*Apr 1 21:29:06.719: vsacount in free is 4
*Apr 1 21:29:06.719: //69/93C644718078/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.191:5060;branch=z9hG4bKgyvE6URaQy4ETUJlunjbXw~~58
From: <sip:2001@192.168.1.191>;tag=ds4ed3a1c6
To: <sip:0532549XXXX@192.168.1.190>;tag=202B9C-1924
Date: Fri, 01 Apr 2016 21:29:06 GMT
Call-ID: 93C818E9-F78711E5-807DAC18-E68D78A7@192.168.1.190
Server: Cisco-SIPGateway/IOS-15.4.3.M3
CSeq: 2 BYE
P-RTP-Stat: PS=423,OS=67680,PR=300,OR=47841,PL=0,JI=0,LA=0,DU=9
Content-Length: 0

*Apr 1 21:29:06.719: //-1/93C644718078/DPM/dpMatchCore:
   Dial String=0532549XXXX, Expanded String=0532549XXXX, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Apr 1 21:29:06.719: //-1/93C644718078/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=16 Is Matched
*Apr 1 21:29:06.719: //68/93C644718078/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=0
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Apr 1 21:29:06.719: :cc_get_feature_vsa malloc success
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Apr 1 21:29:06.719: cc_get_feature_vsa count is 5
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Apr 1 21:29:06.719: :FEATURE_VSA attributes are: feature_name:0,feature_time:1099789672,feature_id:54
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=0532549XXXX, Expanded String=0532549XXXX, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=16 Is Matched
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=0532549XXXX, Expanded String=0532549XXXX, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=16 Is Matched
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Apr 1 21:29:06.719: : updating existing feature vsa
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Apr 1 21:29:06.719: feature call forward featname is 4
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Apr 1 21:29:06.719: :cc_free_feature_vsa freeing 418D7560
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Apr 1 21:29:06.719: vsacount in free is 4
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=0532549XXXX
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=0532549XXXXT
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=0532549XXXX
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=0532549XXXXT
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=16 Is Matched
*Apr 1 21:29:06.719: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=0532549XXXX, Expanded String=0532549XXXX, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Apr 1 21:29:06.723: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=16 Is Matched
*Apr 1 21:29:06.723: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=0532549XXXX, Expanded String=0532549XXXX, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Apr 1 21:29:06.723: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=16 Is Matched
*Apr 1 21:29:06.723: //-1/93C644718078/DPM/dpMatchCore:
   Dial String=0532549XXXX, Expanded String=0532549XXXX, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Apr 1 21:29:06.723: //-1/93C644718078/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=16 Is Matched
*Apr 1 21:29:06.723: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 0532549XXXX
*Apr 1 21:29:06.723: //-1/xxxxxxxxxxxx/CCAPI/cc_find_feature_vsa:

*Apr 1 21:29:06.723: inside while in find feature_sa
*Apr 1 21:29:06.723: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Apr 1 21:29:06.723: feature_vsa is 418D6F40,featname is 4
*Apr 1 21:29:06.723: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Apr 1 21:29:06.723: : updating existing feature vsa
*Apr 1 21:29:06.723: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

*Apr 1 21:29:06.723: feature call basic
*Apr 1 21:29:06.723: //69/93C644718078/CCAPI/ccCallFacility:
   Call Id=69
*Apr 1 21:29:06.723: //68/93C644718078/CCAPI/ccCallDisconnect:
   Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Apr 1 21:29:06.723: //68/93C644718078/CCAPI/ccCallDisconnect:
   Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)
*Apr 1 21:29:06.723: //68/93C644718078/CCAPI/cc_api_get_transfer_info:
   Transfer Number=NULL
*Apr 1 21:29:06.723: //69/93C644718078/CCAPI/ccCallDisconnect:
   Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Apr 1 21:29:06.723: //69/93C644718078/CCAPI/ccCallDisconnect:
   Cause Value=17, Call Entry(Responsed=TRUE, Cause Value no mon=17)
*Apr 1 21:29:06.723: htsp_timer_stop3
*Apr 1 21:29:06.743: //69/93C644718078/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x3E345728, Tag=0x0, Call Id=69,
   Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)
*Apr 1 21:29:06.743: //-1/93C644718078/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x0
*Apr 1 21:29:06.743: //69/93C644718078/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
*Apr 1 21:29:06.743: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Apr 1 21:29:06.743: :cc_free_feature_vsa freeing 418D71E0
*Apr 1 21:29:06.743: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Apr 1 21:29:06.743: vsacount in free is 3
*Apr 1 21:29:06.743: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Apr 1 21:29:06.743: vsacount in free is 2
*Apr 1 21:29:07.535: htsp_process_event: [0/0/3, FXOGS_OFFHOOK, E_HTSP_RELEASE_REQ]fxogs_offhook_release
*Apr 1 21:29:07.535: htsp_timer_stop
*Apr 1 21:29:07.535: htsp_timer_stop2
*Apr 1 21:29:07.535: htsp_timer_stop3
*Apr 1 21:29:07.535: [0/0/3] set signal state = 0x4 timestamp = 0
*Apr 1 21:29:07.535: htsp_timer - 2000 msec
*Apr 1 21:29:08.275: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=15605 systime=211878
*Apr 1 21:29:08.275: htsp_process_event: [0/0/3, FXOGS_WAIT_ONHOOK, E_DSP_SIG_0100]
*Apr 1 21:29:08.275: fxogs_stop_disc_timer
*Apr 1 21:29:08.275: htsp_timer_stop2
*Apr 1 21:29:09.543: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

CME outgoing call 486 Busy here via FXO to PSTN