[asterisk-users] PRI E1 Inbound calls hangup with busy after a few seconds

Daniel Johnson dan.j at scanningsystems.com.au
Fri Sep 19 01:26:49 CDT 2008


Hi,

I have a 10 line PRI E1 ISDN service from AAPT. Connected to Asterisk 
1.4 via a Digium TE121P.

All oubound calls work fine.

Inbound works only if I Dial a SIP phone directly or as the first step. 
This phone MUST NOT be busy or else the call will fail.

eg.

This works:
[from-pstn]
exten => _34397333,1,Dial(SIP/511,30)
exten => _34397333,n,Hangup

This also works:
exten => _34397333,1,Dial(SIP/511,2)
exten => _34397333,n,GoTo(ConfMe,s,1)
exten => _34397333,n,Hangup

This does not work:
exten => _34397333,1,GoTo(ConfMe,s,1)
exten => _34397333,n,Hangup

[ConfMe]
exten => s,1,Answer
exten => s,n,MeetMe(9000|crM|)
exten => s,n,Playback(vm-goodbye)
exten => s,n,Hangup

Below I have included the PRI debug output for a successful and failed 
inbound call. But first I have done a break down of the messages 
received for both failed and successful calls.

Failed:
Message type: SETUP (5)
Message type: CALL PROCEEDING (2)
Message type: CONNECT (7)
Message type: STATUS (125)
Message type: STATUS (125)
Message type: SETUP (5)
Message type: DISCONNECT (69)
Message type: RELEASE (77)
Message type: RELEASE COMPLETE (90)

Successful:
Message type: SETUP (5)
Message type: CALL PROCEEDING (2)
Message type: ALERTING (1)
Message type: STATUS (125)
Message type: CONNECT (7)
Message type: CONNECT ACKNOWLEDGE (15)
Message type: STATUS (125)

And now the call is fully connected...

Now I have no experience with PRI ISDN. However it seems to be that the 
ALERTING message is important and the Dial(SIP/511,30) is forcing this 
response as it starts to ring.
Why Answer() does not do this I think is my problem... however this is 
probably because of a config issue.
Or am I missing something?

Could it be that ISDN service has not been setup correctly? I have 
called AAPT and their tech ran test and think it is all configured 
correctly on their side.

Thank you for your help.


zaptel.conf

loadzone=au
defaultzone=au

# Span 1: WCT1/0 "Wildcard TE121 Card 0" (MASTER) B8ZS/ESF RED
span=1,1,0,ccs,hdb3,crc4
bchan=1-10
unused=11-15,17,31
dchan=16

--------------------------

zapata.conf

[trunkgroups]
trunkgroup => 1,16
spanmap => 1,1,1

[channels]
; Span 1: WCT1/0 "Wildcard TE121 Card 0" (MASTER) B8ZS/ESF RED
group=1
context=from-pstn
switchtype=euroisdn
signalling=pri_cpe
channel => 1-10
group=1
context=default





