[cisco-nas] isdn - Incoming call rejected, unbindable

jc jc at isnet.net
Tue Dec 4 08:19:56 EST 2007


strange problem that suddenly appeared..isdn calls seems to be failing to
AS5300 running 123-24..

it seems to be unable to bind the b channels...

000700: Dec  4 15:15:16.365 SAT: ISDN Se3:15 Q931: RX <- SETUP pd = 8  callref = 0x2E06
        Sending Complete
        Bearer Capability i = 0x8890
                Standard = CCITT
                Transfer Capability = Unrestricted Digital
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0xA98382
                Exclusive, Channel 2
        Calling Party Number i = 0x0083, '01'
                Plan:Unknown, Type:Unknown
        Called Party Number i = 0x81, '28'
                Plan:ISDN, Type:Unknown
000701: Dec  4 15:15:16.373 SAT: ISDN Se3:1: Incoming call rejected, unbindable
000702: Dec  4 15:15:16.373 SAT: ISDN Se3:15 **ERROR**: call_incoming: DIALER ERROR 0x1: b channel 1 call id 0x86
000703: Dec  4 15:15:16.377 SAT: ISDN Se3:15 Q931: TX -> RELEASE_COMP pd = 8  callref = 0xAE06
        Cause i = 0x80AC - Requested circuit/channel not available

earlier debugs...

