Cisco Support Community
cancel
Showing results for 
Search instead for 
Did you mean: 
Announcements

Welcome to Cisco Support Community. We would love to have your feedback.

For an introduction to the new site, click here. And see here for current known issues.

New Member

c3725 and Pri and Anlog call rejection

cisc3725 running 12.2(13)T5 is working on but then it starts rejecting the Analog calls and only way to reover is to reboot. I have copied the debugs below from which it looks like it is not able to assign a modem to the incoming call. is it an IOS issue?

- show modem version ------------------

Slot 2:MICA-6DM Firmware, Source - IOS

CP ver 2730 - 5/23/2001, CheckSum BCCEB316.

SP ver 2730 - 5/23/2001.

MICA 0: HW Version 1.0, Serial Number 30171028.

MICA 1: HW Version 1.0, Serial Number 29780305.

MICA 2: HW Version 1.0, Serial Number 30170979.

MICA 3: HW Version 1.0, Serial Number 30170995.

MICA 4: HW Version 1.0, Serial Number 29219496.

.

deras1#term monitor

% Console already monitors

deras1#sh modem

Codes:

* - Modem has an active call

R - Modem is being Reset

D - Download in progress

B - Modem is marked bad and cannot be used for taking calls

b - Modem is either busied out or shut-down

Avg Hold Inc calls Out calls *2/180 0 0 93%

deras1#

deras1#debug modem csm

Modem Management Call Switching Module debugging is on

deras1#

Aug 4 12:29:36 MESZ: CSM: MODEM_REPORT from 1/0:7, call_id=0xA6, event=0x1, cause=0x0,

dchan_idb=0x61954690

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/0; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/0 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/1; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/1 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/2; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/2 CSM: TTY statbits = 80010020

statbits = 80010020

= 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/12; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/12 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/9; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/9 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/23; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/23 CSM: TTY statbits = 80010020

statbits = 80010020

AugAug 4 12:29:36 MESZ: Modem 2/8 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/10; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/10 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/27; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/27 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/19; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/19 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/11; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/11 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/13; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/13 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/29; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/29 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/20; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/20 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/24; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/24 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/26; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/26 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM: Next free modem = 2/7; statbits = 80010020

Aug 4 12:29:36 MESZ: Modem 2/7 CSM: TTY statbits = 80010020

Aug 4 12:29:36 MESZ: CSM incall failed with dchan index=1, idb state=4, sb=0

Aug 4 12:29:36 MESZ: CSM: MODEM_REPORT rcvd DEV_INCALL for call_id 0xA6, No modem

available

Aug 4 12:29:36 MESZ: CSM: MODEM_REPORT from 1/0:7, call_id=0xA6, event=0x0, cause=0x0,

dchan_idb=0x61954690

Aug 4 12:29:36 MESZ: CSM: MODEM_REPORT rcvd DEV_IDLE for call_id 0xA6, modem not found due

to wrong b channel number or b channel is deallocated

Aug 4 12:29:36 MESZ: CSM: MODEM_REPORT from 1/0:7, call_id=0xA6, event=0x0, cause=0x11,

dchan_idb=0x61954690

Aug 4 12:29:36 MESZ: CSM: MODEM_REPORT rcvd DEV_IDLE for call_id 0xA6, modem not found due

to wrong b channel number or b channel is deallocated

Aug 4 12:29:41 MESZ: CSM: MODEM_REPORT from 1/0:7, call_id=0xA7, event=0x1, cause=0x0,

dchan_idb=0x61954690

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/0; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/0 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/1; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/1 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/2; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/2 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/6; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/6 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/5; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/5 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/14; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/14 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/15; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/15 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/17; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/17 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/28; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/28 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/4; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/4 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/12; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/12 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/9; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/9 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/23; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/23 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/25; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/25 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/21; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/21 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/22; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/22 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/16; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/16 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/8; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/8 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/10; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/10 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/27; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/27 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/19; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/19 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/11; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/11 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/13; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/13 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/29; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/29 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/20; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/20 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/24; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/24 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/26; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/26 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM: Next free modem = 2/7; statbits = 80010020

Aug 4 12:29:41 MESZ: Modem 2/7 CSM: TTY statbits = 80010020

Aug 4 12:29:41 MESZ: CSM incall failed with dchan index=1, idb state=4, sb=0

Aug 4 12:29:41 MESZ: CSM: MODEM_REPORT rcvd DEV_INCALL for call_id 0xA7, No modem

available

Aug 4 12:29:41 MESZ: CSM: MODEM_REPORT from 1/0:7, call_id=0xA7, event=0x0, cause=0x0,

dchan_idb=0x61954690

Aug 4 12:29:41 MESZ: CSM: MODEM_REPORT rcvd DEV_IDLE for call_id 0xA7, modem not found due

to wrong b channel number or b channel is deallocated

Aug 4 12:29:41 MESZ: CSM: MODEM_REPORT from 1/0:7, call_id=0xA7, event=0x0, cause=0x11,

dchan_idb=0x61954690

