[asterisk-users] BT ISDN-30 Call Failures
Russell Brown
russell at lls.lls.com
Wed Mar 17 12:41:17 CDT 2010
I'm seeing both inbound and outgoing call failures on our ISDN-30 lines
that only seem to go away when I do a "zap restart" or in extremis
restart Asterisk (1.4.25 with a Digium TE205P and zaptel 1.4.12.1). If
I don't restart zapata or Asterisk the problem rapidly get worse :-(
The lines are from BT with LCR from Cable&Wireless (I've tried using the
LCR bypass code and it doesn't make any difference).
The outbound symptoms are that the number appears to go out on the ISDN
but the caller hears nothing and the callee's phone doesn't ring
(example below with ISDN debug on).
The incoming problem is that the callee's phone rings for a couple of
seconds and then the call gets cutoff (again example enclosed) or
sometimes doesn't appear at all in the Asterisk logs and the callee gets
a busy tone (no the system hasn't used all of the channels at this point :-).
Can anyone suggest a cause and/or remedy? Any idea what the
disconnection stuff in the PRI debug means?
Outbound log extract:
[Mar 17 16:58:02] VERBOSE[6630] logger.c: -- Executing [01780471800 at from-sip:18] Dial("SIP/197-b6726980", "Zap/G1/01780471800|9999|TWK") in new stack
[Mar 17 16:58:02] VERBOSE[6630] logger.c: -- Making new call for cr 33089
[Mar 17 16:58:02] VERBOSE[6630] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Protocol Discriminator: Q.931 (8) len=40
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Call Ref: len= 2 (reference 321/0x141) (Originator)
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Message type: SETUP (5)
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > [04 03 80 90 a3]
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0)
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > User information layer 1: A-Law (35)
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > [18 03 a9 83 9e]
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > ChanSel: As indicated in following octets
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Ext: 1 Coding: 0 Number Specified Channel Type: 3
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Ext: 1 Channel: 30 ]
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > [6c 08 00 80 38 34 36 30 38 30]
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Calling Number (len=10) [ Ext: 0 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0)
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Presentation: Presentation permitted, user number not screened (0) '846080' ]
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > [70 0c 80 30 31 37 38 30 34 37 31 38 30 32]
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Called Number (len=14) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '01780471800' ]
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > [a1]
[Mar 17 16:58:02] VERBOSE[6630] logger.c: > Sending Complete (len= 1)
[Mar 17 16:58:02] VERBOSE[6630] logger.c: q931.c:3134 q931_setup: call 33089 on channel 30 enters state 1 (Call Initiated)
[Mar 17 16:58:02] VERBOSE[6630] logger.c: -- Called G1/01780471800
*** At this point the caller hears nothing and the phone on
*** 01780471800 *doesn't* receive a call.
[Mar 17 16:58:50] VERBOSE[6630] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Initiated, peerstate Overlap sending
[Mar 17 16:58:50] VERBOSE[6630] logger.c: q931.c:3015 q931_disconnect: call 33089 on channel 30 enters state 11 (Disconnect Request)
[Mar 17 16:58:50] VERBOSE[6630] logger.c: > Protocol Discriminator: Q.931 (8) len=9
[Mar 17 16:58:50] VERBOSE[6630] logger.c: > Call Ref: len= 2 (reference 321/0x141) (Originator)
[Mar 17 16:58:50] VERBOSE[6630] logger.c: > Message type: DISCONNECT (69)
[Mar 17 16:58:50] VERBOSE[6630] logger.c: > [08 02 81 90]
[Mar 17 16:58:50] VERBOSE[6630] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
[Mar 17 16:58:50] VERBOSE[6630] logger.c: > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
[Mar 17 16:58:50] VERBOSE[6630] logger.c: -- Hungup 'Zap/30-1'
[Mar 17 16:58:50] VERBOSE[6630] logger.c: == Spawn extension (from-sip, 01780471800, 18) exited non-zero on 'SIP/197-b6726980'
Here's an example of the inbound failure:
[Mar 17 17:04:46] VERBOSE[13006] logger.c: -- Executing [846092 at isdn_in:1] Ringing("Zap/20-1", "") in new stack
[Mar 17 17:04:46] VERBOSE[13006] logger.c: q931.c:2844 q931_alerting: call 79 on channel 20 enters state 7 (Call Received)
[Mar 17 17:04:46] VERBOSE[13006] logger.c: > Protocol Discriminator: Q.931 (8) len=9
[Mar 17 17:04:46] VERBOSE[13006] logger.c: > Call Ref: len= 2 (reference 79/0x4F) (Terminator)
[Mar 17 17:04:46] VERBOSE[13006] logger.c: > Message type: ALERTING (1)
[Mar 17 17:04:46] VERBOSE[13006] logger.c: > [1e 02 81 88]
[Mar 17 17:04:46] VERBOSE[13006] logger.c: > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
[Mar 17 17:04:46] VERBOSE[13006] logger.c: > Ext: 1 Progress Description: Inband information or appropriate pattern now available. (8) ]
[Mar 17 17:04:46] VERBOSE[13006] logger.c: -- Executing [846092 at isdn_in:2] Set("Zap/20-1", "CALLERID(name)=Banana") in new stack
<snip>
[Mar 17 17:04:48] VERBOSE[13006] logger.c: -- Executing [s at macro-stdexten:38] Dial("Zap/20-1", "Sip/192|15|wWtTkK") in new stack
[Mar 17 17:04:48] VERBOSE[13006] logger.c: -- Called 192
[Mar 17 17:04:48] VERBOSE[13006] logger.c: -- SIP/192-02980aa0 is ringing
[Mar 17 17:04:49] VERBOSE[13006] logger.c: -- SIP/192-02980aa0 is ringing
[Mar 17 17:04:50] VERBOSE[13006] logger.c: -- SIP/192-02980aa0 is ringing
[Mar 17 17:04:52] VERBOSE[13006] logger.c: -- SIP/192-02980aa0 is ringing
[Mar 17 17:04:55] VERBOSE[13006] logger.c: == Spawn extension (macro-stdexten, s, 38) exited non-zero on 'Zap/20-1' in macro 'stdexten'
[Mar 17 17:04:55] VERBOSE[13006] logger.c: == Spawn extension (macro-TELESALESUSER, s, 3) exited non-zero on 'Zap/20-1' in macro 'TELESALESUSER'
[Mar 17 17:04:55] VERBOSE[13006] logger.c: == Spawn extension (real_isdn_in, 846092, 1) exited non-zero on 'Zap/20-1'
[Mar 17 17:04:55] VERBOSE[13006] logger.c: -- Executing [h at real_isdn_in:1] Hangup("Zap/20-1", "") in new stack
[Mar 17 17:04:55] VERBOSE[13006] logger.c: == Spawn extension (real_isdn_in, h, 1) exited non-zero on 'Zap/20-1'
[Mar 17 17:04:55] VERBOSE[13006] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request
*** Who requested this disconnect? Any ideas?
[Mar 17 17:04:55] VERBOSE[13006] logger.c: q931.c:2967 q931_release: call 79 on channel 20 enters state 19 (Release Request)
[Mar 17 17:04:55] VERBOSE[13006] logger.c: > Protocol Discriminator: Q.931 (8) len=9
[Mar 17 17:04:55] VERBOSE[13006] logger.c: > Call Ref: len= 2 (reference 79/0x4F) (Terminator)
[Mar 17 17:04:55] VERBOSE[13006] logger.c: > Message type: RELEASE (77)
[Mar 17 17:04:55] VERBOSE[13006] logger.c: > [08 02 81 e6]
[Mar 17 17:04:55] VERBOSE[13006] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
[Mar 17 17:04:55] VERBOSE[13006] logger.c: > Ext: 1 Cause: Recover on timer expiry (102), class = Protocol Error (e.g. unknown message) (6) ]
*** Ummm... 'Protocol Error' doesn't sound nice!
[Mar 17 17:04:55] VERBOSE[13006] logger.c: -- Hungup 'Zap/20-1'
Any Ideas?
Thanks in Advance.
--
Regards,
Russell
--------------------------------------------------------------------
| Russell Brown | MAIL: russell at lls.com PHONE: 01780 471800 |
| Lady Lodge Systems | WWW Work: http://www.lls.com |
| Peterborough, England | WWW Play: http://www.ruffle.me.uk |
--------------------------------------------------------------------
More information about the asterisk-users
mailing list