[Home]

Summary:ASTERISK-09990: ZAP-channel terminates after 3 seconds with hangup cause 18
Reporter:Holger Hornung (netview)Labels:
Date Opened:2007-07-31 09:45:59Date Closed:2007-08-20 17:32:49
Priority:MinorRegression?No
Status:Closed/CompleteComponents:Channels/chan_zap
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:I receive a hangup cause 18 on the outgoing zap-channel.

example: exten => 52,1,Dial(Zap/g1/52},,)

asterisk version is 1.4.9 (1.4.9+bristuff-0.4.0-test4)
zaptel 1.4.4
libpri 1.4.1

****** ADDITIONAL INFORMATION ******

1 -- Restarting T203 counter
fli4l*CLI>
1 -- Restarting T203 counter
fli4l*CLI>
1 -- Restarting T203 counter
1 -- Restarting T203 counter
fli4l*CLI>
   -- Executing [52@outbound:1] GotoIf("SIP/7930-082a09a8", "1?2:35") in new stack
   -- Goto (outbound,52,2)
   -- Executing [52@outbound:2] GotoIf("SIP/7930-082a09a8", "0?35:3") in new stack
   -- Goto (outbound,52,3)
   -- Executing [52@outbound:3] GotoIf("SIP/7930-082a09a8", "0?35:4") in new stack
   -- Goto (outbound,52,4)
   -- Executing [52@outbound:4] NoCDR("SIP/7930-082a09a8", "") in new stack
   -- Executing [52@outbound:5] GotoIf("SIP/7930-082a09a8", "0?11:6") in new stack
   -- Goto (outbound,52,6)
   -- Executing [52@outbound:6] GotoIf("SIP/7930-082a09a8", "0?19:7") in new stack
   -- Goto (outbound,52,7)
   -- Executing [52@outbound:7] GotoIf("SIP/7930-082a09a8", "0?17:8") in new stack
   -- Goto (outbound,52,8)
   -- Executing [52@outbound:8] GotoIf("SIP/7930-082a09a8", "0?13:9") in new stack
   -- Goto (outbound,52,9)
   -- Executing [52@outbound:9] GotoIf("SIP/7930-082a09a8", "0?15:10") in new stack
   -- Goto (outbound,52,10)
   -- Executing [52@outbound:10] GotoIf("SIP/7930-082a09a8", "1?15:20") in new stack
   -- Goto (outbound,52,15)
   -- Executing [52@outbound:15] Set("SIP/7930-082a09a8", "CALLERID(number)=27") in new stack
   -- Executing [52@outbound:16] Goto("SIP/7930-082a09a8", "20") in new stack
   -- Goto (outbound,52,20)
   -- Executing [52@outbound:20] Set("SIP/7930-082a09a8", "CALLERID(name)=27") in new stack
   -- Executing [52@outbound:21] GotoIf("SIP/7930-082a09a8", "0?22:24") in new stack
   -- Goto (outbound,52,24)
   -- Executing [52@outbound:24] GotoIf("SIP/7930-082a09a8", "0?25:27") in new stack
   -- Goto (outbound,52,27)
   -- Executing [52@outbound:27] GotoIf("SIP/7930-082a09a8", "0?28:30") in new stack
   -- Goto (outbound,52,30)
   -- Executing [52@outbound:30] GotoIf("SIP/7930-082a09a8", "0?31:33") in new stack
   -- Goto (outbound,52,33)
   -- Executing [52@outbound:33] Dial("SIP/7930-082a09a8", "ZAP/g1/52&ZAP/g2/52||Tt") in new stack
1 -- Making new call for cr 133
   -- Requested transfer capability: 0x00 - SPEECH