Aug 4 12:29:41 MESZ: CSM: MODEM_REPORT rcvd DEV_IDLE for call_id 0xA7, modem not found due

to wrong b channel number or b channel is deallocated

deras1#

deras1#no debug modem csm

Modem Management Call Switching Module debugging is off

4 12:31:29 MESZ: ISDN Se1/0:15 **ERROR**: accept_incoming_csm_call: modem problem

Requested circuit/channel not available(0x2C): b channel 4, call id 0xAB

4 REPLIES
Silver

Re: c3725 and Pri and Anlog call rejection

I did not come across an exact match in a bug search, can you try upgrading the MICA portware to say 2.9.4.0. Maybe instead of rebooting the router just try reflashing the modem portware & see if it works.

What does the output of "sh modem" & "sh isdn service" indicate. Are we configured for NFAS?

Thanks, Mak.

New Member

Re: c3725 and Pri and Anlog call rejection

hi Mak

We have tried using the MICA portware 2.9.4.0.

but the still had the same problem. Following is the outputs from 'sh ISDN status' and 'sh iSDN service'

Could it be a Hardware issue? Circuit has been checked and beng reported fine

sh isdn servis ice

PRI Channel Statistics:

ISDN Se1/0: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 : 2 2 2 2 2 2 0 2 2 2 2 2 2 2 2 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0

Service State (0=Inservice 1=Maint 2=Outofservice)

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

deras1#sh isdn status

Global ISDN Switchtype = primary-net5

ISDN Serial1/0:15 interface

******* Network side configuration *******

dsl 0, interface ISDN Switchtype = primary-net5

Layer 1 Status:

ACTIVE

Layer 2 Status:

TEI = 0, Ces = 1, SAPI = 0, State = MULTIPLE_FRAME_ESTABLISHED

Layer 3 Status:

14 Active Layer 3 Call(s)

CCB:callid=50, sapi=0, ces=0, B-chan=8, calltype=DATA, hdlctype=HDLC-TRUNK

CCB:callid=51, sapi=0, ces=0, B-chan=9, calltype=DATA, hdlctype=HDLC-TRUNK

CCB:callid=53, sapi=0, ces=0, B-chan=3, calltype=DATA, hdlctype=HDLC-TRUNK

CCB:callid=54, sapi=0, ces=0, B-chan=4, calltype=DATA, hdlctype=HDLC-TRUNK

CCB:callid=56, sapi=0, ces=0, B-chan=2, calltype=VOICE

CCB:callid=57, sapi=0, ces=0, B-chan=5, calltype=DATA, hdlctype=HDLC-TRUNK

CCB:callid=58, sapi=0, ces=0, B-chan=10, calltype=DATA, hdlctype=HDLC-TRUNK

CCB:callid=5C, sapi=0, ces=0, B-chan=12, calltype=VOICE

CCB:callid=5E, sapi=0, ces=0, B-chan=13, calltype=VOICE

CCB:callid=66, sapi=0, ces=0, B-chan=1, calltype=VOICE

CCB:callid=81, sapi=0, ces=0, B-chan=11, calltype=DATA, hdlctype=HDLC-TRUNK

CCB:callid=83, sapi=0, ces=0, B-chan=6, calltype=VOICE

CCB:callid=84, sapi=0, ces=0, B-chan=14, calltype=DATA, hdlctype=HDLC-TRUNK

CCB:callid=85, sapi=0, ces=0, B-chan=15, calltype=DATA, hdlctype=HDLC-TRUNK

Active dsl 0 CCBs = 14

The Free Channel Mask: 0xFFFF0040

Number of L2 Discards = 0, L2 Session ID = 0

Total Allocated ISDN CCBs = 14

New Member

Re: c3725 and Pri and Anlog call rejection

sh modem shows following

sh modem

Codes:

* - Modem has an active call

R - Modem is being Reset

D - Download in progress

B - Modem is marked bad and cannot be used for taking calls

b - Modem is either busied out or shut-down

Avg Hold Inc calls Out calls Busied Failed No Succ

Mdm Time Succ Fail Succ Fail Out Dial Answer Pct.

2/0 00:00:52 1 0 0 0 0 0 0 100%

2/1 00:04:02 1 0 0 0 0 0 0 100%

2/2 00:02:57 1 0 0 0 0 0 0 100%

* 2/3 00:25:52 5 1 0 0 0 0 0 83%

2/4 00:10:20 2 0 0 0 0 0 0 100%

2/5 00:35:19 1 0 0 0 0 0 0 100%

2/6 00:16:27 1 0 0 0 0 0 0 100%

2/7 00:28:33 5 0 0 0 0 0 0 100%

2/8 00:08:35 4 0 0 0 0 0 0 100%

2/9 00:47:47 2 0 0 0 0 0 0 100%

2/10 00:22:22 3 0 0 0 0 0 0 100%

2/11 00:08:08 4 1 0 0 0 0 0 80%

2/12 08:23:45 1 0 0 0 0 0 0 100%

2/13 00:11:14 6 0 0 0 0 0 0 100%

