04-17-2014 07:41 PM - edited 03-16-2019 10:30 PM
Hi all,
I have a problem with a circuit (R2 digital). Incoming calls work normally but outgoing calls don't work.
Configurations:
network-clock-participate wic 0
network-clock-participate wic 1
network-clock-select 1 E1 0/1/1
network-clock-select 2 E1 0/0/0
!
voice service pots
!
voice service voip
allow-connections h323 to h323
supplementary-service h450.12
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none
h323
modem passthrough nse codec g711ulaw
!
controller E1 0/0/0
framing NO-CRC4
ds0-group 0 timeslots 1-15,17-31 type r2-digital r2-compelled ani
cas-custom 0
country brazil
metering
seizure-ack-time 50
release-ack
double-answer
category 2
answer-signal group-b 1
!
voice-port 0/0/0:0
translation-profile incoming prefixo-unidades
cptone BR
timeouts call-disconnect 3
bearer-cap Speech
!
dial-peer voice 23000 pots
destination-pattern [2-5].......
no digit-strip
port 0/0/0:0
!
dial-peer voice 1010000 voip
destination-pattern 101....
session target ipv4:10.1.64.21
dtmf-relay cisco-rtp
ip qos dscp cs5 media
no vad
Debug vpm signal
*Apr 18 02:01:39.483: htsp_timer_stop3 htsp_setup_req
*Apr 18 02:01:39.487: Orig called num:32413393
*Apr 18 02:01:39.487: Orig called num:32413393
*Apr 18 02:01:39.487: htsp_process_event: [0/0/0:0(5), R2_Q421_IDLE, E_HTSP_SETUP_REQ]
*Apr 18 02:01:39.487: r2_q421_seize(0/0/0:0(5)) E_HTSP_SETUP_REQ DNIS=32413393 ANI=1017080
*Apr 18 02:01:39.487: r2_q421_seize(0/0/0:0(5)) Tx SEIZUREvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:0(5)] set signal state = 0x0
*Apr 18 02:01:39.487: htsp_timer - 5000 msec
*Apr 18 02:01:44.487: htsp_process_event: [0/0/0:0(5), R2_Q421_OG_SEIZE, E_HTSP_EVENT_TIMER]
*Apr 18 02:01:44.487: r2_q421_seize_to(0/0/0:0(5)) Tx CLEAR FWDvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:0(5)] set signal state = 0x8
*Apr 18 02:01:44.487: htsp_timer - 1000 msec
*Apr 18 02:01:44.487: r2_reg_channel_disconnected(0/0/0:0(5))
*Apr 18 02:01:44.487: r2_reg_process_event: [0/0/0:0(5), R2_REG_IDLE, E_R2_REG_DISCONNECT(91)]
*Apr 18 02:01:44.487: r2_reg_disconnect_idle(0/0/0:0(5))
*Apr 18 02:01:44.487: r2_reg_timer_stop(0/0/0:0(5))
*Apr 18 02:01:45.487: htsp_process_event: [0/0/0:0(5), R2_Q421_OG_CLR_FWD, E_HTSP_EVENT_TIMER]
*Apr 18 02:01:45.487: r2_q421_clr_fwd_to(0/0/0:0(5))
*Apr 18 02:01:45.487: htsp_timer - 2000 msec
*Apr 18 02:01:47.487: htsp_process_event: [0/0/0:0(5), R2_Q421_WAIT_IDLE, E_HTSP_EVENT_TIMER]
*Apr 18 02:01:47.487: r2_q421_wait_idle_to(0/0/0:0(5)) Tx IDLEvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:0(5)] set signal state = 0x8
*Apr 18 02:01:47.487: htsp_timer - 2000 msec
*Apr 18 02:01:47.487: htsp_process_event: [0/0/0:0(5), R2_Q421_WAIT_IDLE, E_DSP_SIG_1000]
*Apr 18 02:01:47.487: r2_q421_wait_idle(0/0/0:0(5)) Rx IDLE
*Apr 18 02:01:47.487: r2_reg_channel_disconnected(0/0/0:0(5))
*Apr 18 02:01:47.487: htsp_timer_stop
*Apr 18 02:01:47.487: r2_reg_process_event: [0/0/0:0(5), R2_REG_IDLE, E_R2_REG_DISCONNECT(91)]
*Apr 18 02:01:47.487: r2_reg_disconnect_idle(0/0/0:0(5))
*Apr 18 02:01:47.487: r2_reg_timer_stop(0/0/0:0(5))
*Apr 18 02:01:47.487: htsp_process_event: [0/0/0:0(5), R2_Q421_IDLE, E_HTSP_RELEASE_REQ]
*Apr 18 02:01:47.487: r2_q421_idle_release(0/0/0:0(5)) E_HTSP_RELEASE_REQ
*Apr 18 02:01:47.487: r2_q421_idle_release(0/0/0:0(5)) Tx IDLEvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:0(5)] set signal state = 0x8
*Apr 18 02:01:47.487: htsp_timer_stop
*Apr 18 02:01:47.487: r2_reg_channel_disconnected(0/0/0:0(5))
*Apr 18 02:01:47.491: r2_reg_process_event: [0/0/0:0(5), R2_REG_IDLE, E_R2_REG_DISCONNECT(91)]
*Apr 18 02:01:47.491: r2_reg_disconnect_idle(0/0/0:0(5))
*Apr 18 02:01:47.491: r2_reg_timer_stop(0/0/0:0(5))
Debug vtsp all
*Apr 18 02:02:33.823: //-1/xxxxxxxxxxxx/VTSP:(0/0/0:0):-1:-1:-1/vtsp_allocate_cdb:
CDB=0x23396600
*Apr 18 02:02:33.823: //-1/xxxxxxxxxxxx/VTSP:(0/0/0:0):-1:-1:-1/vtsp_insert_cdb:
*Apr 18 02:02:33.823: //-1/xxxxxxxxxxxx/VTSP:(0/0/0:0):-1:-1:-1/vtsp_process_event:
[state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ]
*Apr 18 02:02:33.823: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:-1:-1/vtsp_og_call_req:
Echo Cancel=TRUE, Gain Control=0
Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE
*Apr 18 02:02:33.823: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:-1:-1/vtsp_do_call_setup_req:
*Apr 18 02:02:33.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_dsm_opened_cb:
*Apr 18 02:02:33.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]
*Apr 18 02:02:33.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/act_setup_req_pend_succ:
*Apr 18 02:02:33.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_request_call:
Mode=0, Peer=23000
*Apr 18 02:02:33.827: digit_strip:0, pcn:32413393, poa:[2-5].......
*Apr 18 02:02:33.827: Final pcn:32413393, poa:[2-5]......., dial_string:32413393
*Apr 18 02:02:33.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_create_call_active_on_setup_req:
Target Carrier ID=
*Apr 18 02:02:33.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_create_call_active_on_setup_req:
Source Carrier ID=
*Apr 18 02:02:33.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_request_call:
Progress Indication=0, Info Trans Capability=128
Calling Number=1017080, TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed
Called Number=32413393, TON=Unknown, NPI=Unknown
*Apr 18 02:02:41.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQUEST, event:E_TSP_DISCONNECT_IND]
*Apr 18 02:02:41.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/act_setup_pend_release_ind:
Cause Value=34
*Apr 18 02:02:41.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_ring_noan_timer_stop:
Timer Stop Time=165898
*Apr 18 02:02:41.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_cc_call_disconnected:
*Apr 18 02:02:41.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_cc_call_disconnected:
Cause Value=34
*Apr 18 02:02:41.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_set_release_source:
Release Direction=PSTN, Release Source=Called Party-PSTN
*Apr 18 02:02:41.827: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/act_setup_pend_release_ind:
Peer=23000
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_call_disconnect:
[Out Of Service]-Tone Played In Direction [Both]
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQUEST, event:E_CC_DISCONNECT]
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/act_disconnect_no_dsp_chan:
Hairpin=FALSE, Cause Value=34, Previous Cause Value=34
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_do_call_history:
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_do_call_history:
Coder Rate=-1
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/act_disconnect_no_dsp_chan:
Peer=23000
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_process_event:
[state:S_SETUP_FAIL, event:E_TSP_DISCONNECT_CONF]
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/act_setup_fail:
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:1:1/vtsp_timer_stop:
Timer Stop Time=165898
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:-1:-1/vtsp_dsm_closed_cb:
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:-1:-1/vtsp_process_event:
[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:-1:-1/act_terminate:
*Apr 18 02:02:41.831: //66/80CCD0AD1800/VTSP:(0/0/0:0):-1:-1:-1/vtsp_timer_stop:
Timer Stop Time=165898
*Apr 18 02:02:41.831: //-1/80CCD0AD1800/VTSP:(0/0/0:0):-1:-1:-1/vtsp_free_cdb:
CDB=0x23396600
*** When I make a outgoing call the result of (show voice port summary)
Router#sh voice port summary
IN OUT
PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC
=============== == ============ ===== ==== ======== ======== ==
0/0/0:0 01 r2-digital up up clearfwd idle y
[ ]'s
04-18-2014 12:07 PM
Hello Rodrigo ,
What version of CUCM are you running ? , check this bug CSCul71689 :)
Regards,
04-18-2014 01:03 PM
That bug should not be applicable here. The debugs show the call hits the gateway. In the case of the bug you linked, the call would never be sent from CUCM to the gateway in the first place.
04-18-2014 01:01 PM
It looks like the PSTN is sending a disconnect with ISDN cause code 34-no circuit/channel available.
It may be because you are sending on the first channel instead of the last. I don't know of any way to change this order on a CAS circuit though unless you're using MGCP.
It also may be because of the calling/called number numbering type/plan of unknown you are sending. You may need some input from the carrier why they are rejecting the calls.
04-20-2014 08:20 PM
Hi all,
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: