MICA No Answer Rate:

dinekal · ‎04-02-2003

hello,

We've higher number of no answer from modem summary report in 144 MICA @ AS5800 access servers.

We found the following "debug csm modem" output

Apr 2 14:09:14.692: allocate slot 11 and port 18 is allocated

Apr 2 14:09:14.692: CSM v(11/18) c(E1 1/0/0:29): CSM_PROC_IDLE: ev_START_DIGIT_COLLECT.

Apr 2 14:09:25.420: CSM v(11/18) c(E1 1/0/0:29): CSM_PROC_IC1_COLLECT_ADDR_INFO: ev_MODEM_OFFHOOK.

Apr 2 14:09:25.504: CSM v(11/18) c(E1 1/0/0:29): CSM_PROC_IC1_COLLECT_ADDR_INFO: ev_DSX0_DISCONNECTED.

Apr 2 14:09:25.504: vdev_deallocate: slot 11 and port 18 is deallocated

for the unanswered call.

Any of you guys could figure out the reason, would be helpful.

Thanks

Dinesh

tepatel · ‎04-02-2003

We need to see the full debug with signalling and modem debug,

So turn on following debug (assuming the CAS signalling) to see the clear picture.

debug cas

debug modem

debug csm modem

Along with that also need to know what modem firmware you have on AS5800. So pl post "sh modem ver" as well.

makchitale · ‎04-02-2003

Which IOS image & MICA portware are you running...there are a few known issues on the same.

deb cas / deb modem / deb csm modem

sh ver / sh mod ver / sh modem summ

Thnaks, Mak.

dinekal · ‎04-02-2003

Hi,

Here the hw/sw info. goes ...

HW: AS5800/144 MICA Card

IOS:c5800-k8p4-mz.122-13.bin

MICA portware : 2.7.3.0

It was working for about 3 months without problem, but a day back the number unanswered rate exponentially increased.

I don't find " debug cas " in this IOS version can you pls. let me know the root cause. Not all calls dropped during the callsetup but the rate is higer.

Thanks,

Dinesh

*************************************************************************************

TR-AS01#sh ver

Cisco Internetwork Operating System Software

IOS (tm) 5800 Software (C5800-K8P4-M), Version 12.2(13), RELEASE SOFTWARE (fc1)

Compiled Tue 19-Nov-02 20:46 by pwade

Image text-base: 0x60008940, data-base: 0x61D68000

ROM: System Bootstrap, Version 11.1(13)CA, EARLY DEPLOYMENT RELEASE SOFTWARE (f

1)

BOOTLDR: 7200 Software (C7200-BOOT-M), Version 12.0(2)XE2, EARLY DEPLOYMENT REL

ASE SOFTWARE (fc1)

TR-AS01 uptime is 19 hours, 6 minutes

System returned to ROM by power-on

System restarted at 12:49:26 IST Wed Apr 2 2003

System image file is "slot0:c5800-k8p4-mz.122-13.bin"

cisco 7206 (NPE200) processor (revision B) with 114688K/16384K bytes of memory.

Processor board ID 16075687

R5000 CPU at 200Mhz, Implementation 35, Rev 2.1, 512KB L2 Cache

6 slot midplane, Version 1.3

Last reset from power-on

Channelized E1, Version 1.0.

X.25 software, Version 3.0.0.

Bridging software.

Primary Rate ISDN software, Version 1.1.

1 FastEthernet/IEEE 802.3 interface(s)

87 Serial network interface(s)

144 terminal line(s)

24 Channelized E1/PRI port(s)

1 FE interface(s) for dial shelf to router shelf interconnect

125K bytes of non-volatile configuration memory.

4096K bytes of packet SRAM memory.

16384K bytes of Flash PCMCIA card at slot 0 (Sector size 128K).

4096K bytes of Flash internal SIMM (Sector size 256K).

Configuration register is 0x102

TR-AS01#show flas

TR-AS01#show flash:

-#- ED ----type---- --crc--- -seek-- nlen -length- -----date/time------ name

1 .. image F818269E CC9B8C 24 13277964 Jan 13 2003 06:49:20 c5800-k

p4-mz.122-13.bin

3105908 bytes available (13278092 bytes used)

TR-AS01#sh modem ver

Modem Range Module Firmware Rev Upgrade

1/11/00 1/11/11 0 2.7.3.0 -

1/11/12 1/11/23 1 2.7.3.0 -

1/11/24 1/11/35 2 2.7.3.0 -

1/11/36 1/11/47 3 2.7.3.0 -

1/11/48 1/11/59 4 2.7.3.0 -

1/11/60 1/11/71 5 2.7.3.0 -

1/11/72 1/11/83 6 2.7.3.0 -

1/11/84 1/11/95 7 2.7.3.0 -

1/11/96 1/11/107 8 2.7.3.0 -

1/11/108 1/11/119 9 2.7.3.0 -

1/11/120 1/11/131 10 2.7.3.0 -

1/11/132 1/11/143 11 2.7.3.0 -

Modem board HW version info:

Modem Range: 1/11/00 1/11/11 Modem Module: 0

Manufacture Cookie Info:

EEPROM Type 0x0001, EEPROM Version 0x01, Board ID 0xDC,

Board Hardware Version 1.0, Item Number 73-2988-03,

Board Revision A48, Serial Number 16129927,

PLD/ISP Version , Manufacture Date 7-Dec-1999.

Modem Range: 1/11/12 1/11/23 Modem Module: 1

Manufacture Cookie Info:

EEPROM Type 0x0001, EEPROM Version 0x01, Board ID 0xDC,

Board Hardware Version 1.0, Item Number 73-2988-03,

