Re: CME Deployment using SIP to ISDN Converter

tyroneclark871 · ‎02-15-2018

Hi all,

I am having no end of troubles with call disconnections/failures.

I have attached a Q931 debug and the running configuration...

The issue I am experiencing is when calling into or out of the Serial0/1/0:15 - I am getting the error:

SyntaxEditor Code Snippet

 ISDN Se0/1/0:15 Q931d: L3IF_rx_L2_pak: received data
Feb 14 10:10:16.992: 	080200304D0802819C
Feb 14 10:10:16.992: ISDN Se0/1/0:15 Q931d: L3_Go: source 0x020A, ces 1, event 0x004D, call id 0x0000, int id 0x0
Feb 14 10:10:16.992: ISDN Se0/1/0:15 Q931d: L3_Go: event 0x4D  cr_len 2 cr 32816
Feb 14 10:10:16.992: ISDN Se0/1/0:15 Q931d: L3_Go: call_id 0x4F1 cr 0x8030 state 11 event 0x4D ces 1
Feb 14 10:10:16.992: ISDN Se0/1/0:15 Q931d: L3_ProcessEvent: callref = 0x8030 RELEASE:U11_Release(nlcb)
Feb
MEDSARI2234UG001# 14 10:10:16.993: ISDN Se0/1/0:15 Q931d: srl_send_l3_pak:
	source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim = DL_DATA_REQ 0x0240
	priv_len = 4 int_id = 0x0x7FB922D4BB60 datasize = 25
Feb 14 10:10:16.993: ISDN Se0/1/0:15 Q931d: data = 
Feb 14 10:10:16.993: 	00007FB922D4BB600000030002400494
Feb 14 10:10:16.993: 	00010000080280305A
Feb 14 10:10:16.993: ISDN Se0/1/0:15 Q931d: L3_state_change: callref 0x8030 old DISCONNECT_REQUEST, new NULL_STATE
Feb 14 10:10:16.993: ISDN Se0/1/0:15 Q931: TX -> REL
MEDSARI2234UG001#EASE_COMP pd = 8  callref = 0x8030
MEDSARI2234UG001#
Feb 14 10:10:44.636: ISDN Se0/1/0:15 Q931: RX <- SETUP pd = 8  callref = 0x0031 
	Bearer Capability i = 0x8090A3 
		Standard = CCITT 
		Transfer Capability = Speech  
		Transfer Mode = Circuit 
		Transfer Rate = 64 kbit/s 
	Channel ID i = 0xA98381 
		Exclusive, Channel 1 
	Calling Party Number i = 0x2181, '549859764' 
		Plan:ISDN, Type:National 
	Called Party Number i = 0x80, '4339122' 
		Plan:Unknown, Type:Unknown 
	Sending Complete
