cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1288
Views
5
Helpful
9
Replies

E1 Outgoing Calls Failing About 20 minutes After Restarting Gateway\Route

jpineira
Level 1
Level 1

We have been having a weird outgoing call issue. Our PBX runs on CUCM 8.6 and we use Cisco 2921 as voice gateways. The voice gateway we are having issues with has an E1 (Mexico-Telmex) line. Incoming calls work fine. Outgoing calls stop working about 10 to 20 minutes after we reboot the router or we do a shut\no shut on the E1 controller interface. Route patterns and dial peer look good. The call gets to the router and we see it dialed. Please help. 

9 Replies 9

Dennis Mink
VIP Alumni
VIP Alumni
Have you got any logs on your gateway that indicate something is wrong? Does the ge stay registered in cucm? Try debug q931 and see if call comes into gateway after 20 minutes.
Please remember to rate useful posts, by clicking on the stars below.

Attached is one of the debug commands I ran where you it disconnecting calls. I have also attached a copy of the config. Thank you. 

Hello  jpineira ,

 

/// Invite recieved to gateway

Received:
INVITE sip:86562276800@10.3.0.1:5060 SIP/2.0

Via: SIP/2.0/TCP 10.3.0.22:5060;branch=z9hG4bK1e5cf03c48c

From: <sip:6566491000@10.3.0.22>;tag=55838~e838a9ed-5df3-406d-b96a-ea849077bd8a-43818037

To: <sip:86562276800@10.3.0.1>

Date: Thu, 29 Aug 2019 17:16:20 GMT

Call-ID: b6980f80-d6810864-824-1600030a@10.3.0.22

Supported: timer,resource-priority,replaces

Min-SE: 1800

User-Agent: Cisco-CUCM8.6

Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY

CSeq: 101 INVITE

Expires: 180

Allow-Events: presence

Supported: X-cisco-srtp-fallback,X-cisco-original-called

Cisco-Guid: 3063418752-0000065536-0000000153-0369099530

Session-Expires: 1800

P-Asserted-Identity: <sip:6566491000@10.3.0.22>

Remote-Party-ID: <sip:6566491000@10.3.0.22>;party=calling;screen=yes;privacy=off

Contact: <sip:6566491000@10.3.0.22:5060;transport=tcp>

Max-Forwards: 69

Content-Length: 0


//////// First CCAPI Leg Incoming to GW

010070: Aug 29 2019 11:16:20.454 T: //-1/B6980F800000/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=6566491000
----- ccCallInfo IE subfields -----
cisco-ani=6566491000
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=86562276800
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