2/14 00:01:06 1 0 0 0 0 0 0 100%

2/15 00:52:09 1 0 0 0 0 0 0 100%

2/16 00:57:05 2 0 0 0 0 0 0 100%

* 2/18 01:14:26 3 0 0 0 0 0 0 100%

2/19 00:08:46 5 0 0 0 0 0 0 100%

2/20 00:06:48 7 0 0 0 0 0 0 100%

2/21 00:07:59 2 0 0 0 0 0 0 100%

2/22 00:00:37 2 1 0 0 0 0 0 67%

2/23 00:00:30 2 0 0 0 0 0 0 100%

2/24 00:10:13 6 1 0 0 0 0 0 86%

2/25 00:03:21 2 0 0 0 0 0 0 100%

2/26 00:20:12 4 1 0 0 0 0 0 80%

2/27 01:46:03 1 0 0 0 0 0 0 100%

2/28 00:00:30 1 0 0 0 0 0 0 100%

2/29 02:37:49 1 0 0 0 0 0 0 100%

Total: 00:26:20 78 5 0 0 0 0 0 93%

New Member

Re: c3725 and Pri and Anlog call rejection

Hello,

I have the same error with a 3725 and nm12d on Version 12.2(15)T7 with MICA-6DM Firmware: CP ver 2940.

have you found any issue ?

Arnaud Lemaire

Arche Omnetica group

arnaud.lemaire@arche.fr

*Mar 1 00:04:46.947: CSM incall failed with dchan index=1, idb state=4, sb=0

*Mar 1 00:04:46.947: CSM: MODEM_REPORT rcvd DEV_INCALL for call_id 0x2, No mode

m available

*Mar 1 00:04:46.947: ISDN Se1/0:15 **ERROR**: accept_incoming_csm_call: modem p

roblem Requested circuit/channel not available(0x2C): b channel 6, call id 0x2

*Mar 1 00:04:46.951: CSM: MODEM_REPORT from 1/0:6, call_id=0x2, event=0x0, caus

e=0x0, dchan_idb=0x61B75D10

*Mar 1 00:04:46.951: CSM: MODEM_REPORT rcvd DEV_IDLE for call_id 0x2, modem not

found due to wrong b channel number or b channel is deallocated

*Mar 1 00:04:46.951: ISDN Se1/0:15 Q921: User TX -> INFO sapi=0 tei=0, ns=3 nr=

3

*Mar 1 00:04:46.951: ISDN Se1/0:15 Q931: CALL_PROC pd = 8 callref = 0x80EC

Channel ID i = 0xA98387

Exclusive, Channel 7

*Mar 1 00:04:46.951: ISDN Se1/0:15 Q921: User TX -> INFO sapi=0 tei=0, ns=4 nr=

3

*Mar 1 00:04:46.951: ISDN Se1/0:15 Q931: DISCONNECT pd = 8 callref = 0x80EC

Cause i = 0x80AC - Requested circuit/channel not available

*Mar 1 00:04:46.975: ISDN Se1/0:15 Q921: User RX <- RR sapi=0 tei=0 nr=4

*Mar 1 00:04:46.987: ISDN Se1/0:15 Q921: User RX <- RR sapi=0 tei=0 nr=5

*Mar 1 00:04:47.259: ISDN Se1/0:15 Q921: User RX <- INFO sapi=0 tei=0, ns=3 nr=

5

*Mar 1 00:04:47.259: ISDN Se1/0:15 Q931: RELEASE pd = 8 callref = 0x00EC

*Mar 1 00:04:47.259: ISDN Se1/0:15 Q921: User TX -> RR sapi=0 tei=0 nr=4

*Mar 1 00:04:47.263: CSM: MODEM_REPORT from 1/0:6, call_id=0x2, event=0x0, caus

e=0x0, dchan_idb=0x61B75D10

*Mar 1 00:04:47.263: CSM: MODEM_REPORT rcvd DEV_IDLE for call_id 0x2, modem not

found due to wrong b channel number or b channel is deallocated

*Mar 1 00:04:47.263: ISDN Se1/0:15 Q921: User TX -> INFO sapi=0 tei=0, ns=5 nr=

4

*Mar 1 00:04:47.263: ISDN Se1/0:15 Q931: RELEASE_COMP pd = 8 callref = 0x80EC

*Mar 1 00:04:47.287: ISDN Se1/0:15 Q921: User RX <- RR sapi=0 tei=0 nr=6

*Mar 1 00:04:57.287: ISDN Se1/0:15 Q921: User TX -> RRp sapi=0 tei=0 nr=4

*Mar 1 00:04:57.287: ISDN Se1/0:15 Q921: User RX <- RRp sapi=0 tei=0 nr=6

*Mar 1 00:04:57.287: ISDN Se1/0:15 Q921: User TX -> RRf sapi=0 tei=0 nr=4

*Mar 1 00:04:57.299: ISDN Se1/0:15 Q921: User RX <- RRf sapi=0 tei=0 nr=6

219
Views
0
Helpful
4
Replies