Feb 14 10:10:44.636: ISDN Se0/1/0:15 Q931d: L3IF_rx_L2_pak
MEDSARI2234UG001#: received data
Feb 14 10:10:44.636: 	080200310504038090A31803A983816C
Feb 14 10:10:44.636: 	0B218135343938353937363470088034
Feb 14 10:10:44.636: 	333339313232A1
Feb 14 10:10:44.636: ISDN Se0/1/0:15 Q931d: L3_Go: source 0x020A, ces 1, event 0x0005, call id 0x0000, int id 0x0
Feb 14 10:10:44.636: ISDN Se0/1/0:15 Q931d: L3_Go: event 0x5  cr_len 2 cr 32817
Feb 14 10:10:44.636: ISDN Se0/1/0:15 Q931d: L3_Go: call_id 0x4F2 cr 0x8031 state 0 event 0x5 ces 1
Feb 14 10:10:44.636: ISDN Se0/1/0:15 Q931d: L3_P
MEDSARI2234UG001#rocessEvent: callref = 0x8031 SETUP:U0_Setup(nlcb)
Feb 14 10:10:44.636: ISDN Se0/1/0:15 Q931d: L3_state_change: callref 0x8031 old NULL_STATE, new CALL_PRESENT
Feb 14 10:10:44.636: ISDN Se0/1/0:15 Q931: Received SETUP  callref = 0x8031 callID = 0x04F2 switch = primary-5ess interface = User 
Feb 14 10:10:44.637: ISDN Se0/1/0:15 Q931d: extract_called_number: Extracted called number 4339122
Feb 14 10:10:44.637: ISDN Se0/1/0:15 Q931d: extract_called_number: Extracted called number 4339122
Feb 14 10:10:44.
MEDSARI2234UG001#637: ISDN Se0/1/0:15 Q931d: extract_called_number: Extracted called number 4339122
Feb 14 10:10:44.637: ISDN Se0/1/0:15 Q931d: extract_called_number: Extracted called number 4339122
Feb 14 10:10:44.637: ISDN Se0/1/0:15 Q931d: extract_called_number: Extracted called number 4339122
Feb 14 10:10:44.637: ISDN Se0/1/0:15 Q931d: extract_called_number: Extracted called number 4339122
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: Default type/plan 0x2 0x1 sw-type 3 Calling number 90549859764 isdn_map 0 mask 0x10
MEDSARI2234UG001#02
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: typeplan from voice leg 0x2 0x1; voice-translation for TYPE none, PLAN none; accept h225 values yes
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: isdn_parser_map_lkup: Comparing 90549859764/., (regexp) 
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: isdn_parser_map_lkup: overriding plan/type for 90549859764, isdn/national to unknown/unknown
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931: Applying typeplan for sw-type 0x3 is 0x0 0x0, Calling num 90549859764; Oct3a 0x
MEDSARI2234UG001#81
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: Default type/plan 0x2 0x1 sw-type 3 Called number 9122 isdn_map 0 mask 0x1002
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: typeplan from voice leg 0x0 0x0; voice-translation for TYPE none, PLAN none; accept h225 values yes
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931: pak_private_number: Invalid type/plan 0x0 0x0 may be overriden; sw-type 3
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: isdn_parser_map_lkup: Comparing 9122/., (regexp) 
Feb 14 10:10:44.640: ISDN 
MEDSARI2234UG001#Se0/1/0:15 Q931d: isdn_parser_map_lkup: overriding plan/type for 9122, isdn/national to unknown/unknown
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931: Applying typeplan for sw-type 0x3 is 0x0 0x0, Called num 9122
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: isdn_parser_map_lkup: Comparing 90549859764/., (regexp) 
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: isdn_parser_map_lkup: overriding plan/type for 90549859764, isdn/national to unknown/unknown
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: CCIE_BuildChanId
MEDSARI2234UG001#_IE: int_ident_len 0
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: L3_Go: source 0x0400, ces 1, event 0x0088, call id 0x04F2, int id 0x0
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: L3_Go: event 0x88  cr_len 2 cr 32817
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: L3_Go: call_id 0x4F2 cr 0x8031 state 6 event 0x88 ces 1
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: L3_ProcessEvent: callref = 0x8031 CC_CALL_PROC_REQ:U6_CallProcReq(nlcb)
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: srl_send_l3_pak:
	source_id =
MEDSARI2234UG001# Q.931 0x0300, dest_id = Q.921 0x0000, prim = DL_DATA_REQ 0x0240
	priv_len = 4 int_id = 0x0x7FB922D4BB60 datasize = 30
Feb 14 10:10:44.640: ISDN Se0/1/0:15 Q931d: data = 
Feb 14 10:10:44.640: 	00007FB922D4BB600000030002400401
Feb 14 10:10:44.640: 	0001000008028031021803A98381
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931d: L3_state_change: callref 0x8031 old CALL_PRESENT, new IN_CALL_PROC
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931d: CCIE_BuildChanId_IE: int_ident_len 0
Feb 14 10:10:44.641: ISDN Se0/1/0:1
MEDSARI2234UG001#5 Q931d: L3_Go: source 0x0400, ces 1, event 0x0080, call id 0x8025, int id 0x0
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931d: L3_Go: event 0x80  cr_len 2 cr 0
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931d: L3_Go: call_id 0x8025 cr 0x0 state 0 event 0x80 ces 1
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931d: L3_ProcessEvent: callref = 0x0000 CC_SETUP_REQ:U0_SetupReq(nlcb)
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931d: srl_send_l3_pak:
	source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim = DL_DATA_REQ 0x0240
	pri
MEDSARI2234UG001#v_len = 4 int_id = 0x0x7FB922D4BB60 datasize = 58
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931d: data = 
Feb 14 10:10:44.641: 	00007FB922D4BB600000030002400401
Feb 14 10:10:44.641: 	00010000080200A405A104038090A318
Feb 14 10:10:44.641: 	03A9838C6C0D00813930353439383539
Feb 14 10:10:44.641: 	37363470058039313232
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931: Sending SETUP  callref = 0x00A4 callID = 0x8025 switch = primary-5ess interface = User 
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931d: L3_state_change: ca
MEDSARI2234UG001#llref 0x00A4 old NULL_STATE, new CALL_INIT
Feb 14 10:10:44.641: ISDN Se0/1/0:15 Q931: TX -> CALL_PROC pd = 8  callref = 0x8031 
	Channel ID i = 0xA98381 
		Exclusive, Channel 1