010071: Aug 29 2019 11:16:20.454 T: //-1/B6980F800000/CCAPI/cc_api_call_setup_ind_common:
Interface=0x314B4874, Call Info(
Calling Number=6566491000,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=86562276800(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=0, Progress Indication=NULL(0), Calling IE Present=TRUE, ---------->>>> Here default dial peer matching which suspecting to be the issue

/////////// 2nd CCAPI leg Outgoing from Gateway towards


010089: Aug 29 2019 11:16:20.458 T: //331/B6980F800000/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=6566491000
----- ccCallInfo IE subfields -----
cisco-ani=6566491000
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=6562276800
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

010090: Aug 29 2019 11:16:20.458 T: //331/B6980F800000/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x2BE125B4, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=6566491000,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=6562276800(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=8050, Call Count On=FALSE,

/// GW send trying to CUCM

Sent:
SIP/2.0 100 Trying

Via: SIP/2.0/TCP 10.3.0.22:5060;branch=z9hG4bK1e5cf03c48c

From: <sip:6566491000@10.3.0.22>;tag=55838~e838a9ed-5df3-406d-b96a-ea849077bd8a-43818037

To: <sip:86562276800@10.3.0.1>

Date: Thu, 29 Aug 2019 17:16:20 GMT

Call-ID: b6980f80-d6810864-824-1600030a@10.3.0.22

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0


/// Disconnect with cauue value 41 >>> (41 - Temporary failure. )

Cause Value=41, Interface=0x2BE125B4, Call Id=332
010103: Aug 29 2019 11:16:28.474 T: //332/B6980F800000/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=41, Retry Count=0)
010104: Aug 29 2019 11:16:28.474 T: //331/B6980F800000/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
010105: Aug 29 2019 11:16:28.474 T: //332/B6980F800000/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=332
010106: Aug 29 2019 11:16:28.474 T: //332/B6980F800000/CCAPI/ccCallDisconnect:
Cause Value=41, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=41)
010107: Aug 29 2019 11:16:28.474 T: //332/B6980F800000/CCAPI/ccCallDisconnect:
Cause Value=41, Call Entry(Responsed=TRUE, Cause Value=41)


//////// Gateway send disconnect to CUCM

Sent:
SIP/2.0 503 Service Unavailable

Via: SIP/2.0/TCP 10.3.0.22:5060;branch=z9hG4bK1e5cf03c48c

From: <sip:6566491000@10.3.0.22>;tag=55838~e838a9ed-5df3-406d-b96a-ea849077bd8a-43818037

To: <sip:86562276800@10.3.0.1>;tag=30F3B0-248E

Date: Thu, 29 Aug 2019 17:16:20 GMT

Call-ID: b6980f80-d6810864-824-1600030a@10.3.0.22

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Reason: Q.850;cause=41

Content-Length: 0

 

//// To investigate the issue please provide below debugs


debug isdn q931
debug voip dialpeer
debug voice translation

 

 

Thanks

Please rate helpful posts
Thanks, Hari Prasad

Hello

 

I checked controller configuration. It is E1 R2.

 

Instead of isdn q931 , please share below debugs with ccapi ,sip and  translation

debug voip vtsp all

debug vpm signal

 

 

 

Please rate helpful posts
Thanks, Hari Prasad

Thank you. Here are the requested debug outputs. 

Hi

 

I have checked debugs and found that when call going to E1 R2 channel, we recieve disconnect message.

 

 

Please refer to below outputs.

 

I will suggest you verify show logs in gateway and see if you found any E1 R2 related error into logs. If you don't see anything then try to  reset E1 R2 controller once by shut and no shut and check.

 

 

 

 


004829: Aug 30 2019 07:47:23.499 T: r2_q421_seize_to(0/0/0:1(25)) Tx CLEAR FWDvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:1(25)] set signal state = 0x8
004830: Aug 30 2019 07:47:23.499 T: htsp_timer - 1000 msec
004831: Aug 30 2019 07:47:23.499 T: r2_reg_channel_disconnected(0/0/0:1(25))
004832: Aug 30 2019 07:47:23.499 T: r2_reg_process_event: [0/0/0:1(25), R2_REG_IDLE, E_R2_REG_DISCONNECT(91)]
004833: Aug 30 2019 07:47:23.499 T: r2_reg_disconnect_idle(0/0/0:1(25))
004834: Aug 30 2019 07:47:23.499 T: r2_reg_timer_stop(0/0/0:1(25))
004835: Aug 30 2019 07:47:24.499 T: htsp_process_event: [0/0/0:1(25), R2_Q421_OG_CLR_FWD, E_HTSP_EVENT_TIMER]
004836: Aug 30 2019 07:47:24.499 T: r2_q421_clr_fwd_to(0/0/0:1(25))
004837: Aug 30 2019 07:47:24.499 T: htsp_timer - 2000 msec
004838: Aug 30 2019 07:47:26.499 T: htsp_process_event: [0/0/0:1(25), R2_Q421_WAIT_IDLE, E_HTSP_EVENT_TIMER]
004839: Aug 30 2019 07:47:26.499 T: r2_q421_wait_idle_to(0/0/0:1(25)) Tx IDLEvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:1(25)] set signal state = 0x8
004840: Aug 30 2019 07:47:26.499 T: htsp_timer - 2000 msec
004841: Aug 30 2019 07:47:26.499 T: htsp_process_event: [0/0/0:1(25), R2_Q421_WAIT_IDLE, E_DSP_SIG_0100]
004842: Aug 30 2019 07:47:26.499 T: r2_q421_wait_idle_to(0/0/0:1(25)) Tx IDLEvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:1(25)] set signal state = 0x8
004843: Aug 30 2019 07:47:26.499 T: htsp_timer - 2000 msec
004844: Aug 30 2019 07:47:26.503 T: htsp_process_event: [0/0/0:1(25), R2_Q421_WAIT_IDLE, E_DSP_SIG_0100]
004845: Aug 30 2019 07:47:26.503 T: r2_q421_wait_idle_to(0/0/0:1(25)) Tx IDLEvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:1(25)] set signal state = 0x8
004846: Aug 30 2019 07:47:26.503 T: htsp_timer - 2000 msec
004847: Aug 30 2019 07:47:26.507 T: htsp_process_event: [0/0/0:1(25), R2_Q421_WAIT_IDLE, E_DSP_SIG_0100]
004848: Aug 30 2019 07:47:26.507 T: r2_q421_wait_idle_to(0/0/0:1(25)) Tx IDLEvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:1(25)] set signal state = 0x8
004849: Aug 30 2019 07:47:26.507 T: htsp_timer - 2000 msec
004850: Aug 30 2019 07:47:26.511 T: htsp_process_event: [0/0/0:1(25), R2_Q421_WAIT_IDLE, E_DSP_SIG_0100]
004851: Aug 30 2019 07:47:26.511 T: r2_q421_wait_idle_to(0/0/0:1(25)) Tx IDLEvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:1(25)] set signal state = 0x8
004852: Aug 30 2019 07:47:26.511 T: htsp_timer - 2000 msec
004853: Aug 30 2019 07:47:26.511 T: htsp_process_event: [0/0/0:1(25), R2_Q421_WAIT_IDLE, E_DSP_SIG_0100]
004854: Aug 30 2019 07:47:26.511 T: r2_q421_wait_idle_to (0/0/0:1(25)) releasing call because time limit has
been reached in R2_Q421_WAIT_IDLE state