1 > Protocol Discriminator: Q.931 (8)  len=31
1 > Call Ref: len= 1 (reference 5/0x5) (Originator)
1 > Message type: SETUP (5)
1 > [04 03 80 90 a3]
1 > Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
1 >                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
1 >                              Ext: 1  User information layer 1: A-Law (35)
1 > [18 01 89]
1 > Channel ID (len= 3) [ Ext: 1  IntID: Implicit  Other  Spare: 0  Exclusive  Dchan: 0
1 >                        ChanSel: B1 channel
1                          ]
1 > [28 02 32 37]
1 > Display (len= 2) [ 27 ]
1 > [6c 04 00 80 32 37]
1 > Calling Number (len= 6) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
1 >                           Presentation: Presentation permitted, user number not screened (0)  '27' ]
1 > [70 03 c1 35 32]
1 > Called Number (len= 5) [ Ext: 1  TON: Subscriber Number (4)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)  '52' ]
1 > [7d 02 91 81]
1 > High-layer compatibilty (len= 4) [ 1 0x91 1 0x81 1  ]
1 q931.c:3635 q931_setup: call 133 on channel 1 enters state 1 (Call Initiated)
   -- Called g1/52
   -- Requested transfer capability: 0x00 - SPEECH
   -- Called g2/52
fli4l*CLI>
1 -- Restarting T203 counter
1 -- Restarting T203 counter
fli4l*CLI>
1 -- Restarting T203 counter
1 < Protocol Discriminator: Q.931 (8)  len=8
1 < Call Ref: len= 1 (reference 133/0x85) (Terminator)
1 < Message type: RELEASE COMPLETE (90)
1 < [08 02 80 92]
1 < Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: User (0)
1 <                  Ext: 1  Cause: No user responding (18), class = Normal Event (1) ]
1 -- Processing IE 8 (cs0, Cause)
1 -- Restarting T203 counter
fli4l*CLI>
1 -- Restarting T203 counter
1 < Protocol Discriminator: Q.931 (8)  len=4
1 < Call Ref: len= 1 (reference 133/0x85) (Terminator)
1 < Message type: ALERTING (1)
1 q931.c:4275 q931_receive: call 133 on channel 1 enters state 4 (Call Delivered)
1 -- Making new q921 call for cref 133 tei 65
1 -- Restarting T203 counter
fli4l*CLI>
   -- Zap/1-1 is ringing
fli4l*CLI>
1 No response to SETUP message
1 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Delivered, peerstate Call Received
   -- Channel 0/1, span 2 got hangup, cause 18
   -- Channel 0/1, span 1 got hangup, cause 18
fli4l*CLI>
1 sending RELEASE for TEI 65
1 -- Restarting T203 counter
1 > Protocol Discriminator: Q.931 (8)  len=8
1 > Call Ref: len= 1 (reference 5/0x5) (Originator)
1 > Message type: RELEASE (77)
1 > [08 02 80 90]
1 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: User (0)
1 >                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
1 NEW_HANGUP DEBUG: Destroying the call, ourstate Call Delivered, peerstate Call Received
1 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Delivered, peerstate Call Received
   -- Hungup 'Zap/1-1'
fli4l*CLI>
   -- Hungup 'Zap/4-1'
 == Everyone is busy/congested at this time (2:0/0/2)
   -- Executing [52@outbound:34] Macro("SIP/7930-082a09a8", "cbh") in new stack
   -- Executing [s@macro-cbh:1] GotoIf("SIP/7930-082a09a8", "0?5:2") in new stack
   -- Goto (macro-cbh,s,2)
   -- Executing [s@macro-cbh:2] GotoIf("SIP/7930-082a09a8", "0?4:3") in new stack
   -- Goto (macro-cbh,s,3)
   -- Executing [s@macro-cbh:3] GotoIf("SIP/7930-082a09a8", "0?5:6") in new stack
   -- Goto (macro-cbh,s,6)
   -- Executing [s@macro-cbh:6] Hangup("SIP/7930-082a09a8", "") in new stack
 == Spawn extension (macro-cbh, s, 6) exited non-zero on 'SIP/7930-082a09a8'
fli4l*CLI>
1 -- Restarting T203 counter
fli4l*CLI>
1 -- Restarting T203 counter
1 < Protocol Discriminator: Q.931 (8)  len=4
1 < Call Ref: len= 1 (reference 133/0x85) (Terminator)
1 < Message type: RELEASE COMPLETE (90)
1 -- Making new call for cr 133
1 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
1 NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
1 -- Restarting T203 counter
fli4l*CLI>
1 -- Restarting T203 counter
fli4l*CLI>
1 -- Restarting T203 counter
Comments:By: Holger Hornung (netview) 2007-07-31 09:47:55

