[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