PRI debug output for a failed inbound:
< Protocol Discriminator: Q.931 (8)  len=45
< Call Ref: len= 2 (reference 3687/0xE67) (Originator)
< Message type: SETUP (5)
< [04 03 80 90 a3]
< Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer 
capability: Speech (0)
<                              Ext: 1  Trans mode/rate: 64kbps, 
circuit-mode (16)
<                                User information layer 1: A-Law (35)
< [18 03 a1 83 87]
< Channel ID (len= 5) [ Ext: 1  IntID: Implicit  PRI  Spare: 0  
Preferred  Dchan: 0
<                        ChanSel: As indicated in following octets
<                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
<                       Ext: 1  Channel: 7 ]
< [6c 0c 21 83 30 37 33 33 38 37 35 35 35 35]
< Calling Number (len=14) [ Ext: 0  TON: National Number (2)  NPI: 
ISDN/Telephony Numbering Plan (E.164/E.163) (1)
<                           Presentation: Presentation allowed of 
network provided number (3)  '0733875555' ]
< [70 09 c1 33 34 33 39 37 33 30 30]
< Called Number (len=11) [ Ext: 1  TON: Subscriber Number (4)  NPI: 
ISDN/Telephony Numbering Plan (E.164/E.163) (1)  '34397300' ]
< [7d 02 91 81]
< IE: High-layer Compatibility (len = 4)
< [a1]sk*CLI>
< Sending Complete (len= 1)
-- Making new call for cr 3687
-- Processing Q.931 Call Setup
-- Processing IE 4 (cs0, Bearer Capability)
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 108 (cs0, Calling Party Number)
-- Processing IE 112 (cs0, Called Party Number)
-- Processing IE 125 (cs0, High-layer Compatibility)
-- Processing IE 161 (cs0, Sending Complete)
q931.c:3509 q931_receive: call 3687 on channel 7 enters state 6 (Call 
Present)
q931.c:2774 q931_call_proceeding: call 3687 on channel 7 enters state 9 
(Incoming Call Proceeding)
 > Protocol Discriminator: Q.931 (8)  len=11
 > Call Ref: len= 2 (reference 3687/0xE67) (Terminator)
 > Message type: CALL PROCEEDING (2)
 > [18 04 e9 81 83 87]
 > Channel ID (len= 6) [ Ext: 1  IntID: Explicit  PRI  Spare: 0  
Exclusive  Dchan: 0
 >                        ChanSel: As indicated in following octets
 >                       Ext: 1  DS1 Identifier: 1 
 >                       Ext: 1  Coding: 0  Number Specified  Channel 
Type: 3
 >                       Ext: 1  Channel: 7 ]
    -- Accepting call from '0733875555' to '34397300' on channel 1/7, span 1
    -- Executing [34397300 at from-pstn:1] NoOp("Zap/7-1", "New Call in 
from-pstn: 0733875555") in new stack
    -- Executing [34397300 at from-pstn:2] Set("Zap/7-1", "GROUP()=OUT_5") 
in new stack
    -- Executing [34397300 at from-pstn:3] NoOp("Zap/7-1", "USED CHANNEL 
COUNT - OUT_5 = 1") in new stack
    -- Executing [34397300 at from-pstn:4] GotoIfTime("Zap/7-1", 
"8:30-17:15|mon-fri|25|dec?After-Hours-Queue|s|1") in new stack
    -- Executing [34397300 at from-pstn:5] GotoIfTime("Zap/7-1", 
"8:30-17:15|mon-fri|*|*?Inbound-Queue|s|1") in new stack
    -- Goto (Inbound-Queue,s,1)
    -- Executing [s at Inbound-Queue:1] Dial("Zap/7-1", "SIP/500| 6") in 
new stack
    -- Called 500
    -- Got SIP response 486 "Busy Here" back from 192.168.0.188
    -- SIP/500-0821ff70 is busy
  == Everyone is busy/congested at this time (1:1/0/0)
    -- Executing [s at Inbound-Queue:2] Dial("Zap/7-1", "SIP/518| 6") in 
new stack
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s at Inbound-Queue:3] Answer("Zap/7-1", "") in new stack
q931.c:2909 q931_connect: call 3687 on channel 7 enters state 8 (Connect 
Request)
 > Protocol Discriminator: Q.931 (8)  len=15
 > Call Ref: len= 2 (reference 3687/0xE67) (Terminator)
 > Message type: CONNECT (7)
 > [18 04 e9 81 83 87]
 > Channel ID (len= 6) [ Ext: 1  IntID: Explicit  PRI  Spare: 0  
Exclusive  Dchan: 0
 >                        ChanSel: As indicated in following octets
 >                       Ext: 1  DS1 Identifier: 1 
 >                       Ext: 1  Coding: 0  Number Specified  Channel 
Type: 3
 >                       Ext: 1  Channel: 7 ]
 > [1e 02 81 82]
 > Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard 
(0)  0: 0  Location: Private network serving the local user (1)
 >                               Ext: 1  Progress Description: Called 
equipment is non-ISDN. (2) ]
    -- Executing [s at Inbound-Queue:4] BackGround("Zap/7-1", 
"ssa/welcome&ssa/please-hold&ssa/or-choose&ssa/main-menu") in new stack
    -- <Zap/7-1> Playing 'ssa/welcome' (language 'en')