sorry - there is a typo in the example: exten => 52,1,Dial(Zap/g1/52,,)
By: Joshua C. Colp (jcolp) 2007-07-31 10:00:04

I'm suspending this bug because I believe this is a configuration issue, possibly with whatever device you are talking to. It is the one sending back the 18 causecode and terminating the call attempt. Asterisk is just behaving as it should.
By: Holger Hornung (netview) 2007-07-31 10:05:04

I use the same configuration with asterisk 1.2.23 and there is no cause 18 (zaptel.conf and extensions.conf are identically).
By: Joshua C. Colp (jcolp) 2007-07-31 10:07:50

Then please post the same output from 1.2 for comparison.
By: Holger Hornung (netview) 2007-07-31 10:17:24

-- Executing GotoIf("SIP/7930-0822b270", "1?2:35") in new stack

fli4l*CLI>
   -- Goto (outbound,52,2)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?35:3") in new stack

fli4l*CLI>
   -- Goto (outbound,52,3)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?35:4") in new stack

fli4l*CLI>
   -- Goto (outbound,52,4)

fli4l*CLI>
   -- Executing NoCDR("SIP/7930-0822b270", "") in new stack

fli4l*CLI>
Jul 31 17:28:37 NOTICE[6499]: cdr.c:443 ast_cdr_free: CDR on channel 'SIP/7930-0822b270' not posted

fli4l*CLI>
Jul 31 17:28:37 NOTICE[6499]: cdr.c:445 ast_cdr_free: CDR on channel 'SIP/7930-0822b270' lacks end

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?11:6") in new stack

fli4l*CLI>
   -- Goto (outbound,52,6)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?19:7") in new stack

fli4l*CLI>
   -- Goto (outbound,52,7)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?17:8") in new stack

fli4l*CLI>
   -- Goto (outbound,52,8)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?13:9") in new stack

fli4l*CLI>
   -- Goto (outbound,52,9)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?15:10") in new stack

fli4l*CLI>
   -- Goto (outbound,52,10)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "1?15:20") in new stack

fli4l*CLI>
   -- Goto (outbound,52,15)

fli4l*CLI>
   -- Executing Set("SIP/7930-0822b270", "CALLERID(number)=27") in new stack

fli4l*CLI>
   -- Executing Goto("SIP/7930-0822b270", "20") in new stack

fli4l*CLI>
   -- Goto (outbound,52,20)

fli4l*CLI>
   -- Executing Set("SIP/7930-0822b270", "CALLERID(name)=27") in new stack

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?22:24") in new stack

fli4l*CLI>
   -- Goto (outbound,52,24)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?25:27") in new stack

fli4l*CLI>
   -- Goto (outbound,52,27)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?28:30") in new stack

fli4l*CLI>
   -- Goto (outbound,52,30)

fli4l*CLI>
   -- Executing GotoIf("SIP/7930-0822b270", "0?31:33") in new stack

fli4l*CLI>
   -- Goto (outbound,52,33)

fli4l*CLI>
   -- Executing Dial("SIP/7930-0822b270", "Zap/g1/52&Zap/g2/52||Tt") in new stack

fli4l*CLI>
1 -- Making new call for cr 132

fli4l*CLI>
   -- Requested transfer capability: 0x00 - SPEECH

fli4l*CLI>
1 > Protocol Discriminator: Q.931 (8)  len=31

fli4l*CLI>
1 > Call Ref: len= 1 (reference 4/0x4) (Originator)

fli4l*CLI>
1 > Message type: SETUP (5)

fli4l*CLI>
1 > [04 03 80 90 a3]

fli4l*CLI>
1 > Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)

fli4l*CLI>
1 >                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)

fli4l*CLI>
1 >                              Ext: 1  User information layer 1: A-Law (35)

fli4l*CLI>
1 > [18 01 89]