Board Revision A48, Serial Number 16129727,

PLD/ISP Version , Manufacture Date 7-Dec-1999.

Modem Range: 1/11/24 1/11/35 Modem Module: 2

Manufacture Cookie Info:

EEPROM Type 0x0001, EEPROM Version 0x01, Board ID 0xDC,

Board Hardware Version 1.0, Item Number 73-2988-03,

Board Revision A48, Serial Number 16129797,

PLD/ISP Version , Manufacture Date 7-Dec-1999.

TR-AS01#sh modem su

TR-AS01#sh modem summary

Avg Hold Incoming calls Outgoing calls Busied Failed No Suc

Time Succ Fail Avail Succ Fail Avail Out Dial Ans Pct

00:14:04 985 339 130 0 0 130 0 1 234 74%

TR-AS01#

tepatel · ‎04-02-2003

Need to know the signalling type you have on those trunk lines. Initially i thought that you have CAS signalling. Anyway just get me the debug requested so that we can see how far the modems go during connection.

Now you can also run modem call-record terse on AS5800 to capture the call records forthose failed calls to figureout the reason for modem disconnection..Here is the link for that

http://www.cisco.com/warp/public/76/mica-states-drs.html

Above link explains how to configure that and what is the meaning of those fields.

dinekal · ‎04-02-2003

No doubt, its CAS signalling. But I don't find the command "debug cas", is there any other command as an substitute for this to track the cas events. The requested debug outputs (debug csm modem, debug modem ) have been attached at next post.

Irrespective of trunks the failures happen in all E1 ports, nothing in particular.

The configuration goes here

!

controller E1 1/0/3

framing NO-CRC4

ds0-group 0 timeslots 1-15,17-31 type r2-digital r2-compelled ani

cas-custom 0

invert-abcd 0 0 0 1

country india

debounce-time 20

release-guard-time 20

dnis-digits min 3 max 10

ani-digits min 3 max 10

description TAX LTG 1-26-2

!

Pls. refer my next post for debug outputs.

Thanks

dinekal · ‎04-02-2003

Debug Outputs:

TR-AS01#sh debugging

General OS:

Modem control/process activation debugging is on

CSM Modem:

Modem Management Call Switching Module debugging is on

TR-AS01#

Apr 3 10:19:27.980: allocate slot 11 and port 31 is allocated

TR-AS01#deb

TR-AS01#debug mo

Apr 3 10:19:33.760: allocate slot 11 and port 7 is allocated

Apr 3 10:19:33.760: CSM v(11/7) c(E1 1/0/3:22): CSM_PROC_IDLE: ev_START_DIGIT_COLLECT.

Apr 3 10:19:33.784: CSM v(11/7) c(E1 1/0/3:22): CSM_PROC_IC1_COLLECT_ADDR_INFO:

ev_MODEM_OFFHOOK.

Apr 3 10:19:34.080: CSM v(11/7) c(E1 1/0/3:22): CSM_PROC_IC1_COLLECT_ADDR_INFO:

ev_DSX0_DISCONNECTED.

Apr 3 10:19:34.080: vdev_deallocate: slot 11 and port 7 is deallocated

de

TR-AS01#

Apr 3 10:19:38.524: TTY1/11/29: DSR came up

Apr 3 10:19:38.524: tty1/11/29: Modem: IDLE->(unknown)

Apr 3 10:19:38.524: TTY1/11/29: EXEC creation

Apr 3 10:19:38.524: TTY1/11/29: set timer type 10, 30 seconds

Apr 3 10:19:38.524: TTY1: autoconfigure probe started

Apr 3 10:19:38.524: TTY1/11/29: Autoselect(2) sample 7E

Apr 3 10:19:38.524: TTY1/11/29: Autoselect(2) sample 7EFF

Apr 3 10:19:38.524: TTY1/11/29: Autoselect(2) sample 7EFF7D

Apr 3 10:19:38.524: TTY1/11/29: Autoselect(2) sample 7EFF7D23

Apr 3 10:19:38.528: TTY1/11/29 Autoselect cmd: ppp negotiate

Apr 3 10:19:38.528: TTY1/11/29: EXEC creation

Apr 3 10:19:38.528: TTY1/11/29: create timer type 1, 600 seconds

Apr 3 10:19:38.528: TTY1/11/29: destroy timer type 1

Apr 3 10:19:38.528: TTY1/11/29: no timer type 0 to destroy

Apr 3 10:19:38.528: TTY1/11/29: no timer type 2 to destroy

Apr 3 10:19:38.656: CSM v(11/31) c(E1 1/0/1:13): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_IC_ADDR_INFO_COLLECTED.

Apr 3 10:19:38.656: CSM v(11/31) c(E1 1/0/1:13): CSM_PROC_IC1_COLLECT_ADDR_INFO

: 172233.(calling 4312411942)

Apr 3 10:19:38.656: CSM v(11/31) c(E1 1/0/1:13): CSM_PROC_IC3_WAIT_FOR_RES_RESP

: ev_RESOURCE_OK.

Apr 3 10:19:39.052: CSM v(11/31) c(E1 1/0/1:13): CSM_PROC_IC6_WAIT_FOR_CONNECT:

ev_DSX0_CONNECTED.

Apr 3 10:19:41.532: TTY1/11/08: DSR came up

Apr 3 10:19:41.532: tty1/11/08: Modem: IDLE->(unknown)

Apr 3 10:19:41.532: TTY1/11/08: EXEC creation

Apr 3 10:19:41.532: TTY1/11/08: set timer type 10, 30 seconds

Apr 3 10:19:41.568: TTY1/11/08: Autoselect(2) sample 7E

