T1 CAS (nfas) - No outbound and Inbound calls after a power outage

[abraham23482]

We have a site with a 4351 H323 Voice Gateway. After a power outage last day, both incoming and outgoing calls are not working. After deleting and reconfiguring the controller, i am seeing the below errors on the console. T1 controller and ISDN status are fine. Show ISDN nfas group shows that the ISDN D channels are out of service. Rebooted the router but no luck. Could you someone please suggest me a solution. 

Aug 30 16:12:23: ISDN Se0/1/0:23 **ERROR**: handle_l2d_srq_mail: Layer 1 inactive
Aug 30 16:12:23: ISDN Se0/1/0:23 **ERROR**: handle_l2d_srq_mail: Layer 1 inactive

This is the debug log of an outbound call. 

Aug 31 12:49:55: ISDN Se0/1/0:23 Q931: RX <- SETUP pd = 8 callref = 0x0CA4
Bearer Capability i = 0x8090A2
Standard = CCITT
Transfer Capability = Speech
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA98381
Exclusive, Channel 1
Facility i = 0x9F8B0100A10802017E0201008400
Progress Ind i = 0x8283 - Origination address is non-ISDN
Calling Party Number i = 0x4183, 'ZZZZZ'
Plan:ISDN, Type:Subscriber(local)
Called Party Number i = 0xA1, 'XXXX'
Plan:ISDN, Type:National
Aug 31 12:49:55: ISDN Se0/1/0:23 **ERROR**: L3_GetUser_NLCB: SETUP on not currently active DSL.
Aug 31 12:49:55: ISDN Se0/1/0:23 **ERROR**: L3_GetUser_NLCB: is_nfas_not_active()

Show ISDN Status

==================================

ISDN Serial0/1/0:23 interface
dsl 0, interface ISDN Switchtype = primary-4ess : Primary D channel of nfas group 1
Layer 1 Status:
ACTIVE
Layer 2 Status:
TEI = 0, Ces = 1, SAPI = 0, State = MULTIPLE_FRAME_ESTABLISHED
Layer 3 Status:
0 Active Layer 3 Call(s)
Active dsl 0 CCBs = 0
The Free Channel Mask: 0x807FFFFF
Number of L2 Discards = 0, L2 Session ID = 2

show isdn nfas group 1

=====================

ISDN NFAS GROUP 1 ENTRIES:

The primary D is Serial0/1/0:23.
The backup D is Serial0/1/1:23.

There are 2 total nfas members.
There are 0 total available B channels.
The primary D-channel is DSL 0 in state OUT OF SERVICE
The backup D-channel is DSL 1 in state OUT OF SERVICE
The current active layer 2 DSL is 255.

[chrihussey]

Where do both PRIs terminate? (Phone company?)

Can you post the configs of the T1 controllers, serial interfaces and the output of "show isdn service".

Just shooting from the hip here but have you tried "isdn service b_channel 0 state 0" command under the serial interfaces? This basically just tells to put all channels in service.

Also, a debug of isdn q921 may provide some answers.

[abraham23482]

Thanks for your time. The PRIs terminate to the Service Provider and below are the various outputs.

Controller Status

================

T1 0/1/0 is up.
Applique type is Channelized T1
Cablelength is long gain36 0db
Description: T1 PRI Primary
No alarms detected.
alarm-trigger is not set
Soaking time: 3, Clearance time: 10
AIS State:Clear LOS State:Clear LOF State:Clear
Framing is ESF, Line Code is B8ZS, Clock Source is Line.
Data in current interval (823 seconds elapsed):
0 Line Code Violations, 0 Path Code Violations
9 Slip Secs, 0 Fr Loss Secs, 0 Line Err Secs, 0 Degraded Mins
9 Errored Secs, 0 Bursty Err Secs, 0 Severely Err Secs, 0 Unavail Secs
Total Data (last 36 15 minute intervals):
2 Line Code Violations, 2 Path Code Violations,
408 Slip Secs, 0 Fr Loss Secs, 1 Line Err Secs, 0 Degraded Mins,
408 Errored Secs, 1 Bursty Err Secs, 0 Severely Err Secs, 2 Unavail Secs

sh ISDN Service
=================
PRI Channel Statistics:
ISDN Se0/1/0:23, Channel [1-24]
Configured Isdn Interface (dsl) 0
Channel State (0=Idle 1=Proposed 2=Busy 3=Reserved 4=Restart 5=Maint_Pend)
Channel : 1 2 3 4 5 6 7 8 9 0 1 2 3 4 5 6 7 8 9 0 1 2 3 4
State : 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 3
Service State (0=Inservice 1=Maint 2=Outofservice 8=MaintPend 9=OOSPend)
Channel : 1 2 3 4 5 6 7 8 9 0 1 2 3 4 5 6 7 8 9 0 1 2 3 4
State : 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2
ISDN Se0/1/1:23, Channel [1-24]
Configured Isdn Interface (dsl) 1
Channel State (0=Idle 1=Proposed 2=Busy 3=Reserved 4=Restart 5=Maint_Pend)
Channel : 1 2 3 4 5 6 7 8 9 0 1 2 3 4 5 6 7 8 9 0 1 2 3 4
State : 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 3
Service State (0=Inservice 1=Maint 2=Outofservice 8=MaintPend 9=OOSPend)
Channel : 1 2 3 4 5 6 7 8 9 0 1 2 3 4 5 6 7 8 9 0 1 2 3 4
State : 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2