fli4l*CLI>
1 > Channel ID (len= 3) [ Ext: 1  IntID: Implicit, Other Spare: 0, Exclusive Dchan: 0

fli4l*CLI>
1 >                        ChanSel: B1 channel

fli4l*CLI>
1                          ]

fli4l*CLI>
1 > [28 02 32 37]

fli4l*CLI>
1 > Display (len= 2) [ 27 ]

fli4l*CLI>
1 > [6c 04 00 80 32 37]

fli4l*CLI>
1 > Calling Number (len= 6) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)

fli4l*CLI>
1 >                           Presentation: Presentation permitted, user number not screened (0) '27' ]

fli4l*CLI>
1 > [70 03 c1 35 32]

fli4l*CLI>
1 > Called Number (len= 5) [ Ext: 1  TON: Subscriber Number (4)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '52' ]

fli4l*CLI>
1 > [7d 02 91 81]

fli4l*CLI>

1 > High-layer compatibilty (len= 4) [ 1 0x91 1 0x81 1  ]

fli4l*CLI>
   -- Called g1/52

fli4l*CLI>
   -- Requested transfer capability: 0x00 - SPEECH

fli4l*CLI>
   -- Called g2/52

fli4l*CLI>
1 < Protocol Discriminator: Q.931 (8)  len=8
1 < Call Ref: len= 1 (reference 132/0x84) (Terminator)
1 < Message type: RELEASE COMPLETE (90)
1 < [08 02 80 92]
1 < Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: User (0)
1 <                  Ext: 1  Cause: No user responding (18), class = Normal Event (1) ]
1 -- Processing IE 8 (cs0, Cause)

fli4l*CLI>
1 < Protocol Discriminator: Q.931 (8)  len=4
1 < Call Ref: len= 1 (reference 132/0x84) (Terminator)
1 < Message type: ALERTING (1)

fli4l*CLI>
   -- Zap/1-1 is ringing

fli4l*CLI>
1 < Protocol Discriminator: Q.931 (8)  len=12
1 < Call Ref: len= 1 (reference 132/0x84) (Terminator)
1 < Message type: CONNECT (7)
1 < [4c 06 01 80 37 30 35 30]
1 < COLP (len= 8) [ Ext: 0  TON: Unknown Number Type (0)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
1 <                           Presentation: Presentation permitted, user number not screened (0) '7050' ]
1 -- Processing IE 76 (cs0, Connect Line ID Presentation)
1 > Protocol Discriminator: Q.931 (8)  len=4
1 > Call Ref: len= 1 (reference 4/0x4) (Originator)
1 > Message type: CONNECT ACKNOWLEDGE (15)

fli4l*CLI>
   -- Zap/1-1 answered SIP/7930-0822b270

fli4l*CLI>
1 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Active, peerstate Connect Request
1 > Protocol Discriminator: Q.931 (8)  len=8
1 > Call Ref: len= 1 (reference 4/0x4) (Originator)
1 > Message type: DISCONNECT (69)
1 > [08 02 81 90]
1 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: Private network serving the local user (1)
1 >                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
   -- Hungup 'Zap/1-1'
 == Spawn extension (outbound, 52, 33) exited non-zero on 'SIP/7930-0822b270'
   -- Executing Hangup("SIP/7930-0822b270", "") in new stack
 == Spawn extension (outbound, h, 1) exited non-zero on 'SIP/7930-0822b270'

fli4l*CLI>
1 < Protocol Discriminator: Q.931 (8)  len=4
1 < Call Ref: len= 1 (reference 132/0x84) (Terminator)
1 < Message type: RELEASE (77)
1 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release Request
1 > Protocol Discriminator: Q.931 (8)  len=8
1 > Call Ref: len= 1 (reference 4/0x4) (Originator)
1 > Message type: RELEASE COMPLETE (90)
1 > [08 02 81 90]
1 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: Private network serving the local user (1)
1 >                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
1 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
1 NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null

fli4l*CLI>
By: Russell Bryant (russell) 2007-08-20 17:32:32

We do not support patched versions of Asterisk.