Apr 3 10:19:41.568: TTY1/11/08: Autoselect(2) sample 7EFF

Apr 3 10:19:41.568: TTY1/11/08: Autoselect(2) sample 7EFF7D

Apr 3 10:19:41.568: TTY1/11/08: Autoselect(2) sample 7EFF7D23

Apr 3 10:19:41.568: TTY1/11/08 Autoselect cmd: ppp negotiate

Apr 3 10:19:41.572: TTY1/11/08: EXEC creation

Apr 3 10:19:41.572: TTY1/11/08: create timer type 1, 600 seconds

Apr 3 10:19:41.572: TTY1/11/08: destroy timer type 1

Apr 3 10:19:41.572: TTY1/11/08: no timer type 0 to destroy

Apr 3 10:19:41.572: TTY1/11/08: no timer type 2 to destroy

TR-AS01#sh modem su

TR-AS01#sh modem summary

Avg Hold Incoming calls Outgoing calls Busied Failed No Succ

Time Succ Fail Avail Succ Fail Avail Out Dial Ans Pct.

00:00:45 11 2 23 0 0 23 0 0 2 85%

TR-AS01#

Apr 3 10:19:47.536: TTY1/11/37: DSR came up

Apr 3 10:19:47.536: tty1/11/37: Modem: IDLE->(unknown)

Apr 3 10:19:47.536: TTY1/11/37: EXEC creation

Apr 3 10:19:47.536: TTY1/11/37: set timer type 10, 30 seconds

Apr 3 10:19:47.536: TTY1/11/37: Autoselect(2) sample 7E

Apr 3 10:19:47.536: TTY1/11/37: Autoselect(2) sample 7EFF

Apr 3 10:19:47.536: TTY1/11/37: Autoselect(2) sample 7EFF7D

Apr 3 10:19:47.536: TTY1/11/37: Autoselect(2) sample 7EFF7D23

Apr 3 10:19:47.536: TTY1/11/37 Autoselect cmd: ppp negotiate

Apr 3 10:19:47.540: TTY1/11/37: EXEC creation

Apr 3 10:19:47.540: TTY1/11/37: create timer type 1, 600 seconds

Apr 3 10:19:47.544: TTY1/11/37: destroy timer type 1

Apr 3 10:19:47.544: TTY1/11/37: no timer type 0 to destroy

Apr 3 10:19:47.544: TTY1/11/37: no timer type 2 to destroy

TR-AS01#

Apr 3 10:19:51.528: TTY1/11/30: Async Int reset: Dropping DTR

Apr 3 10:19:51.540: TTY1/11/01: DSR came up

Apr 3 10:19:51.540: tty1/11/01: Modem: IDLE->(unknown)

Apr 3 10:19:51.540: TTY1/11/30: DSR was dropped

Apr 3 10:19:51.540: tty1/11/30: Modem: READY->(unknown)

Apr 3 10:19:51.540: TTY1/11/01: EXEC creation

Apr 3 10:19:51.540: TTY1/11/01: set timer type 10, 30 seconds

Apr 3 10:19:51.540: TTY1/11/01: Autoselect(2) sample 7E

Apr 3 10:19:51.540: TTY1/11/01: Autoselect(2) sample 7EFF

Apr 3 10:19:51.540: TTY1/11/01: Autoselect(2) sample 7EFF7D

Apr 3 10:19:51.540: TTY1/11/01: Autoselect(2) sample 7EFF7D23

Apr 3 10:19:51.544: TTY1/11/01 Autoselect cmd: ppp negotiate

TR-AS01#

Apr 3 10:19:51.544: TTY1/11/01: EXEC creation

Apr 3 10:19:51.544: TTY1/11/01: create timer type 1, 600 seconds

Apr 3 10:19:51.544: TTY1/11/01: destroy timer type 1

Apr 3 10:19:51.544: TTY1/11/01: no timer type 0 to destroy

Apr 3 10:19:51.544: TTY1/11/01: no timer type 2 to destroy

Apr 3 10:19:51.564: CSM v(11/30) c(E1 1/0/1:6): CSM_PROC_IC7_OC6_CONNECTED: ev

_MODEM_ONHOOK.

Apr 3 10:19:51.564: vdev_deallocate: slot 11 and port 30 is deallocated

Apr 3 10:19:52.540: TTY1/11/30: dropping DTR, hanging up

Apr 3 10:19:52.540: tty1/11/30: Modem: HANGUP->(unknown)

Apr 3 10:19:52.540: TTY1/11/30: cleanup pending. Delaying DTR

Apr 3 10:19:52.540: TTY1/11/34: DSR came up

Apr 3 10:19:52.540: tty1/11/34: Modem: IDLE->(unknown)

Apr 3 10:19:52.540: TTY1/11/30: cleanup pending. Delaying DTR

Apr 3 10:19:52.548: TTY1/11/34: EXEC creation

Apr 3 10:19:52.548: TTY1/11/34: set timer type 10, 30 seconds

Apr 3 10:19:52.548: TTY1/11/34: Autoselect(2) sample 7E

Apr 3 10:19:52.548: TTY1/11/34: Autoselect(2) sample 7EFF

Apr 3 10:19:52.548: TTY1/11/34: Autoselect(2) sample 7EFF7D

Apr 3 10:19:52.548: TTY1/11/34: Autoselect(2) sample 7EFF7D23

Apr 3 10:19:52.548: TTY1/11/34 Autoselect cmd: ppp negotiate

Apr 3 10:19:52.548: TTY1/11/34: EXEC creation

Apr 3 10:19:52.552: TTY1/11/34: create timer type 1, 600 seconds