004855: Aug 30 2019 07:47:26.511 T: htsp_process_event: [0/0/0:1(25), R2_Q421_WAIT_IDLE, E_DSP_SIG_1000]
004856: Aug 30 2019 07:47:26.511 T: r2_q421_wait_idle(0/0/0:1(25)) Rx IDLE
004857: Aug 30 2019 07:47:26.511 T: r2_reg_channel_disconnected(0/0/0:1(25))
004858: Aug 30 2019 07:47:26.511 T: htsp_timer_stop
004859: Aug 30 2019 07:47:26.515 T: //208/AD647C800000/CCAPI/cc_api_call_disconnected:
Cause Value=41, Interface=0x2BBE5074, Call Id=208
004860: Aug 30 2019 07:47:26.515 T: //208/AD647C800000/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=41, Retry Count=0)
004861: Aug 30 2019 07:47:26.515 T: r2_reg_process_event: [0/0/0:1(25), R2_REG_IDLE, E_R2_REG_DISCONNECT(91)]
004862: Aug 30 2019 07:47:26.515 T: r2_reg_disconnect_idle(0/0/0:1(25))
004863: Aug 30 2019 07:47:26.515 T: r2_reg_timer_stop(0/0/0:1(25))
004864: Aug 30 2019 07:47:26.515 T: //207/AD647C800000/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
004865: Aug 30 2019 07:47:26.515 T: //208/AD647C800000/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=208
004866: Aug 30 2019 07:47:26.515 T: //208/AD647C800000/CCAPI/ccCallDisconnect:
Cause Value=41, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=41)
004867: Aug 30 2019 07:47:26.515 T: //208/AD647C800000/CCAPI/ccCallDisconnect:
Cause Value=41, Call Entry(Responsed=TRUE, Cause Value=41)
004868: Aug 30 2019 07:47:26.515 T: //208/AD647C800000/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
004869: Aug 30 2019 07:47:26.515 T: htsp_process_event: [0/0/0:1(25), R2_Q421_IDLE, E_HTSP_RELEASE_REQ]
004870: Aug 30 2019 07:47:26.515 T: r2_q421_idle_release(0/0/0:1(25)) E_HTSP_RELEASE_REQ
004871: Aug 30 2019 07:47:26.515 T: r2_q421_idle_release(0/0/0:1(25)) Tx IDLEvnm_dsp_set_sig_state:[R2 Q.421 0/0/0:1(25)] set signal state = 0x8
004872: Aug 30 2019 07:47:26.515 T: htsp_timer_stop
004873: Aug 30 2019 07:47:26.515 T: r2_reg_channel_disconnected(0/0/0:1(25))
004874: Aug 30 2019 07:47:26.515 T: r2_reg_process_event: [0/0/0:1(25), R2_REG_IDLE, E_R2_REG_DISCONNECT(91)]
004875: Aug 30 2019 07:47:26.515 T: r2_reg_disconnect_idle(0/0/0:1(25))
004876: Aug 30 2019 07:47:26.515 T: r2_reg_timer_stop(0/0/0:1(25))
004877: Aug 30 2019 07:47:26.515 T: //208/AD647C800000/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x2BBE5074, Tag=0x0, Call Id=208,
Call Entry(Disconnect Cause=41, Voice Class Cause Code=0, Retry Count=0)
004878: Aug 30 2019 07:47:26.515 T: //-1/AD647C800000/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x0
004879: Aug 30 2019 07:47:26.515 T: //208/AD647C800000/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
004880: Aug 30 2019 07:47:26.515 T: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004881: Aug 30 2019 07:47:26.515 T: :cc_free_feature_vsa freeing 32BE08F0
004882: Aug 30 2019 07:47:26.515 T: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004883: Aug 30 2019 07:47:26.515 T: vsacount in free is 1
004884: Aug 30 2019 07:47:26.515 T: //207/AD647C800000/CCAPI/ccCallDisconnect:
Cause Value=41, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
004885: Aug 30 2019 07:47:26.515 T: //207/AD647C800000/CCAPI/ccCallDisconnect:
Cause Value=41, Call Entry(Responsed=TRUE, Cause Value=41)
004886: Aug 30 2019 07:47:26.519 T: //207/AD647C800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 503 Service Unavailable