< Protocol Discriminator: Q.931 (8)  len=13
< Call Ref: len= 2 (reference 3687/0xE67) (Originator)
< Message type: STATUS (125)
< [08 03 80 e4 18]
< Cause (len= 5) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  
Location: User (0)
<                  Ext: 1  Cause: Invalid information element contents 
(100), class = Protocol Error (e.g. unknown message) (6) ]
<              Cause data 1: 18 (24)
< [14 01 06]>
< Call State (len= 3) [ Ext: 0  Coding: CCITT (ITU) standard (0)  Call 
state: Call Present (6)
-- Processing IE 8 (cs0, Cause)
-- Processing IE 20 (cs0, Call State)
< Protocol Discriminator: Q.931 (8)  len=13
< Call Ref: len= 2 (reference 3687/0xE67) (Originator)
< Message type: STATUS (125)
< [08 03 80 e4 18]
< Cause (len= 5) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  
Location: User (0)
<                  Ext: 1  Cause: Invalid information element contents 
(100), class = Protocol Error (e.g. unknown message) (6) ]
<              Cause data 1: 18 (24)
< [14 01 06]>
< Call State (len= 3) [ Ext: 0  Coding: CCITT (ITU) standard (0)  Call 
state: Call Present (6)
-- Processing IE 8 (cs0, Cause)
-- Processing IE 20 (cs0, Call State)
    -- <Zap/7-1> Playing 'ssa/please-hold' (language 'en')
< Protocol Discriminator: Q.931 (8)  len=45
< Call Ref: len= 2 (reference 3687/0xE67) (Originator)
< Message type: SETUP (5)
< [04 03 80 90 a3]
< Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer 
capability: Speech (0)
<                              Ext: 1  Trans mode/rate: 64kbps, 
circuit-mode (16)
<                                User information layer 1: A-Law (35)
< [18 03 a1 83 87]
< Channel ID (len= 5) [ Ext: 1  IntID: Implicit  PRI  Spare: 0  
Preferred  Dchan: 0
<                        ChanSel: As indicated in following octets
<                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
<                       Ext: 1  Channel: 7 ]
< [6c 0c 21 83 30 37 33 33 38 37 35 35 35 35]
< Calling Number (len=14) [ Ext: 0  TON: National Number (2)  NPI: 
ISDN/Telephony Numbering Plan (E.164/E.163) (1)
<                           Presentation: Presentation allowed of 
network provided number (3)  '0733875555' ]
< [70 09 c1 33 34 33 39 37 33 30 30]
< Called Number (len=11) [ Ext: 1  TON: Subscriber Number (4)  NPI: 
ISDN/Telephony Numbering Plan (E.164/E.163) (1)  '34397300' ]
< [7d 02 91 81]
< IE: High-layer Compatibility (len = 4)
< [a1]sk*CLI>
< Sending Complete (len= 1)
-- Processing Q.931 Call Setup
-- Processing IE 4 (cs0, Bearer Capability)
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 108 (cs0, Calling Party Number)
-- Processing IE 112 (cs0, Called Party Number)
-- Processing IE 125 (cs0, High-layer Compatibility)
-- Processing IE 161 (cs0, Sending Complete)
Timed out looking for connect acknowledge
q931.c:2973 q931_disconnect: call 3687 on channel 7 enters state 11 
(Disconnect Request)
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 3687/0xE67) (Terminator)
 > Message type: DISCONNECT (69)
 > [08 02 81 90]
 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  
Location: Private network serving the local user (1)
 >                  Ext: 1  Cause: Normal Clearing (16), class = Normal 
Event (1) ]
< Protocol Discriminator: Q.931 (8)  len=5
< Call Ref: len= 2 (reference 3687/0xE67) (Originator)
< Message type: RELEASE (77)
q931.c:3759 q931_receive: call 3687 on channel 7 enters state 0 (Null)
    -- Channel 1/7, span 1 got hangup, cause -1
  == Spawn extension (Inbound-Queue, s, 4) exited non-zero on 'Zap/7-1'
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release 
Request
 > Protocol Discriminator: Q.931 (8)  len=9
 > Call Ref: len= 2 (reference 3687/0xE67) (Terminator)
 > Message type: RELEASE COMPLETE (90)
 > [08 02 81 90]
 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  
Location: Private network serving the local user (1)
 >                  Ext: 1  Cause: Normal Clearing (16), class = Normal 
Event (1) ]
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
    -- Hungup 'Zap/7-1'

-----------------------------------------------------------------------------------------------------------------

PRI Debug output for successful inbound:
----------------------------------------------------------------------------------------------------------------

This is included in a reply as the message was to big.

-- 
*Daniel Johnson*
Systems Administrator / Systems Development
Scanning Systems Australia

Scanning Systems Australia

*Office:* +61 7 3387 5555
*Facsimile:* +61 7 3387 5588
*E-mail:* dan.j at scanningsystems.com.au <mailto:dan.j at scanningsystems.com.au>
*Website:* http://www.scanningsystems.com.au

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20080919/dec4e45a/attachment.htm 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: ssa-logo.jpg
Type: image/jpeg
Size: 5930 bytes
Desc: not available
Url : http://lists.digium.com/pipermail/asterisk-users/attachments/20080919/dec4e45a/attachment.jpg 


More information about the asterisk-users mailing list