TR-AS01#

Apr 3 10:19:52.552: TTY1/11/34: destroy timer type 1

Apr 3 10:19:52.552: TTY1/11/34: no timer type 0 to destroy

Apr 3 10:19:52.552: TTY1/11/34: no timer type 2 to destroy

Apr 3 10:19:53.544: TTY1/11/30: cleanup pending. Delaying DTR

TR-AS01#

Apr 3 10:19:54.552: TTY1/11/30: cleanup pending. Delaying DTR

TR-AS01#

Apr 3 10:19:55.556: TTY1/11/30: cleanup pending. Delaying DTR

Apr 3 10:19:56.528: TTY1/11/30: no timer type 0 to destroy

Apr 3 10:19:56.528: TTY1/11/30: no timer type 1 to destroy

Apr 3 10:19:56.528: TTY1/11/30: no timer type 3 to destroy

Apr 3 10:19:56.528: TTY1/11/30: no timer type 4 to destroy

Apr 3 10:19:56.528: TTY1/11/30: no timer type 2 to destroy

Apr 3 10:19:56.528: Async1/11/30: allowing modem_process to continue hangup

Apr 3 10:19:56.556: TTY1/11/30: restoring DTR

Apr 3 10:19:56.556: TTY1/11/30: autoconfigure probe started

Apr 3 10:19:57.220: allocate slot 11 and port 19 is allocated

Apr 3 10:19:57.220: CSM v(11/19) c(E1 1/0/1:8): CSM_PROC_IDLE: ev_START_DIGIT_

COLLECT.

Apr 3 10:19:57.248: CSM v(11/19) c(E1 1/0/1:8): CSM_PROC_IC1_COLLECT_ADDR_INFO:

ev_MODEM_OFFHOOK.

dinekal · ‎04-02-2003

Debug Output 2:

Apr 3 10:20:05.560: CSM v(11/19) c(E1 1/0/1:8): CSM_PROC_IC3_WAIT_FOR_RES_RESP:

ev_RESOURCE_OK.

Apr 3 10:20:05.792: allocate slot 11 and port 14 is allocated

Apr 3 10:20:05.792: CSM v(11/14) c(E1 1/0/0:21): CSM_PROC_IDLE: ev_START_DIGIT

_COLLECT.

Apr 3 10:20:05.820: CSM v(11/14) c(E1 1/0/0:21): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_MODEM_OFFHOOK.

Apr 3 10:20:05.960: CSM v(11/19) c(E1 1/0/1:8): CSM_PROC_IC6_WAIT_FOR_CONNECT:

ev_DSX0_CONNECTED.

Apr 3 10:20:06.548: TTY2: EXEC creation

Apr 3 10:20:06.548: TTY2: set timer type 10, 30 seconds

Apr 3 10:20:06.568: TTY1/11/13: DSR came up

Apr 3 10:20:06.568: tty1/11/13: Modem: IDLE->(unknown)

Apr 3 10:20:06.572: TTY1/11/13: EXEC creation

Apr 3 10:20:06.572: TTY1/11/13: set timer type 10, 30 seconds

Apr 3 10:20:06.572: TTY1/11/13: Autoselect(2) sample 7E

Apr 3 10:20:06.576: TTY1/11/13: Autoselect(2) sample 7EFF

Apr 3 10:20:06.576: TTY1/11/13: Autoselect(2) sample 7EFF7D

Apr 3 10:20:06.576: TTY1/11/13: Autoselect(2) sample 7EFF7D23

Apr 3 10:20:06.576: TTY1/11/13 Autoselect cmd: ppp negotiate

Apr 3 10:20:06.576: TTY1/11/13: EXEC creation

Apr 3 10:20:06.576: TTY1/11/13: create timer type 1, 600 seconds

Apr 3 10:20:06.580: TTY1/11/13: destroy timer type 1

Apr 3 10:20:06.580: TTY1/11/13: no timer type 0 to destroy

Apr 3 10:20:06.580: TTY1/11/13: no timer type 2 to destroy

Apr 3 10:20:06.732: allocate slot 11 and port 12 is allocated

Apr 3 10:20:06.732: CSM v(11/12) c(E1 1/0/1:25): CSM_PROC_IDLE: ev_START_DIGIT

_COLLECT.

Apr 3 10:20:06.752: CSM v(11/12) c(E1 1/0/1:25): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_MODEM_OFFHOOK.

Apr 3 10:20:07.572: allocate slot 11 and port 35 is allocated

Apr 3 10:20:07.572: CSM v(11/35) c(E1 1/0/1:17): CSM_PROC_IDLE: ev_START_DIGIT

_COLLECT.

Apr 3 10:20:07.588: CSM v(11/35) c(E1 1/0/1:17): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_MODEM_OFFHOOK.

Apr 3 10:20:08.556: TTY2: set timer type 10, 30 seconds

Apr 3 10:20:11.180: allocate slot 11 and port 24 is allocated

Apr 3 10:20:11.180: CSM v(11/24) c(E1 1/0/1:21): CSM_PROC_IDLE: ev_START_DIGIT

_COLLECT.

Apr 3 10:20:11.204: CSM v(11/24) c(E1 1/0/1:21): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_MODEM_OFFHOOK.

Apr 3 10:20:11.988: TTY2: create timer type 1, 600 seconds

Apr 3 10:20:13.976: TTY2: set timer type 10, 30 seconds

Apr 3 10:20:15.572: CSM v(11/12) c(E1 1/0/1:25): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_IC_ADDR_INFO_COLLECTED.

