[asterisk-users] libpri error??

Andrew McRory andrew.mcrory at sayso.net
Fri Mar 30 11:05:12 CDT 2012


> > Try adding
> > resetinterval = never
> > here.  The default is to reset any idle channels every 3600 seconds.
> > Unfortunately, if a channel is being reset just as an incoming call
> > arrives, there is a chance that the channel will get stuck in a
> > resetting state and block any further use of that channel.
> 
> Thanks, I'll give this a shot.
> 

This has been working very well for us so far. At least on the TELCO trunk.
Today we have a new problem. The Iwatsu legacy phone system connected on span
2 and 3 is now unable to grab an outbound channel on span 3. Inbound calls are
OK. Iwatsu is configured to dial descending, Asterisk dials ascending.

    [Mar 30 11:26:14] DEBUG[7643] chan_dahdi.c: Span 3: SETUP requested
unavailable channel 0/23.  Attempting to renegotiate.

This channel, DAHDI/71-1, is not shown as in use. 

Here is the call debug log:
==========================================================================
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < Protocol Discriminator: Q.931 (8)
 len=32
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < TEI=0 Call Ref: len= 2 (reference
1/0x1) (Sent from originator)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < Message Type: SETUP (5)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < [04 03 90 90 a2]
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < Bearer Capability (len= 5) [ Ext:
1  Coding-Std: 0  Info transfer capability: 3.1kHz audio (16)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: <                              Ext:
1  Trans mode/rate: 64kbps, circuit-mode (16)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: <                               
User information layer 1: u-Law (34)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < [18 03 a9 83 97]
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < Channel ID (len= 5) [ Ext: 1 
IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Mar 30 11:26:14] VERBOSE[7643] logger.c: <                       ChanSel: As
indicated in following octets
[Mar 30 11:26:14] VERBOSE[7643] logger.c: <                       Ext: 1 
Coding: 0  Number Specified  Channel Type: 3
[Mar 30 11:26:14] VERBOSE[7643] logger.c: <                       Ext: 1 
Channel: 23 Type: NET]
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < [6c 05 00 80 33 39 39]
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < Calling Party Number (len= 7) [
Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: <                                
Presentation: Presentation permitted, user number not screened (0)  'XXX' ]
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < [70 08 80 35 37 36 36 38 34 39]
[Mar 30 11:26:14] VERBOSE[7643] logger.c: < Called Party Number (len=10) [
Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  'XXX6849' ]
[Mar 30 11:26:14] VERBOSE[7643] logger.c: -- Making new call for cref 1
[Mar 30 11:26:14] VERBOSE[7643] logger.c: Received message for call 0x8c925a8
on link 0xb7dcf194 TEI/SAPI 0/0
[Mar 30 11:26:14] VERBOSE[7643] logger.c: -- Processing Q.931 Call Setup
[Mar 30 11:26:14] VERBOSE[7643] logger.c: -- Processing IE 4 (cs0, Bearer
Capability)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: -- Processing IE 24 (cs0, Channel ID)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: -- Processing IE 108 (cs0, Calling
Party Number)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: -- Processing IE 112 (cs0, Called
Party Number)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: q931.c:8515
post_handle_q931_message: Call 1 enters state 6 (Call Present).  Hold state: Idle
[Mar 30 11:26:14] DEBUG[7643] chan_dahdi.c: Span 3: SETUP requested
unavailable channel 0/23.  Attempting to renegotiate.
[Mar 30 11:26:14] VERBOSE[7643] logger.c: q931.c:7004 q931_hangup: Hangup
other cref:1
[Mar 30 11:26:14] VERBOSE[7643] logger.c: q931.c:6761 __q931_hangup: ourstate
Call Present, peerstate Call Initiated, hold-state Idle
[Mar 30 11:26:14] VERBOSE[7643] logger.c: q931.c:6252 q931_release_complete:
Call 1 enters state 0 (Null).  Hold state: Idle
[Mar 30 11:26:14] VERBOSE[7643] logger.c:
[Mar 30 11:26:14] VERBOSE[7643] logger.c: > DL-DATA request
[Mar 30 11:26:14] VERBOSE[7643] logger.c: > Protocol Discriminator: Q.931 (8)
 len=9
[Mar 30 11:26:14] VERBOSE[7643] logger.c: > TEI=0 Call Ref: len= 2 (reference
1/0x1) (Sent to originator)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: > Message Type: RELEASE COMPLETE (90)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: TEI=0 Transmitting N(S)=89, window
is open V(A)=89 K=7
[Mar 30 11:26:14] VERBOSE[7643] logger.c:
[Mar 30 11:26:14] VERBOSE[7643] logger.c: > Protocol Discriminator: Q.931 (8)
 len=9