sh run interface serial
=========================
interface Serial0/1/0:23
encapsulation hdlc
isdn switch-type primary-4ess
isdn incoming-voice voice
isdn bchan-number-order ascending
end

Sh interface serial
===================
Serial0/1/0:23 is up, line protocol is up (spoofing)
Hardware is NIM-2MFT-T1/E1
MTU 1500 bytes, BW 64 Kbit/sec, DLY 20000 usec,
reliability 255/255, txload 1/255, rxload 1/255
Encapsulation HDLC, crc 16, loopback not set
Keepalive set (10 sec)
Last input 00:00:26, output 00:00:26, output hang never
Last clearing of "show interface" counters never
Input queue: 0/375/0/0 (size/max/drops/flushes); Total output drops: 0
Queueing strategy: fifo
Output queue: 0/40 (size/max)
5 minute input rate 0 bits/sec, 0 packets/sec
5 minute output rate 0 bits/sec, 0 packets/sec
1285 packets input, 13162 bytes, 0 no buffer
Received 0 broadcasts (0 IP multicasts)
0 runts, 0 giants, 0 throttles
0 input errors, 0 CRC, 0 frame, 0 overrun, 0 ignored, 0 abort
1290 packets output, 8109 bytes, 0 underruns
0 output errors, 0 collisions, 1 interface resets
0 unknown protocol drops
0 output buffer failures, 0 output buffers swapped out
1 carrier transitions no alarm present
Timeslot(s) Used:24, subrate: 64Kb/s, transmit delay is 0 flags

Sh ISDN Status
================
Global ISDN Switchtype = primary-4ess
ISDN Serial0/1/0:23 interface
dsl 0, interface ISDN Switchtype = primary-4ess : Primary D channel of nfas group 1
Layer 1 Status:
ACTIVE
Layer 2 Status:
TEI = 0, Ces = 1, SAPI = 0, State = MULTIPLE_FRAME_ESTABLISHED
Layer 3 Status:
0 Active Layer 3 Call(s)
Active dsl 0 CCBs = 0
The Free Channel Mask: 0x807FFFFF
Number of L2 Discards = 0, L2 Session ID = 2

debug isdn q921
Aug 31 17:52:26: ISDN Se0/1/0:23 Q921: User TX -> RRp sapi=0 tei=0 nr=25
Aug 31 17:52:26: ISDN Se0/1/0:23 Q921: User RX <- RRf sapi=0 tei=0 nr=29
Aug 31 17:52:38: ISDN Se0/1/1:23 Q921: User TX -> RRp sapi=0 tei=0 nr=27
Aug 31 17:52:38: ISDN Se0/1/1:23 Q921: User RX <- RRf sapi=0 tei=0 nr=24
Aug 31 17:52:40: ISDN Se0/1/0:23 Q921: User RX <- INFO sapi=0 tei=0, ns=25 nr=29
Aug 31 17:52:40: ISDN Se0/1/0:23 Q921: User TX -> RR sapi=0 tei=0 nr=26
Aug 31 17:52:40: ISDN Se0/1/0:23 **ERROR**: L3_GetUser_NLCB: SETUP on not currently active DSL.
Aug 31 17:52:40: ISDN Se0/1/0:23 **ERROR**: L3_GetUser_NLCB: is_nfas_not_active()

debug isdn q931
Aug 31 17:54:46: ISDN Se0/1/0:23 Q931: RX <- SETUP pd = 8 callref = 0x03D4
Bearer Capability i = 0x9090A2
Standard = CCITT
Transfer Capability = 3.1kHz Audio
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA98381
Exclusive, Channel 1
Facility i = 0x9F8B0100A10F02013D06072A8648CE1500040A0100
Progress Ind i = 0x8281 - Call not end-to-end ISDN, may have in-band info
Calling Party Number i = 0x2183, 'xxxxx'
Plan:ISDN, Type:National
Called Party Number i = 0xA1, 'zzzzzz'
Plan:ISDN, Type:National
Aug 31 17:54:46: ISDN Se0/1/0:23 **ERROR**: L3_GetUser_NLCB: SETUP on not currently active DSL.
Aug 31 17:54:46: ISDN Se0/1/0:23 **ERROR**: L3_GetUser_NLCB: is_nfas_not_active()
Aug 31 17:54:46: ISDN Se0/1/0:23 Q931: RX <- FACILITY pd = 8 callref = 0x03D4
Facility i = 0x9F8B0100A11702013E020100800F494D53204845414C54482020202020
Aug 31 17:54:50: ISDN Se0/1/0:23 Q931: RX <- SETUP pd = 8 callref = 0x03D4
Bearer Capability i = 0x9090A2
Standard = CCITT
Transfer Capability = 3.1kHz Audio
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA98381
Exclusive, Channel 1
Facility i = 0x9F8B0100A10F02013D06072A8648CE1500040A0100
Progress Ind i = 0x8281 - Call not end-to-end ISDN, may have in-band info
Calling Party Number i = 0x2183, 'xxxxxx'
Plan:ISDN, Type:National
Called Party Number i = 0xA1, 'zzzzzz'
Plan:ISDN, Type:National
Aug 31 17:54:50: ISDN Se0/1/0:23 **ERROR**: L3_GetUser_NLCB: SETUP on not currently active DSL.
Aug 31 17:54:50: ISDN Se0/1/0:23 **ERROR**: L3_GetUser_NLCB: is_nfas_not_active()
Aug 31 17:54:54: ISDN Se0/1/0:23 Q931: RX <- RELEASE_COMP pd = 8 callref = 0x03D4