Apr 3 10:20:15.572: CSM v(11/12) c(E1 1/0/1:25): CSM_PROC_IC1_COLLECT_ADDR_INFO

: 172233.(calling 4312740587)

Apr 3 10:20:15.572: CSM v(11/12) c(E1 1/0/1:25): CSM_PROC_IC3_WAIT_FOR_RES_RESP

: ev_RESOURCE_OK.

Apr 3 10:20:15.580: TTY1: autoconfigure probe started

Apr 3 10:20:15.924: CSM v(11/14) c(E1 1/0/0:21): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_IC_ADDR_INFO_COLLECTED.

Apr 3 10:20:15.924: CSM v(11/14) c(E1 1/0/0:21): CSM_PROC_IC1_COLLECT_ADDR_INFO

: 172233.(calling 4332263855)

Apr 3 10:20:15.924: CSM v(11/14) c(E1 1/0/0:21): CSM_PROC_IC3_WAIT_FOR_RES_RESP

: ev_RESOURCE_OK.

Apr 3 10:20:15.972: CSM v(11/12) c(E1 1/0/1:25): CSM_PROC_IC6_WAIT_FOR_CONNECT:

ev_DSX0_CONNECTED.

Apr 3 10:20:16.320: CSM v(11/14) c(E1 1/0/0:21): CSM_PROC_IC6_WAIT_FOR_CONNECT:

ev_DSX0_CONNECTED.

Apr 3 10:20:16.744: CSM v(11/35) c(E1 1/0/1:17): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_IC_ADDR_INFO_COLLECTED.

Apr 3 10:20:16.744: CSM v(11/35) c(E1 1/0/1:17): CSM_PROC_IC1_COLLECT_ADDR_INFO

: 172233.(calling 4328269222)

Apr 3 10:20:16.744: CSM v(11/35) c(E1 1/0/1:17): CSM_PROC_IC3_WAIT_FOR_RES_RESP

: ev_RESOURCE_OK.

Apr 3 10:20:16.812: allocate slot 11 and port 6 is allocated

Apr 3 10:20:16.812: CSM v(11/6) c(E1 1/0/0:13): CSM_PROC_IDLE: ev_START_DIGIT_

COLLECT.

Apr 3 10:20:16.832: CSM v(11/6) c(E1 1/0/0:13): CSM_PROC_IC1_COLLECT_ADDR_INFO:

ev_MODEM_OFFHOOK.

Apr 3 10:20:17.140: CSM v(11/35) c(E1 1/0/1:17): CSM_PROC_IC6_WAIT_FOR_CONNECT:

ev_DSX0_CONNECTED.

Apr 3 10:20:19.444: CSM v(11/24) c(E1 1/0/1:21): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_IC_ADDR_INFO_COLLECTED.

Apr 3 10:20:19.444: CSM v(11/24) c(E1 1/0/1:21): CSM_PROC_IC1_COLLECT_ADDR_INFO

: 172233.(calling 4312792757)

Apr 3 10:20:19.444: CSM v(11/24) c(E1 1/0/1:21): CSM_PROC_IC3_WAIT_FOR_RES_RESP

: ev_RESOURCE_OK.

Apr 3 10:20:19.720: allocate slot 11 and port 9 is allocated

Apr 3 10:20:19.720: CSM v(11/9) c(E1 1/0/1:1): CSM_PROC_IDLE: ev_START_DIGIT_C

OLLECT.

Apr 3 10:20:19.748: CSM v(11/9) c(E1 1/0/1:1): CSM_PROC_IC1_COLLECT_ADDR_INFO:

ev_MODEM_OFFHOOK.

TR-AS01#

Apr 3 10:20:21.812: allocate slot 11 and port 36 is allocated

Apr 3 10:20:21.812: CSM v(11/36) c(E1 1/0/0:11): CSM_PROC_IDLE: ev_START_DIGIT

_COLLECT.

Apr 3 10:20:22.732: allocate slot 11 and port 18 is allocated

Apr 3 10:20:22.732: CSM v(11/18) c(E1 1/0/0:16): CSM_PROC_IDLE: ev_START_DIGIT

_COLLECT.

TR-AS01#

Apr 3 10:20:24.820: allocate slot 11 and port 38 is allocated

Apr 3 10:20:24.820: CSM v(11/38) c(E1 1/0/0:2): CSM_PROC_IDLE: ev_START_DIGIT_

COLLECT.

Apr 3 10:20:24.992: CSM v(11/24) c(E1 1/0/1:21): CSM_PROC_IC6_WAIT_FOR_CONNECT:

ev_DSX0_CONNECTED.

Apr 3 10:20:25.144: CSM v(11/6) c(E1 1/0/0:13): CSM_PROC_IC1_COLLECT_ADDR_INFO:

ev_IC_ADDR_INFO_COLLECTED.

Apr 3 10:20:25.144: CSM v(11/6) c(E1 1/0/0:13): CSM_PROC_IC1_COLLECT_ADDR_INFO:

172233.(calling 4312491210)

Apr 3 10:20:25.144: CSM v(11/6) c(E1 1/0/0:13): CSM_PROC_IC3_WAIT_FOR_RES_RESP:

ev_RESOURCE_OK.

Apr 3 10:20:25.612: CSM v(11/18) c(E1 1/0/0:16): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_MODEM_OFFHOOK.

Apr 3 10:20:25.620: CSM v(11/36) c(E1 1/0/0:11): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_MODEM_OFFHOOK.

Apr 3 10:20:25.620: CSM v(11/38) c(E1 1/0/0:2): CSM_PROC_IC1_COLLECT_ADDR_INFO:

ev_MODEM_OFFHOOK.

