[cisco-voip] Cisco IAD 243X releases NI2 call when receiving minor
ISDN messages from PBX
Hodgeman, Samuel
shodgeman at xo.com
Thu Aug 4 16:54:39 EDT 2005
Hi all,
I'm hoping for an avalanche of suggestions, here, so please don't hold
back!
We're testing an interface between a Nortel Meridian 1 PBX and the Cisco
IAD243X series of access devices.
'Normal' calls complete in both directions. Yippee!
However, calls that include redirection info, ie Originally Called
Number and Redirecting Number, Fail.
The PBX sends one of two messages back to the IAD after the SETUP
message:
'setup message contains unimplimented IE;' and/or 'in-band info or
pattern available.'
In either case, as soon as the IAD receives this message, it releases
the call.
Now, I see both of these 'error' messages QUITE frequently on one or
another of our many PBXs across the country, and this NEVER causes a
call to fail.
Here is the Debug output. It certainly seems that, as soon as the IAD
receives the message from the PBX, it kills the call. Check out the
lines that include the text **error**
Any ideas would be greatly appreciated.
- Sam Hodgeman
*Mar 9 16:27:44.264: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:4435780463 at 172.27.13.102:5060 SIP/2.0
Via:SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143706-1687763104-1123164235314
From:"Ring
Splash"<sip:RingSplash at 205.158.241.245>;tag=1687763104-1123164235314
To:"4435780463"<sip:4435780463 at 172.27.13.102:5060>
Call-ID:BW100355314040805-1349256735 at 205.158.241.245
CSeq:15143706 INVITE
Contact:<sip:205.158.241.245:5060>
Alert-Info:<http://127.0.0.1/Bellcore-dr5>
Allow:ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,UPDATE,NOTIFY
Supported:100rel,timer
Min-SE:60
Accept:multipart/mixed,application/sdp
Max-Forwards:10
Content-Type:application/sdp
Content-Length:113
v=0
o=BroadWorks 115330 0 IN IP4 205.158.241.245
s=-
c=IN IP4 205.158.241.245
t=0 0
m=audio 5000 RTP/AVP 0
*Mar 9 16:27:44.276: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143706-1687763104-1123164235314
From: "Ring
Splash"<sip:RingSplash at 205.158.241.245>;tag=1687763104-1123164235314
To: "4435780463"<sip:4435780463 at 172.27.13.102:5060>;tag=2CBB2D3C-1341
Date: Sat, 09 Mar 2002 16:27:44 GMT
Call-ID: BW100355314040805-1349256735 at 205.158.241.245
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 15143706 INVITE
Allow-Events: telephone-event
Content-Length: 0
*Mar 9 16:27:44.284: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:2028210347 at 172.27.13.102:5060 SIP/2.0
Via:SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143708-87566713-1123164235319
From:<sip:7035472147 at 205.158.241.245;user=phone>;tag=87566713-1123164235
319
To:"0347 BT-1"<sip:2028210347 at 172.27.13.102:5060>
Call-ID:BW100355319040805-932787069 at 205.158.241.245
CSeq:15143708 INVITE
Contact:<sip:205.158.241.245:5060>
Diversion:"0463
BT-1-FXS"<sip:4435780463 at 205.158.241.245>;reason=time-of-day;counter=1;p
rivacy=off
Allow:ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,UPDATE,NOTIFY
Supported:100rel,timer
Min-SE:60
Accept:multipart/mixed,application/sdp
Max-Forwards:10
Content-Type:application/sdp
Content-Length:148
v=0
o=BroadWorks 115328 1 IN IP4 205.158.241.235
s=-
c=IN IP4 205.158.241.161
t=0 0
m=audio 14656 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=sendrecv
*Mar 9 16:27:44.296: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143708-87566713-1123164235319
From:
<sip:7035472147 at 205.158.241.245;user=phone>;tag=87566713-1123164235319
To: "0347 BT-1"<sip:2028210347 at 172.27.13.102:5060>;tag=2CBB2D50-9C7
Date: Sat, 09 Mar 2002 16:27:44 GMT
Call-ID: BW100355319040805-932787069 at 205.158.241.245
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 15143708 INVITE
Allow-Events: telephone-event
Content-Length: 0
*Mar 9 16:27:44.304: ISDN Se1/1:23 EVENTd: isdn_get_guid: Got Guid
6B2EDFC581AE
*Mar 9 16:27:44.304: ISDN Se1/1:23 EVENT: process_pri_call: call id
0x8026, number 2028210347, Guid 6B2EDFC581AE, speed 0, call type VOICE,
redial No, CSM call No, pdata YesReceived pdata len 0x3B data:1C 39 9E 1
0 3 67 74 64 0 0 0 2E 49 41 4D 2C D A 47 43 49 2C 36 62 32 65 64 66 63
35 33 32 62 31 31 31 64 36 38 31 61 65 66 34 64 66 33 38 65 63 64 64 32
31 D A D A
*Mar 9 16:27:44.304: ISDN Se1/1:23 EVENTd: process_pri_call: No name in
GTD
*Mar 9 16:27:44.304: ISDN Se1/1:23 Q931: Applying typeplan for sw-type
0x5 is 0x2 0x1, Calling num 7035472147
*Mar 9 16:27:44.308: ISDN Se1/1:23 EVENTd: : Mapping RDN IE to OCN IE -
counter 0 cfnr ind 1696755256
*Mar 9 16:27:44.308: ISDN Se1/1:23 EVENTd: process_pri_call: Packet to
CC Data: 4D 0 1 80 26 29 4 4 80 0 10 A2 18 4 0 3 0 FF 6C D 2
81 80 37 30 33 35 34 37 32 31 34 37 70 C 2 1 32 30 32 38 32 31 30 33
34 37 4 3 8 0 10 18 3 0 3 0 6C C 21 80 37 30 33 35 34 37 32 31
34 37 73 E 0 0 F 81 34 34 33 35 37 38 30 34 36 33
*Mar 9 16:27:44.312: ISDN Se1/1:23 EVENTd: calltrkr_setup_received:
isdn_info=1695181852l, call_id=0x8026 ORIGINATE
*Mar 9 16:27:44.312: ISDN Se1/1:23 EVENTd: calltrkr_setup_received:
calltracker disabled
*Mar 9 16:27:44.312: ISDN Se1/1:23 Q931: TX -> SETUP pd = 8 callref =
0x0014
Bearer Capability i = 0x8090A2
Standard = CCITT
Transfer Capability = Speech
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA98381
Exclusive, Channel 1
Calling Party Number i = 0x2180, '7035472147'
Plan:ISDN, Type:National
Called Party Number i = 0xA1, '2028210347'
Plan:ISDN, Type:National
Original Called Number i = 0x00000F81, '4435780463'
Plan:Unknown, Type:Unknown
*Mar 9 16:27:44.408: ISDN Se1/1:23 Q931: RX <- STATUS pd = 8 callref =
0x8014
Cause i = 0x81E3 - Information element not implemented
Call State i = 0x06
*Mar 9 16:27:44.412: ISDN Se1/1:23 **ERROR**: Ux_Status: STATUS call
state mismatch with invalid cause: cause 0x63, state 0x1, peer state 0x6
*Mar 9 16:27:44.412: ISDN Se1/1:23 Q931: TX -> RELEASE pd = 8 callref
= 0x0014
Cause i = 0x82EF - Protocol error; unspecified
*Mar 9 16:27:44.452: ISDN Se1/1:23 Q931: RX <- CALL_PROC pd = 8
callref = 0x8014
Channel ID i = 0xA98381
Exclusive, Channel 1
*Mar 9 16:27:44.452: ISDN Se1/1:23 **ERROR**: L3_ProcessEvent:
UNEXPECTED event - 0x2 state 19
*Mar 9 16:27:44.456: ISDN Se1/1:23 Q931: RX <- ALERTING pd = 8 callref
= 0x8014
Progress Ind i = 0x8188 - In-band info or appropriate now
available
*Mar 9 16:27:44.456: ISDN Se1/1:23 **ERROR**: L3_ProcessEvent:
UNEXPECTED event - 0x1 state 19
*Mar 9 16:27:44.764: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
CANCEL sip:4435780463 at 172.27.13.102:5060 SIP/2.0
Via:SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143706-1687763104-1123164235314
From:"Ring
Splash"<sip:RingSplash at 205.158.241.245>;tag=1687763104-1123164235314
To:"4435780463"<sip:4435780463 at 172.27.13.102:5060>
Call-ID:BW100355314040805-1349256735 at 205.158.241.245
CSeq:15143706 CANCEL
Max-Forwards:10
Content-Length:0
*Mar 9 16:27:44.768: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143706-1687763104-1123164235314
From: "Ring
Splash"<sip:RingSplash at 205.158.241.245>;tag=1687763104-1123164235314
To: "4435780463"<sip:4435780463 at 172.27.13.102:5060>
Date: Sat, 09 Mar 2002 16:27:44 GMT
Call-ID: BW100355314040805-1349256735 at 205.158.241.245
Content-Length: 0
CSeq: 15143706 CANCEL
*Mar 9 16:27:44.772: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 487 Request Cancelled
Via: SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143706-1687763104-1123164235314
From: "Ring
Splash"<sip:RingSplash at 205.158.241.245>;tag=1687763104-1123164235314
To: "4435780463"<sip:4435780463 at 172.27.13.102:5060>;tag=2CBB2D3C-1341
Date: Sat, 09 Mar 2002 16:27:44 GMT
Call-ID: BW100355314040805-1349256735 at 205.158.241.245
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 15143706 INVITE
Allow-Events: telephone-event
Content-Length: 0
*Mar 9 16:27:44.792: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:4435780463 at 172.27.13.102:5060 SIP/2.0
Via:SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143706-1687763104-1123164235314
From:"Ring
Splash"<sip:RingSplash at 205.158.241.245>;tag=1687763104-1123164235314
To:"4435780463"<sip:4435780463 at 172.27.13.102:5060>;tag=2CBB2D3C-1341
Call-ID:BW100355314040805-1349256735 at 205.158.241.245
CSeq:15143706 ACK
Max-Forwards:10
Content-Length:0
*Mar 9 16:27:44.792: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2,
ConnectionId 6B2BD265 32B111D6 81ACF4DF 38ECDD21, SetupTime
*11:27:44.272 EST Sat Mar 9 2002, PeerAddress RingSplash, PeerSubAddress
, DisconnectCause 10 , DisconnectText normal call clearing (16),
ConnectTime *11:27:44.792 EST Sat Mar 9 2002, DisconnectTime
*11:27:44.792 EST Sat Mar 9 2002, CallOrigin 2, ChargedUnits 0, InfoType
2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
*Mar 9 16:27:47.996: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1,
ConnectionId 6B2BD265 32B111D6 81ACF4DF 38ECDD21, SetupTime
*11:27:44.296 EST Sat Mar 9 2002, PeerAddress 4435780463, PeerSubAddress
, DisconnectCause 10 , DisconnectText normal call clearing (16),
ConnectTime *11:27:47.996 EST Sat Mar 9 2002, DisconnectTime
*11:27:47.996 EST Sat Mar 9 2002, CallOrigin 1, ChargedUnits 0, InfoType
2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
*Mar 9 16:27:48.312: ISDN Se1/1:23 **ERROR**: L3_ProcessEvent:
UNEXPECTED event - 0x1313 state 19
*Mar 9 16:27:48.312: ISDN **ERROR**: Module-l3_sdl_u
Function-U19_BadMsg Error-Bad message received.
*Mar 9 16:27:48.412: ISDN Se1/1:23 Q931: TX -> RELEASE pd = 8 callref
= 0x0014
Cause i = 0x82EF - Protocol error; unspecified
*Mar 9 16:27:48.428: ISDN Se1/1:23 Q931: RX <- RELEASE_COMP pd = 8
callref = 0x8014
Cause i = 0x81D1 - Invalid call reference value
*Mar 9 16:27:48.428: ISDN EVENTd: cc_clear_free_list freed 0x653F5F68
*Mar 9 16:27:48.428: ISDN Se1/1:23 EVENT: process_rxstate: ces/callid
1/0x8026 calltype 2 CALL_REJECTION
*Mar 9 16:27:48.428: ISDN Se1/1:23 EVENTd: process_rxstate: cause=0x51
(81), cause_present=1
*Mar 9 16:27:48.432: ISDN CDAPI: cdapi_find_tsm found a GTD message
RLC,
PRN,isdn*,,NT100,
:
end of gtd length is 27
*Mar 9 16:27:48.432: ISDN Se1/1:23 EVENT: process_rxstate: ces/callid
1/0x8026 calltype 2 CALL_CLEARED
*Mar 9 16:27:48.432: ISDN Se1/1:23 EVENTd: process_rxstate: cause=0x51
(81), cause_present=1
*Mar 9 16:27:48.432: ISDN Se1/1:23 EVENTd: calltrkr_call_cleared:
isdn_info=0x650A6C1C, call_id=0x8026
*Mar 9 16:27:48.432: ISDN Se1/1:23 EVENTd: calltrkr_call_cleared:
isdn_info=0x64FA7ABC, call_id=0x8026
*Mar 9 16:27:48.432: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1,
ConnectionId 6B2EDFC5 32B111D6 81AEF4DF 38ECDD21, SetupTime
*11:27:48.432 EST Sat Mar 9 2002, PeerAddress 2028210347, PeerSubAddress
, DisconnectCause 51 , DisconnectText invalid call ref value (81),
ConnectTime *11:27:48.432 EST Sat Mar 9 2002, DisconnectTime
*11:27:48.432 EST Sat Mar 9 2002, CallOrigin 1, ChargedUnits 0, InfoType
2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
*Mar 9 16:27:48.440: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 500 Internal Server Error
Via: SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143708-87566713-1123164235319
From:
<sip:7035472147 at 205.158.241.245;user=phone>;tag=87566713-1123164235319
To: "0347 BT-1"<sip:2028210347 at 172.27.13.102:5060>;tag=2CBB2D50-9C7
Date: Sat, 09 Mar 2002 16:27:44 GMT
Call-ID: BW100355319040805-932787069 at 205.158.241.245
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 15143708 INVITE
Allow-Events: telephone-event
Reason: Q.850;cause=81
Content-Length: 0
*Mar 9 16:27:48.456: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:2028210347 at 172.27.13.102:5060 SIP/2.0
Via:SIP/2.0/UDP
205.158.241.245;branch=z9hG4bK-BroadWorks.205.158.241.245-172.27.13.102V
5060-0-15143708-87566713-1123164235319
From:<sip:7035472147 at 205.158.241.245;user=phone>;tag=87566713-1123164235
319
To:"0347 BT-1"<sip:2028210347 at 172.27.13.102:5060>;tag=2CBB2D50-9C7
Call-ID:BW100355319040805-932787069 at 205.158.241.245
CSeq:15143708 ACK
Max-Forwards:10
Content-Length:0
*Mar 9 16:27:48.460: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 2,
ConnectionId 6B2EDFC5 32B111D6 81AEF4DF 38ECDD21, SetupTime
*11:27:44.290 EST Sat Mar 9 2002, PeerAddress 7035472147, PeerSubAddress
, DisconnectCause 51 , DisconnectText invalid call ref value (81),
ConnectTime *11:27:48.460 EST Sat Mar 9 2002, DisconnectTime
*11:27:48.460 EST Sat Mar 9 2002, CallOrigin 2, ChargedUnits 0, InfoType
2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
More information about the cisco-voip
mailing list