Feb 14 10:10:44.642: ISDN Se0/1/0:15 Q931: TX -> SETUP pd = 8  callref = 0x00A4 
	Sending Complete 
	Bearer Capability i = 0x8090A3 
		Standard = CCITT 
		Transfer Capability = Speech  
		Transfer Mode = Circuit 
		Transfer Rate = 64 kbit/s 
	Channel ID i = 0xA9838C 
		Exclusive, Channel 12 
	Calling Party Number i = 0x0081, '90549859764' 
		Plan:Unknown, Type:Unknown 
	Called Party Number i = 0x80, '9122' 
		Plan:Unknown, Type:Unknown
Feb 14 10:10:44.754: ISDN Se0/1/0:15 Q931: RX <- CALL_PROC pd = 8  callref = 0x80A4 
	Channel ID i = 0xA9838C 
		Exclusive, Channel 12
Feb 14 10:10:44.754: ISDN Se0/1/0:15 Q931d: L3IF_rx_L2_pak: received data
Feb 14 10:10:44.754: 	080280A4021803A9838C
Feb 14 10:10:44.754: ISDN Se0/1/0:15 Q931d: L3_Go: source 0x020A, ces 1, event 0x0002, call id 0x0000, int id 0x0
Feb 14 10:10:44.
MEDSARI2234UG001#754: ISDN Se0/1/0:15 Q931d: L3_Go: event 0x2  cr_len 2 cr 164
Feb 14 10:10:44.754: ISDN Se0/1/0:15 Q931d: L3_Go: call_id 0x8025 cr 0xA4 state 1 event 0x2 ces 1
Feb 14 10:10:44.754: ISDN Se0/1/0:15 Q931d: L3_ProcessEvent: callref = 0x00A4 CALL_PROC:U1_CallProc(nlcb)
Feb 14 10:10:44.754: ISDN Se0/1/0:15 Q931d: L3_state_change: callref 0x00A4 old CALL_INIT, new OUT_CALL_PROC
Feb 14 10:10:49.952: ISDN Se0/1/0:15 Q931: RX <- DISCONNECT pd = 8  callref = 0x80A4 
	Cause i = 0x819C - Invalid number format (in
MEDSARI2234UG001#complete number)
Feb 14 10:10:49.952: ISDN Se0/1/0:15 Q931d: L3IF_rx_L2_pak: received data
Feb 14 10:10:49.952: 	080280A4450802819C
Feb 14 10:10:49.952: ISDN Se0/1/0:15 Q931d: L3_Go: source 0x020A, ces 1, event 0x0045, call id 0x0000, int id 0x0
Feb 14 10:10:49.952: ISDN Se0/1/0:15 Q931d: L3_Go: event 0x45  cr_len 2 cr 164
Feb 14 10:10:49.952: ISDN Se0/1/0:15 Q931d: L3_Go: call_id 0x8025 cr 0xA4 state 3 event 0x45 ces 1
Feb 14 10:10:49.952: ISDN Se0/1/0:15 Q931d: L3_ProcessEvent: callref = 0x00A4 DIS
MEDSARI2234UG001#CONNECT:Ux_Disconnect(nlcb)
Feb 14 10:10:49.952: ISDN Se0/1/0:15 Q931d: L3_state_change: callref 0x00A4 old OUT_CALL_PROC, new DISCONNECT_IND
Feb 14 10:10:49.953: ISDN Se0/1/0:15 Q931d: L3_Go: source 0x0400, ces 1, event 0x0085, call id 0x8025, int id 0x0
Feb 14 10:10:49.953: ISDN Se0/1/0:15 Q931d: L3_Go: event 0x85  cr_len 2 cr 164
Feb 14 10:10:49.953: ISDN Se0/1/0:15 Q931d: L3_Go: call_id 0x8025 cr 0xA4 state 12 event 0x85 ces 1
Feb 14 10:10:49.953: ISDN Se0/1/0:15 Q931d: L3_ProcessEvent: callref = 
MEDSARI2234UG001#0x00A4 CC_RELEASE_REQ:U12_ReleaseReq(nlcb)
Feb 14 10:10:49.953: ISDN Se0/1/0:15 Q931d: srl_send_l3_pak:
	source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim = DL_DATA_REQ 0x0240
	priv_len = 4 int_id = 0x0x7FB922D4BB60 datasize = 25
Feb 14 10:10:49.953: ISDN Se0/1/0:15 Q931d: data = 
Feb 14 10:10:49.953: 	00007FB922D4BB600000030002400494
Feb 14 10:10:49.953: 	00010000080200A44D
Feb 14 10:10:49.953: ISDN Se0/1/0:15 Q931d: L3_state_change: callref 0x00A4 old DISCONNECT_IND, new RELEASE_REQUEST
Feb 
MEDSARI2234UG001#14 10:10:49.953: ISDN Se0/1/0:15 Q931: TX -> RELEASE pd = 8  callref = 0x00A4
Feb 14 10:10:50.072: ISDN Se0/1/0:15 Q931: RX <- RELEASE_COMP pd = 8  callref = 0x80A4 
	Cause i = 0x8190 - Normal call clearing
Feb 14 10:10:50.073: ISDN Se0/1/0:15 Q931d: L3IF_rx_L2_pak: received data
Feb 14 10:10:50.073: 	080280A45A08028190
Feb 14 10:10:50.073: ISDN Se0/1/0:15 Q931d: L3_Go: source 0x020A, ces 1, event 0x005A, call id 0x0000, int id 0x0
Feb 14 10:10:50.073: ISDN Se0/1/0:15 Q931d: L3_Go: event 0x5A  cr_len
MEDSARI2234UG001# 2 cr 164
Feb 14 10:10:50.073: ISDN Se0/1/0:15 Q931d: L3_Go: call_id 0x8025 cr 0xA4 state 19 event 0x5A ces 1
Feb 14 10:10:50.073: ISDN Se0/1/0:15 Q931d: L3_ProcessEvent: callref = 0x00A4 RELEASE_COMP:U19_ReleaseComp(nlcb)
Feb 14 10:10:50.073: ISDN Se0/1/0:15 Q931d: L3_state_change: callref 0x00A4 old RELEASE_REQUEST, new NULL_STATE
Feb 14 10:10:50.076: ISDN Se0/1/0:15 Q931d: L3_Go: source 0x0400, ces 1, event 0x0084, call id 0x04F2, int id 0x0
Feb 14 10:10:50.076: ISDN Se0/1/0:15 Q931d: L3_Go: event 
MEDSARI2234UG001#0x84  cr_len 2 cr 32817
Feb 14 10:10:50.076: ISDN Se0/1/0:15 Q931d: L3_Go: call_id 0x4F2 cr 0x8031 state 9 event 0x84 ces 1
Feb 14 10:10:50.076: ISDN Se0/1/0:15 Q931d: L3_ProcessEvent: callref = 0x8031 CC_DISCONNECT_REQ:Ux_DiscReq(nlcb)
Feb 14 10:10:50.076: ISDN Se0/1/0:15 Q931d: srl_send_l3_pak:
	source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim = DL_DATA_REQ 0x0240
	priv_len = 4 int_id = 0x0x7FB922D4BB60 datasize = 29
Feb 14 10:10:50.076: ISDN Se0/1/0:15 Q931d: data = 
Feb 14 10:10:50.076: 	0
MEDSARI2234UG001#0007FB922D4BB600000030002400494
Feb 14 10:10:50.076: 	0001000008028031450802809C
Feb 14 10:10:50.076: ISDN Se0/1/0:15 Q931d: L3_state_change: callref 0x8031 old IN_CALL_PROC, new DISCONNECT_REQUEST
Feb 14 10:10:50.077: ISDN Se0/1/0:15 Q931: TX -> DISCONNECT pd = 8  callref = 0x8031 
	Cause i = 0x809C - Invalid number format (incomplete number)
Feb 14 10:10:50.232: ISDN Se0/1/0:15 Q931: RX <- RELEASE pd = 8  callref = 0x0031 
	Cause i = 0x819C - Invalid number format (incomplete number)

Any help you can provide would be most appreciated!!

Regards.

R0g22 · ‎02-15-2018

Called Party Number i = 0x80, '9122'
Plan:Unknown, Type:Unknown

This does not look to be a correct number for your Telco to recognise. They are appropriately rejecting your setup -

Feb 14 10:10:49.952: ISDN Se0/1/0:15 Q931: RX <- DISCONNECT pd = 8 callref = 0x80A4
Cause i = 0x819C - Invalid number format (incomplete number)

Where are these calls being originated ? Are you hair pinning PSTN to PSTN calls ?

tyroneclark871 · ‎07-04-2018

Hi,

Sorry for not seeing this or responding! I managed to find the issue was with a translation ; it was matching the wrong one - with no reason for it. Removed it, put it back in and all sorted out.

Thanks for the response though.