064588: Dec  4 13:43:51.485 SAT: ISDN Se1:15 Q921f: User TX -> 0x020101B6^M
064589: Dec  4 13:43:51.485 SAT: ISDN Se1:15 Q921: User TX -> RR sapi=0 tei=0 nr=91^M
064590: Dec  4 13:43:51.485 SAT: ISDN  Q921d: isdn_l2d_srq_process: event_count 1^M
064591: Dec  4 13:43:51.485 SAT: ISDN Se1:15 Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0^M
064592: Dec  4 13:43:51.485 SAT: ISDN Se1:15 Q931d: L3_Go: call_id 0x9F5 cr 0xAF1A state 0 event 0x5 ces 1^M
064593: Dec  4 13:43:51.485 SAT: ISDN Se1:15 Q931d: L3_ProcessEvent: callref = 0xAF1A SETUP:U0_Setup(nlcb)^M
064594: Dec  4 13:43:51.489 SAT: ISDN Se1:15 Q931d: L3_state_change: callref 0xAF1A old NULL_STATE, new CALL_PRESENT^M
064595: Dec  4 13:43:51.489 SAT: ISDN Se1:15 CC: CCPRI_Go: source id 0x300, call id 0x9F5, event 0x341 (pre-ccb recovery)^M
064596: Dec  4 13:43:51.489 SAT: ISDN Se1:15 CC: CCPRI_Go: call id 0x1 cref 0x9F5 event 0xAF1A Source->L3^M
064597: Dec  4 13:43:51.489 SAT: ISDN Se1:15 CC: CCPCC_CallIdle: event 0x90 b channel 0 nfas int_id 0 call_id 0x9F5^M
064598: Dec  4 13:43:51.489 SAT: ISDN Se1:15 CC: CCPRI_AcceptChanId: Negotiated int_id 0 bchan 0 cref 0xAF1A call_id 0x09F5 lo_chan 10 final int_id/bchan 0/10 cause 0^M
064599: Dec  4 13:43:51.489 SAT: ISDN Se1:15 TGRM: get_tgrm_avail_state: idb 0x62CE09E8 bchan 10 service_state 0 call_state 2 false busy 0x0 dsl 1^M
064600: Dec  4 13:43:51.489 SAT: ISDN Se1:15 TGRM: update_tgrm_call_status: idb 0x62CE09E8 bchan 10 availability state 1 call state(prev,new) (0,2), dsl 1^M
064601: Dec  4 13:43:51.489 SAT: ISDN Se1:15 TGRM: update_tgrm_call_status: Calling TGRM with tgrm_call_isdn_update: idb 0x62CE09E8 bchan 10 call state 1 call type 1 call dir 1^M
064602: Dec  4 13:43:51.489 SAT: ISDN Se1:15 EVENT: process_rxstate: ces/callid 1/0x9F5 calltype 1 CALL_INCOMING^M
064603: Dec  4 13:43:51.493 SAT: ISDN Se1:15 PACKET: process_rxstate:  0x4C010109F5160403080010180421030A^M
064604: Dec  4 13:43:51.493 SAT:        007009000132383331303030A1040288^M
064605: Dec  4 13:43:51.493 SAT:        901803A9838A6C0C0081303133323436^M
064606: Dec  4 13:43:51.493 SAT:        3236343970088132383331303030^M
064607: Dec  4 13:43:51.493 SAT: ISDN Se1:15 EVENTd: calltrkr_incoming_call: call_id=0x9F5^M
064608: Dec  4 13:43:51.493 SAT: ISDN Se1:15 EVENTd: calltrkr_setup_received: isdn_info=1657704592l, call_id=0x9F5 ANSWER^M
064609: Dec  4 13:43:51.493 SAT: ISDN Se1:15 EVENTd: calltrkr_setup_received: isdn_info=1657491880l, call_id=0x9F5 ANSWER^M
064610: Dec  4 13:43:51.493 SAT: ISDN Se1:15 EVENTd: call_incoming: b channel 9, call type is DATA ^M
064611: Dec  4 13:43:51.493 SAT: ISDN Se1:15 EVENTd: call_incoming: Received a DATA call from 0132462649 on b channel 9 at 64 Kb/s^M
064612: Dec  4 13:43:51.497 SAT: ISDN Se1:9: Incoming call rejected, unbindable^M
064613: Dec  4 13:43:51.497 SAT: ISDN Se1:15 CC: process_rejectcall: call_id 0x09F5, cause 0x2C, call_type 0x1^M
064614: Dec  4 13:43:51.497 SAT: ISDN Se1:15 EVENTd: calltrkr_call_cleared: isdn_info=0x62CB51A8, call_id=0x9F5^M
064615: Dec  4 13:43:51.497 SAT: ISDN Se1:15 EVENTd: calltrkr_call_cleared: hndl=839l, cause=44, Requested circuit/channel not available^M
064616: Dec  4 13:43:51.497 SAT: ISDN Se1:15 EVENTd: calltrkr_call_cleared: isdn_info=0x62CE9090, call_id=0x9F5^M
064617: Dec  4 13:43:51.497 SAT: ISDN Se1:15 **ERROR**: call_incoming: DIALER ERROR 0x1: b channel 9 call id 0x9F5^M
064618: Dec  4 13:43:51.497 SAT: ISDN Se0:15 CC: CCPRI_Go: source id 0x500, call id 0x0, event 0x4F (pre-ccb recovery)^M
064619: Dec  4 13:43:51.497 SAT: ISDN Se1:15 CC: CCPRI_Go: call_id 0x9F5 cref 0xAF1A event 0x4F Source->HOST^M
064620: Dec  4 13:43:51.497 SAT: ISDN Se1:15 CC: CCPCC_CallOffered: event = 0x4F b channel 10 nfas int_id 0 call_id 0x9F5^M
064621: Dec  4 13:43:51.501 SAT: ISDN Se1:15 Q931d: CC_CHAN_GetUserpri: using call_id = 0x9F5^M
064622: Dec  4 13:43:51.501 SAT: ISDN Se1:15 CCd: CC_CHAN_ReleaseChanpri: channel 10^M

and there is def not a resource shortage...

janitor#sh isdn service
PRI Channel Statistics:
ISDN Se0:15, Channel [1-31]
  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 5 6 7 8 9 0 1
    State   :  0 2 2 2 2 0 0 0 0 0 0 0 0 0 0 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
   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 5 6 7 8 9 0 1
    State   :  0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