[chrihussey · Accepted solution]

I could be wrong but from what I can see you have two way communication with the provider but the NFAS piece is not operational. Suggest removing the NFAS part and just do plain ISDN with the last channel on each PRI used for signaling to see if that fixes the problem. You can deal with the provider if that is the fix.

There is also a slight clocking issue with T1 0/1/0, but that is obviously your secondary concern.

Hope this helps and works.

[abraham23482]

Thank you very much for that input. I removed NFAS and configured the PRI with last channel for signalling and inbound is working fine now. One wierd thing is that if i do a show isdn service, the output shows that the D channel is still out of service and when i make an inbound call it rings but i see the below error on console.

Aug 31 20:57:19: ISDN Se0/1/0:23 Q931: RX <- SETUP pd = 8 callref = 0x0833
Bearer Capability i = 0x9090A2
Standard = CCITT
Transfer Capability = 3.1kHz Audio
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA98381
Exclusive, Channel 1
Facility i = 0x9F8B0100A10F02011906072A8648CE1500040A0100
Progress Ind i = 0x8281 - Call not end-to-end ISDN, may have in-band info
Calling Party Number i = 0x2183, '6104273405'
Plan:ISDN, Type:National
Called Party Number i = 0xA1, '4804440621'
Plan:ISDN, Type:National
Aug 31 20:57:19: ISDN Se0/1/0:23 Q931: Received SETUP callref = 0x8833 callID = 0x0019 switch = primary-4ess interface = User
Aug 31 20:57:19: ISDN Se0/1/0:23 Q931: TX -> CALL_PROC pd = 8 callref = 0x8833
Channel ID i = 0xA98381
Exclusive, Channel 1
Aug 31 20:57:19: ISDN Se0/1/0:23 Q931: TX -> ALERTING pd = 8 callref = 0x8833
Aug 31 20:57:20: ISDN Se0/1/0:23 Q931: RX <- FACILITY pd = 8 callref = 0x0833
Facility i = 0x9F8B0100A11702011A020100800F494D53204845414C54482020202020
Aug 31 20:57:20: ISDN Se0/1/0:23 **ERROR**: Ux_BadMsg: Invalid Message for call state 7, call id 0x19, call ref 0x8833, event 0x62
Aug 31 20:57:20: ISDN Se0/1/0:23 Q931: TX -> STATUS pd = 8 callref = 0x8833
Cause i = 0x80E262 - Message not compatible with call state or not implemented
Call State i = 0x07
Aug 31 20:57:20: ISDN Se0/1/0:23 Q931: RX <- STATUS pd = 8 callref = 0x0833
Cause i = 0x80E408 - Invalid information element contents
Call State i = 0x07
Aug 31 20:57:26: ISDN Se0/1/0:23 Q931: RX <- DISCONNECT pd = 8 callref = 0x0833

[chrihussey]

Very glad I was able to help and thanks for the votes.

I run my PRIs in normal mode without the NFAS and that is the normal state for the D channel:

XXXX-r1#sh isdn service
PRI Channel Statistics:
ISDN Se0/0/0:23, Channel [1-24]
  Configured Isdn Interface (dsl) 0
   Channel State (0=Idle 1=Proposed 2=Busy 3=Reserved 4=Restart 5=Maint_Pend)
    Channel :  1 2 3 4 5 6 7 8 9 0 1 2 3 4 5 6 7 8 9 0 1 2 3 4
    State   :  0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 3
   Service State (0=Inservice 1=Maint 2=Outofservice 8=MaintPend 9=OOSPend)
    Channel :  1 2 3 4 5 6 7 8 9 0 1 2 3 4 5 6 7 8 9 0 1 2 3 4
    State   :  0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2

If you've done something to explicitly define the last channel for signaling that may be the issue. Here's what my configs look like:

!
interface Serial0/0/0:23
 no ip address
 encapsulation hdlc
 isdn switch-type primary-ni
 isdn incoming-voice voice
 isdn map address 011............ plan unknown type unknown
 isdn send-alerting
 isdn sending-complete
 no cdp enable

!

I've also found that error messages are not uncommon on some debugs, just got to know if it is anything to worry about. If things are working you may be OK. If your concerned a TAC case may be in order to get into the weeds.

Regards

[abraham23482]

Thank you once again. Big help !