Apr 3 10:20:25.652: CSM v(11/9) c(E1 1/0/1:1): CSM_PROC_IC1_COLLECT_ADDR_INFO:

ev_DSX0_DISCONNECTED.

Apr 3 10:20:25.652: vdev_deallocate: slot 11 and port 9 is deallocated

Apr 3 10:20:25.672: CSM v(11/18) c(E1 1/0/0:16): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_DSX0_DISCONNECTED.

Apr 3 10:20:25.672: vdev_deallocate: slot 11 and port 18 is deallocated

Apr 3 10:20:25.680: CSM v(11/36) c(E1 1/0/0:11): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_DSX0_DISCONNECTED.

TR-AS01#

Apr 3 10:20:25.680: vdev_deallocate: slot 11 and port 36 is deallocated

Apr 3 10:20:25.992: CSM v(11/6) c(E1 1/0/0:13): CSM_PROC_IC6_WAIT_FOR_CONNECT:

ev_DSX0_CONNECTED.

TR-AS01#

Apr 3 10:20:31.600: TTY1/11/19: DSR came up

Apr 3 10:20:31.600: tty1/11/19: Modem: IDLE->(unknown)

Apr 3 10:20:31.600: TTY1/11/19: EXEC creation

Apr 3 10:20:31.600: TTY1/11/19: set timer type 10, 30 seconds

Apr 3 10:20:31.600: TTY1/11/19: Autoselect(2) sample 7E

Apr 3 10:20:31.604: TTY1/11/19: Autoselect(2) sample 7EFF

Apr 3 10:20:31.604: TTY1/11/19: Autoselect(2) sample 7EFF7D

Apr 3 10:20:31.604: TTY1/11/19: Autoselect(2) sample 7EFF7D23

Apr 3 10:20:31.604: TTY1/11/19 Autoselect cmd: ppp negotiate

Apr 3 10:20:31.604: TTY1/11/19: EXEC creation

Apr 3 10:20:31.604: TTY1/11/19: create timer type 1, 600 seconds

Apr 3 10:20:31.604: TTY1/11/19: destroy timer type 1

Apr 3 10:20:31.604: TTY1/11/19: no timer type 0 to destroy

Apr 3 10:20:31.604: TTY1/11/19: no timer type 2 to destroy

Apr 3 10:20:32.140: allocate slot 11 and port 20 is allocated

Apr 3 10:20:32.140: CSM v(11/20) c(E1 1/0/1:29): CSM_PROC_IDLE: ev_START_DIGIT

_COLLECT.

Apr 3 10:20:34.240: allocate slot 11 and port 21 is allocated

Apr 3 10:20:34.240: CSM v(11/21) c(E1 1/0/1:28): CSM_PROC_IDLE: ev_START_DIGIT

_COLLECT.

Apr 3 10:20:34.592: CSM v(11/38) c(E1 1/0/0:2): CSM_PROC_IC1_COLLECT_ADDR_INFO:

ev_IC_ADDR_INFO_COLLECTED.

Apr 3 10:20:34.592: CSM v(11/38) c(E1 1/0/0:2): CSM_PROC_IC1_COLLECT_ADDR_INFO:

172233.(calling 4312414982)

Apr 3 10:20:34.592: CSM v(11/38) c(E1 1/0/0:2): CSM_PROC_IC3_WAIT_FOR_RES_RESP:

ev_RESOURCE_OK.

Apr 3 10:20:34.992: CSM v(11/38) c(E1 1/0/0:2): CSM_PROC_IC6_WAIT_FOR_CONNECT:

ev_DSX0_CONNECTED.

Apr 3 10:20:39.608: TTY1/11/14: DSR came up

Apr 3 10:20:39.608: tty1/11/14: Modem: IDLE->(unknown)

Apr 3 10:20:39.608: TTY1/11/35: DSR came up

Apr 3 10:20:39.608: tty1/11/35: Modem: IDLE->(unknown)

Apr 3 10:20:39.608: TTY1/11/14: EXEC creation

Apr 3 10:20:39.608: TTY1/11/14: set timer type 10, 30 seconds

Apr 3 10:20:39.608: TTY1/11/35: EXEC creation

Apr 3 10:20:39.612: TTY1/11/35: set timer type 10, 30 seconds

Apr 3 10:20:39.612: TTY1/11/14: Autoselect(2) sample 7E

Apr 3 10:20:39.612: TTY1/11/35: Autoselect(2) sample 7E

Apr 3 10:20:39.612: TTY1/11/14: Autoselect(2) sample 7EFF

Apr 3 10:20:39.612: TTY1/11/35: Autoselect(2) sample 7EFF

Apr 3 10:20:39.612: TTY1/11/14: Autoselect(2) sample 7EFF7D

Apr 3 10:20:39.612: TTY1/11/35: Autoselect(2) sample 7EFF7D

Apr 3 10:20:39.612: TTY1/11/14: Autoselect(2) sample 7EFF7D23

Apr 3 10:20:39.612: TTY1/11/14 Autoselect cmd: ppp negotiate

Apr 3 10:20:39.616: TTY1/11/35: Autoselect(2) sample 7EFF7D23

Apr 3 10:20:39.616: TTY1/11/35 Autoselect cmd: ppp negotiate

Apr 3 10:20:39.616: TTY1/11/14: EXEC creation

Apr 3 10:20:39.616: TTY1/11/14: create timer type 1, 600 seconds

Apr 3 10:20:39.616: TTY1/11/14: destroy timer type 1

Apr 3 10:20:39.616: TTY1/11/14: no timer type 0 to destroy

Apr 3 10:20:39.616: TTY1/11/14: no timer type 2 to destroy