[Mar 30 11:26:14] VERBOSE[7643] logger.c: > TEI=0 Call Ref: len= 2 (reference
1/0x1) (Sent to originator)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: > Message Type: RELEASE COMPLETE (90)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: > [08 02 81 ac]
[Mar 30 11:26:14] VERBOSE[7643] logger.c: > Cause (len= 4) [ Ext: 1  Coding:
CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the
local user (1)
[Mar 30 11:26:14] VERBOSE[7643] logger.c: >                  Ext: 1  Cause:
Requested channel not available (44), class = Network Congestion (resource
unavailable) (2) ]
[Mar 30 11:26:14] VERBOSE[7643] logger.c: q931.c:7004 q931_hangup: Hangup
other cref:1
[Mar 30 11:26:14] VERBOSE[7643] logger.c: q931.c:6761 __q931_hangup: ourstate
Null, peerstate Null, hold-state Idle
[Mar 30 11:26:14] VERBOSE[7643] logger.c: Destroying call 0x8c925a8, ourstate
Null, peerstate Null, hold-state Idle
===============================================================================

While I was writing this message, the onsite tech pulled the cable on span 3
and outbound calls are going through now. Here is the log from the good call
on span 2. It's not stuck on dialing out on 0/23...

===============================================================================
< Protocol Discriminator: Q.931 (8)  len=36
< TEI=0 Call Ref: len= 2 (reference 6/0x6) (Sent from originator)
< Message Type: SETUP (5)
< [04 03 80 90 a2]
< Bearer Capability (len= 5) [ Ext: 1  Coding-Std: 0  Info transfer
capability: Speech (0)
<                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
<                                User information layer 1: u-Law (34)
< [18 03 a9 83 92]
< Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0 
Exclusive  Dchan: 0
<                       ChanSel: As indicated in following octets
<                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
<                       Ext: 1  Channel: 18 Type: NET]
< [6c 05 00 80 32 37 30]
< Calling Party Number (len= 7) [ Ext: 0  TON: Unknown Number Type (0)  NPI:
Unknown Number Plan (0)
<                                 Presentation: Presentation permitted, user
number not screened (0)  'ZZZ' ]
< [70 0c 80 31 38 30 30 34 36 37 34 34 34 38]
< Called Party Number (len=14) [ Ext: 1  TON: Unknown Number Type (0)  NPI:
Unknown Number Plan (0)  'ZZZZZZZ4448' ]
-- Making new call for cref 6
Received message for call 0xb7ddc9f8 on link 0xb7dcb33c TEI/SAPI 0/0
-- Processing Q.931 Call Setup
-- Processing IE 4 (cs0, Bearer Capability)
-- Processing IE 24 (cs0, Channel ID)
-- Processing IE 108 (cs0, Calling Party Number)
-- Processing IE 112 (cs0, Called Party Number)
q931.c:8515 post_handle_q931_message: Call 6 enters state 6 (Call Present). 
Hold state: Idle
q931.c:5644 q931_call_proceeding: Call 6 enters state 9 (Incoming Call
Proceeding).  Hold state: Idle

> DL-DATA request
> Protocol Discriminator: Q.931 (8)  len=10
> TEI=0 Call Ref: len= 2 (reference 6/0x6) (Sent to originator)
> Message Type: CALL PROCEEDING (2)
TEI=0 Transmitting N(S)=2, window is open V(A)=2 K=7

> Protocol Discriminator: Q.931 (8)  len=10

> TEI=0 Call Ref: len= 2 (reference 6/0x6) (Sent to originator)
> Message Type: CALL PROCEEDING (2)
> [18 03 a9 83 92]
> Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0 
Exclusive  Dchan: 0
>                       ChanSel: As indicated in following octets
>                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
>                       Ext: 1  Channel: 18 Type: NET]
    -- Accepting call from 'XXX' to 'ZZZZZZZ4448' on channel 0/18, span 2
=========================================================================================

Any ideas for testing / debugging / reconfiguring? 

I have some ISDN pcap files of failed calls on span 3 and span 1 if it will help. 

Thank you,
--
Andrew McRory
Sayso Communications, Inc.
2850 Industrial Plaza
Tallahassee, Florida 32301
Office) 850-224-5737
Mobile) 850-778-3206



More information about the asterisk-users mailing list