ISDN Se1:15, Channel [1-31]
  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 5 6 7 8 9 0 1
    State   :  0 2 2 0 0 2 2 0 0 2 0 0 2 2 2 3 0 2 2 0 2 2 2 2 0 0 2 0 2 0 0
   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 5 6 7 8 9 0 1
    State   :  0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
ISDN Se2:15, Channel [1-31]
  Configured Isdn Interface (dsl) 2
   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 5 6 7 8 9 0 1
    State   :  2 0 2 0 2 0 0 0 0 0 0 0 2 2 0 3 0 0 2 2 0 2 2 2 0 0 2 0 0 0 0
   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 5 6 7 8 9 0 1
    State   :  0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
ISDN Se3:15, Channel [1-31]
  Configured Isdn Interface (dsl) 3
   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 5 6 7 8 9 0 1
    State   :  0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
   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 5 6 7 8 9 0 1
    State   :  0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0



E1 0 is up.
  Applique type is Channelized E1 - balanced
  Description: 01
  No alarms detected.
  alarm-trigger is not set
  Version info of slot 0:  HW: 1, PLD Rev: 11
  Framer Version: 0x8

Manufacture Cookie Info:
 EEPROM Type 0x0001, EEPROM Version 0x01, Board ID 0x4B,
 Board Hardware Version 1.0, Item Number 800-3881-01,
 Board Revision A0, Serial Number 11768222,
 PLD/ISP Version 0.1,  Manufacture Date 25-Feb-1999.

  Framing is CRC4, Line Code is HDB3, Clock Source is Line Primary.
  Data in current interval (876 seconds elapsed):
     0 Line Code Violations, 0 Path Code Violations
     0 Slip Secs, 0 Fr Loss Secs, 0 Line Err Secs, 0 Degraded Mins
     0 Errored Secs, 0 Bursty Err Secs, 0 Severely Err Secs, 0 Unavail Secs
  Data in Interval 1:
     5 Line Code Violations, 2 Path Code Violations
     0 Slip Secs, 0 Fr Loss Secs, 1 Line Err Secs, 0 Degraded Mins
     1 Errored Secs, 0 Bursty Err Secs, 0 Severely Err Secs, 0 Unavail Secs
  Total Data (last 1 15 minute intervals):
     5 Line Code Violations, 2 Path Code Violations,
     0 Slip Secs, 0 Fr Loss Secs, 1 Line Err Secs, 0 Degraded Mins,
     1 Errored Secs, 0 Bursty Err Secs, 0 Severely Err Secs, 0 Unavail Secs



bits of my cfg

--
!
interface Serial0:15
 no ip address
 encapsulation ppp
 no logging event link-status
 dialer pool-member 10
 no snmp trap link-status
 isdn switch-type primary-net5
 isdn incoming-voice modem
 isdn guard-timer 3000 on-expiry accept
 isdn disconnect-cause not-available
 no keepalive
 no cdp enable
 ppp authentication pap chap callin
 ppp multilink
!
!
interface Group-Async1
 ip unnumbered Loopback0
 no ip proxy-arp
 encapsulation ppp
 autodetect encapsulation ppp
 async mode interactive
 no snmp trap link-status
 peer default ip address pool janitor
 ppp max-bad-auth 3
 ppp authentication pap chap callin
 ppp multilink
 group-range 61 120
!
!
interface Dialer10
 ip unnumbered Loopback0
 encapsulation ppp
 dialer in-band
 dialer-group 1
 no snmp trap link-status
 peer default ip address pool janitor
 no cdp enable
 ppp authentication pap chap callin
 ppp multilink
!
!
line 1 120
 privilege level 0
 no flush-at-activation
 modem InOut
 modem autoconfigure type v92
 autocommand  ppp
 transport input all
 transport output pad telnet rlogin udptn
 autoselect during-login
 autoselect ppp

!


any suggestions ... must be the silly season bug or something :)

j.



More information about the cisco-nas mailing list