Apr 3 10:20:39.616: TTY1/11/35: EXEC creation

Apr 3 10:20:39.616: TTY1/11/35: create timer type 1, 600 seconds

Apr 3 10:20:39.620: TTY1/11/35: destroy timer type 1

Apr 3 10:20:39.620: TTY1/11/35: no timer type 0 to destroy

Apr 3 10:20:39.620: TTY1/11/35: no timer type 2 to destroy

dinekal · ‎04-02-2003

Debug Ouput 3:

Apr 3 10:20:40.640: CSM v(11/20) c(E1 1/0/1:29): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_MODEM_OFFHOOK.

Apr 3 10:20:40.640: CSM v(11/21) c(E1 1/0/1:28): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_MODEM_OFFHOOK.

Apr 3 10:20:40.700: CSM v(11/20) c(E1 1/0/1:29): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_DSX0_DISCONNECTED.

Apr 3 10:20:40.700: vdev_deallocate: slot 11 and port 20 is deallocated

Apr 3 10:20:40.700: CSM v(11/21) c(E1 1/0/1:28): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_DSX0_DISCONNECTED.

Apr 3 10:20:40.700: vdev_deallocate: slot 11 and port 21 is deallocated

Apr 3 10:20:42.616: TTY1/11/12: DSR came up

Apr 3 10:20:42.616: tty1/11/12: Modem: IDLE->(unknown)

Apr 3 10:20:42.620: TTY1/11/12: EXEC creation

Apr 3 10:20:42.620: TTY1/11/12: set timer type 10, 30 seconds

Apr 3 10:20:42.620: TTY1/11/12: Autoselect(2) sample 7E

Apr 3 10:20:42.620: TTY1/11/12: Autoselect(2) sample 7EFF

Apr 3 10:20:42.624: TTY1/11/12: Autoselect(2) sample 7EFF7D

Apr 3 10:20:42.624: TTY1/11/12: Autoselect(2) sample 7EFF7D23

Apr 3 10:20:42.624: TTY1/11/12 Autoselect cmd: ppp negotiate

Apr 3 10:20:42.624: TTY1/11/12: EXEC creation

Apr 3 10:20:42.624: TTY1/11/12: create timer type 1, 600 seconds

Apr 3 10:20:42.624: TTY1/11/12: destroy timer type 1

Apr 3 10:20:42.624: TTY1/11/12: no timer type 0 to destroy

Apr 3 10:20:42.624: TTY1/11/12: no timer type 2 to destroy

Apr 3 10:20:52.624: TTY1: autoconfigure probe started

Apr 3 10:20:53.780: allocate slot 11 and port 16 is allocated

Apr 3 10:20:53.780: CSM v(11/16) c(E1 1/0/0:23): CSM_PROC_IDLE: ev_START_DIGIT

_COLLECT.

Apr 3 10:20:53.804: CSM v(11/16) c(E1 1/0/0:23): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_MODEM_OFFHOOK.

Apr 3 10:20:58.624: TTY1/11/38: DSR came up

Apr 3 10:20:58.624: tty1/11/38: Modem: IDLE->(unknown)

Apr 3 10:20:58.624: TTY1/11/38: EXEC creation

Apr 3 10:20:58.624: TTY1/11/38: set timer type 10, 30 seconds

Apr 3 10:20:58.884: TTY1/11/38: Autoselect(2) sample 7E

Apr 3 10:20:58.884: TTY1/11/38: Autoselect(2) sample 7EFF

Apr 3 10:20:58.884: TTY1/11/38: Autoselect(2) sample 7EFF7D

Apr 3 10:20:58.884: TTY1/11/38: Autoselect(2) sample 7EFF7D23

Apr 3 10:20:58.884: TTY1/11/38 Autoselect cmd: ppp negotiate

Apr 3 10:20:58.884: TTY1/11/38: EXEC creation

Apr 3 10:20:58.884: TTY1/11/38: create timer type 1, 600 seconds

Apr 3 10:21:01.628: TTY1/11/00: DSR was dropped

Apr 3 10:21:01.628: tty1/11/00: Modem: READY->(unknown)

Apr 3 10:21:02.460: CSM v(11/0) c(E1 1/0/1:20): CSM_PROC_IC7_OC6_CONNECTED: ev

_DSX0_DISCONNECTED.

Apr 3 10:21:02.572: CSM v(11/0) c(E1 1/0/1:20): CSM_PROC_IC9_OC8_DISCONNECTING:

ev_MODEM_ONHOOK.

Apr 3 10:21:02.572: vdev_deallocate: slot 11 and port 0 is deallocated

Apr 3 10:21:02.628: TTY1/11/00: dropping DTR, hanging up

Apr 3 10:21:02.628: TTY1/11/00: Async Int reset: Dropping DTR

Apr 3 10:21:02.628: tty1/11/00: Modem: HANGUP->(unknown)

Apr 3 10:21:02.628: TTY1/11/35: DSR was dropped

Apr 3 10:21:02.628: tty1/11/35: Modem: READY->(unknown)

Apr 3 10:21:02.628: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:03.080: CSM v(11/35) c(E1 1/0/1:17): CSM_PROC_IC7_OC6_CONNECTED: e

v_MODEM_ONHOOK.

Apr 3 10:21:03.080: vdev_deallocate: slot 11 and port 35 is deallocated

Apr 3 10:21:03.100: CSM v(11/16) c(E1 1/0/0:23): CSM_PROC_IC1_COLLECT_ADDR_INFO

: ev_IC_ADDR_INFO_COLLECTED.

Apr 3 10:21:03.100: CSM v(11/16) c(E1 1/0/0:23): CSM_PROC_IC1_COLLECT_ADDR_INFO

: 172233.(calling 4312410128)

Apr 3 10:21:03.100: CSM v(11/16) c(E1 1/0/0:23): CSM_PROC_IC3_WAIT_FOR_RES_RESP

: ev_RESOURCE_OK.

Apr 3 10:21:03.500: CSM v(11/16) c(E1 1/0/0:23): CSM_PROC_IC6_WAIT_FOR_CONNECT:

ev_DSX0_CONNECTED.

Apr 3 10:21:03.628: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:03.628: TTY1/11/35: dropping DTR, hanging up

Apr 3 10:21:03.628: TTY1/11/35: Async Int reset: Dropping DTR

Apr 3 10:21:03.628: tty1/11/35: Modem: HANGUP->(unknown)

Apr 3 10:21:03.628: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:03.628: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:03.748: TTY1/11/38: destroy timer type 1

Apr 3 10:21:03.748: TTY1/11/38: no timer type 0 to destroy

Apr 3 10:21:03.748: TTY1/11/38: no timer type 2 to destroy

Apr 3 10:21:04.632: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:04.632: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:04.632: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:04.632: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:04.632: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:05.640: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:05.640: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:05.640: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:05.640: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:05.640: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:05.640: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:06.644: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:06.644: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:06.644: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:06.644: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:06.644: TTY1/11/00: cleanup pending. Delaying DTR

Apr 3 10:21:06.644: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:07.628: TTY1/11/00: no timer type 0 to destroy

Apr 3 10:21:07.628: TTY1/11/00: no timer type 1 to destroy

Apr 3 10:21:07.628: TTY1/11/00: no timer type 3 to destroy

Apr 3 10:21:07.628: TTY1/11/00: no timer type 4 to destroy

Apr 3 10:21:07.628: TTY1/11/00: no timer type 2 to destroy

Apr 3 10:21:07.628: Async1/11/00: allowing modem_process to continue hangup

Apr 3 10:21:07.648: TTY1/11/00: restoring DTR

Apr 3 10:21:07.648: TTY1/11/35: cleanup pending. Delaying DTR

Apr 3 10:21:07.652: TTY1/11/00: autoconfigure probe started

Apr 3 10:21:08.628: TTY1/11/35: no timer type 0 to destroy

Apr 3 10:21:08.628: TTY1/11/35: no timer type 1 to destroy

Apr 3 10:21:08.628: TTY1/11/35: no timer type 3 to destroy

Apr 3 10:21:08.628: TTY1/11/35: no timer type 4 to destroy

Apr 3 10:21:08.628: TTY1/11/35: no timer type 2 to destroy

Apr 3 10:21:08.628: Async1/11/35: allowing modem_process to continue hangup

Apr 3 10:21:08.652: TTY1/11/35: restoring DTR

Apr 3 10:21:08.660: TTY1/11/35: autoconfigure probe started

Apr 3 10:21:14.656: TTY1/11/06: DSR came up

Apr 3 10:21:14.656: tty1/11/06: Modem: IDLE->(unknown)

Apr 3 10:21:14.656: TTY1/11/06: EXEC creation

Apr 3 10:21:14.656: TTY1/11/06: set timer type 10, 30 seconds

Apr 3 10:21:14.656: TTY1/11/06: Autoselect(2) sample 7E

Apr 3 10:21:14.656: TTY1/11/06: Autoselect(2) sample 7EFF

Apr 3 10:21:14.656: TTY1/11/06: Autoselect(2) sample 7EFF7D

Apr 3 10:21:14.656: TTY1/11/06: Autoselect(2) sample 7EFF7D23

Apr 3 10:21:14.656: TTY1/11/06 Autoselect cmd: ppp negotiate

Apr 3 10:21:14.660: TTY1/11/06: EXEC creation

Apr 3 10:21:14.660: TTY1/11/06: create timer type 1, 600 seconds

TR-AS01#

Apr 3 10:21:17.660: TTY3: timer type 1 expired

Apr 3 10:21:17.660: TTY3: Exec timer (continued)

TR-AS01#

Apr 3 10:21:19.660: TTY1/11/24: DSR came up

Apr 3 10:21:19.660: tty1/11/24: Modem: IDLE->(unknown)

Apr 3 10:21:19.660: TTY1/11/24: EXEC creation

Apr 3 10:21:19.664: TTY1/11/24: set timer type 10, 30 seconds

Apr 3 10:21:19.664: TTY1/11/24: Autoselect(2) sample 7E

Apr 3 10:21:19.664: TTY1/11/24: Autoselect(2) sample 7EFF

Apr 3 10:21:19.664: TTY1/11/24: Autoselect(2) sample 7EFF7D

Apr 3 10:21:19.664: TTY1/11/24: Autoselect(2) sample 7EFF7D23

Apr 3 10:21:19.664: TTY1/11/24 Autoselect cmd: ppp negotiate

Apr 3 10:21:19.664: TTY1/11/24: EXEC creation

Apr 3 10:21:19.664: TTY1/11/24: create timer type 1, 600 seconds

Apr 3 10:21:19.668: TTY1/11/24: destroy timer type 1

TR-AS01#

Apr 3 10:21:19.668: TTY1/11/24: no timer type 0 to destroy

Apr 3 10:21:19.668: TTY1/11/24: no timer type 2 to destroy

Apr 3 10:21:19.780: TTY1/11/06: destroy timer type 1

Apr 3 10:21:19.780: TTY1/11/06: no timer type 0 to destroy

Apr 3 10:21:19.780: TTY1/11/06: no timer type 2 to destroy