Via: SIP/2.0/TCP 10.3.0.22:5060;branch=z9hG4bK5d82137b0fba

From: <sip:6566491000@10.3.0.22>;tag=236508~e838a9ed-5df3-406d-b96a-ea849077bd8a-43839291

To: <sip:86566235899@10.3.0.1>;tag=2A8C8C4-24ED

Date: Fri, 30 Aug 2019 13:47:18 GMT

Call-ID: ad647c80-d69128e6-16e1-1600030a@10.3.0.22

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Reason: Q.850;cause=41

Content-Length: 0

 

 

 

 

 

 

Please rate helpful posts
Thanks, Hari Prasad

No errors show in "show logs" and the issue is TEMPORARILY resolved by shut\no shut of controller E1. The temporary solutions lasts anywhere from 5 minutes to 1 hour and then outgoing calls stop working. Incoming calls works and even outgoing calls we made while it was working stay connected, any new outbound calls don't work again. 

 

In other words the shut/no shut resolves it, but after a while it reverts back to no outgoing calls. That's what's so weird and driving us crazy. 

 

Thanks for the help.

Hi,

 

Can you please check Incoming call also using the same E1 R2 controller.

 

If yes then i will suggest you to check with E1 R2 provider once if they need any change in Controller configuration since no issue observed on gateway.

Please rate helpful posts
Thanks, Hari Prasad

Incoming calls work fine. I suspect it is the provider but they keep saying it is not them. They brought what they called a "mobile or portable PBX" testing machine and they were able to make outgoing calls with no issue.

 

What I can't get is why the OUTGOING calls stop working after a while but everything else works fine. Thanks for all your help. 

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: