[cisco-nas] Modems not responding on cisco AS5300.
Joseph Mays
mays at win.net
Mon Aug 25 20:27:28 EDT 2008
Well, what's puzzling me is that it's the same six modems on two cards in
the box. So I'm wondering if the slots the cards are plugged into share any
circuitry.
----- Original Message -----
From: "Aaron Leonard" <Aaron at cisco.com>
To: "Joseph Mays" <mays at win.net>
Cc: <cisco-nas at puck.nether.net>; "Mike Andrews" <mandrews at bit0.com>
Sent: Monday, August 25, 2008 8:18 PM
Subject: Re: [cisco-nas] Modems not responding on cisco AS5300.
> Joseph,
>
> If this problem affects groups of 6 ports, and if the first port number
> in that affected group is evenly divisible by 6, then I would say that
> you are suffering not from "DSP death" but from "hex death".
>
> Background: MICA modems (the older HMMs anyway) were implemented with 6
> modems module. The 6 modems shared a single i960 control processor
> (CP), and each 2 modems shared one DSP (i.e. 3 DSPs per HMM.)
>
> So it sounds like the CP, not the DSP, has crashed. Right now I
> wouldn't blame the slot (never having heard of such a thing), but the CP
> code, and figure it's just happenstance that the third HMM on each card
> happened to fail.
>
> In any case, the guidance below is still generally applicable - i.e. run
> latest code (2.9.5.0); turn on modem recovery.
>
> Aaron
>
> ------------------------------------------------------------------------
>
>> More news on this problem. There are two modem cards in this box. Each
>> with
>> 48 active modems, so 1/0 1/47 and 2/0 2/47.
>>
>> In dialing through all the modems, I get modem tone on 1/0 to 1/11 then
>> dead
>> air on 1/12 to 1/17, then tone again on 1/18 to 1/47. Then modem tone on
>> 2/0
>> to 2/11, then dead air on 2/12 to 2/17, then modem tone again on 2/18 to
>> 2/47.
>>
>> If it were just one card, easy, bad spe chipset. But it seems awfully
>> damned
>> coincidental to have the same set of chips bad on each card. Is there any
>> shared circuitry for positions x/12 to x/17 in the slots on the
>> motherboard?
>> I don't see anything at all in the config that specifically references
>> those
>> modem ranges, but could there be any modem thing that is causing it?
>>
>>
>>
>>
>> ----- Original Message -----
>> From: "Aaron Leonard" <Aaron at cisco.com>
>> To: "Joseph Mays" <mays at win.net>
>> Cc: <cisco-nas at puck.nether.net>
>> Sent: Monday, August 25, 2008 3:48 PM
>> Subject: Re: [cisco-nas] Modems not responding on cisco AS5300.
>>
>>
>>
>>> Hi Joseph,
>>>
>>> (Boy, it's been awhile since this list has gotten a legitimate
>>> question!)
>>>
>>> From the debugs, it looks like this call into modem 2/51 simply failed
>>> to train. It is possible that the DSP that handles 2/51 (and 2/50) has
>>> gone into some bad state; can't tell from the debugs alone.
>>>
>>> I would get the "show modem csr" output and see whether there is an
>>> inordinately large % of failed calls into this DSP (i.e. 2/50 and 2/51),
>>> when compared with other modems in this 5300 that handle the same client
>>> base.
>>>
>>> If so, then I suppose this would be a DSP bug of some sort. Could try
>>> upgrading to the last portware (2.9.5.0) if you're not already running
>>> it. If that doesn't clear it up, then could configure Modem Recovery.
>>>
>>> Configuring MICA Modem Recovery
>>> http://www.cisco.com/en/US/tech/tk801/tk36/technologies_tech_note09186a0080094cac.shtml
>>>
>>> Hth,
>>>
>>> Aaron
>>>
>>> ----
>>>
>>>
>>>> We're working with a Cisco AS5300, and have been having a problem
>>>> wherein it will answer a call, but the call never gets forwarded to a
>>>> modem. Sometimes it works, usually not. There is some reason to
>>>> believe that it works after a call has hit a modem once. It fails the
>>>> first time and works after that, but I can't confirm that.
>>>>
>>>> Here's the "debug modem csm" output from a failed call. I've bolded
>>>> what I assume is the important text.
>>>>
>>>> as5300_frv_1#
>>>> *Apr 27 00:30:32.087: ISDN Dc2 SC Q921d: srl_send_l2_pak:
>>>> source_id = IUA, dest_id = Q.931, prim = DL_DATA_IND
>>>> priv_len = 4 int_id = 0x62D3E3E0 datasize = 62
>>>> *Apr 27 00:30:32.087: ISDN Dc2 SC Q921d: data =
>>>> 0x62D3E3E0000000010241040000010000
>>>> *Apr 27 00:30:32.091: 080207AB0504038090A21803A1838E1E
>>>> *Apr 27 00:30:32.091: 0281836C0CA181353032383135373030
>>>> *Apr 27 00:30:32.091: 30700BA134333433393431323334
>>>> *Apr 27 00:30:32.091: ISDN Dc2 SC BACKHAUL: L3IF_rx_L2_pak: received
>>>> data 0x080207AB0504038090A21803A1838E1E
>>>> *Apr 27 00:30:32.091: 0281836C0CA181353032383135373030
>>>> *Apr 27 00:30:32.091: 30700BA134333433393431323334
>>>> *Apr 27 00:30:32.091: ISDN Dc2 SC Q931d: L3_Go: source = 0x20A, event
>>>> = 0x241, call id = 0x0, int id = 0x0
>>>> *Apr 27 00:30:32.091: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
>>>> state 0 event 0x5 ces 1
>>>> *Apr 27 00:30:32.091: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
>>>> 0x87AB SETUP:U0_Setup(nlcb)
>>>> *Apr 27 00:30:32.091: ISDN Dc2 SC Q931d: L3_state_change: callref
>>>> 0x87AB old NULL_STATE, new CALL_PRESENT
>>>> *Apr 27 00:30:32.091: ISDN Dc2 SC CC: CCPRI_Go: source id 0x300, call
>>>> id 0xE, event 0x341 (pre-ccb recovery)
>>>> *Apr 27 00:30:32.091: ISDN Dc2 SC CC: CCPRI_Go: call id 0x2 cref 0xE
>>>> event 0x87AB Source->L3
>>>> *Apr 27 00:30:32.095: ISDN Dc2 SC CC: CCPCC_CallIdle: event 0x90 b
>>>> channel 0 nfas int_id 0 call_id 0xE
>>>> *Apr 27 00:30:32.095: ISDN Dc2 SC CC: CCPRI_AcceptChanId: Negotiated
>>>> int_id 0 bchan 0 cref 0x87AB call_id 0x000E lo_chan 14 final
>>>> int_id/bchan 2/14 cause 0
>>>> *Apr 27 00:30:32.095: ISDN Dc2 SC TGRM: get_tgrm_avail_state: idb
>>>> 0x62D3E3E0 bchan 14 service_state 0 call_state 2 false busy 0x0 dsl 2
>>>> *Apr 27 00:30:32.095: ISDN Dc2 SC TGRM: update_tgrm_call_status: idb
>>>> 0x62D3E3E0 bchan 14 availability state 1 call state(prev,new) (0,2),
>>>> dsl
>>>> 2
>>>> *Apr 27 00:30:32.095: ISDN Dc2 SC TGRM: update_tgrm_call_status:
>>>> Calling TGRM with tgrm_call_isdn_update: idb 0x62D3E3E0 bchan 14 call
>>>> state 1 call type 2 call dir 1
>>>> *Apr 27 00:30:32.095: ISDN Dc2 SC EVENT: process_rxstate: ces/callid
>>>> 1/0xE calltype 2 CALL_INCOMING
>>>> *Apr 27 00:30:32.095: ISDN Dc2 SC PACKET: process_rxstate:
>>>> 0x4C0201000E1A04040000100218042003
>>>> *Apr 27 00:30:32.095: 0E02700C020134333433393431323334
>>>> *Apr 27 00:30:32.095: 04038090A21803A1838E1E0281836C0C
>>>> *Apr 27 00:30:32.095: A18135303238313537303030700BA134
>>>> *Apr 27 00:30:32.095: 333433393431323334
>>>> *Apr 27 00:30:32.095: ISDN Dc2 SC EVENTd: calltrkr_incoming_call:
>>>> call_id=0xE
>>>> *Apr 27 00:30:32.095: ISDN Dc2 SC EVENTd: calltrkr_setup_received:
>>>> isdn_info=1658087904l, call_id=0xE ANSWER
>>>> *Apr 27 00:30:32.099: ISDN Dc2 SC EVENTd: calltrkr_setup_received:
>>>> isdn_info=1658675348l, call_id=0xE ANSWER
>>>> *Apr 27 00:30:32.099: ISDN Dc2 SC EVENTd: call_incoming: b channel 13,
>>>> call type is VOICE ULAW
>>>> *Apr 27 00:30:32.099: ISDN Dc2 SC EVENTd: call_incoming: Received a
>>>> VOICE call from 5028157000 on b channel 13 at 64 Kb/s
>>>> *Apr 27 00:30:32.099: VDEV_ALLOCATE: 2/51 is allocated
>>>> *Apr 27 00:30:32.099: ISDN Dc2 SC EVENTd: isdn_fromdialer: RM returned
>>>> call_type 1 resource type 0 response 2
>>>> *Apr 27 00:30:32.099: ISDN Dc0 SC CC: CCPRI_Go: source id 0x500, call
>>>> id 0x0, event 0x4A (pre-ccb recovery)
>>>> *Apr 27 00:30:32.099: ISDN Dc2 SC CC: CCPRI_Go: call_id 0xE cref
>>>> 0x87AB event 0x4A Source->HOST
>>>> *Apr 27 00:30:32.099: ISDN Dc2 SC CC: CCPCC_CallOffered: event = 0x4A
>>>> b channel 14 nfas int_id 2 call_id 0xE
>>>> *Apr 27 00:30:32.099: ISDN Dc2 SC Q931d: L3_Go: source = 0x400, event
>>>> = 0x340, call id = 0xE, int id = 0x2
>>>> *Apr 27 00:30:32.099: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
>>>> state 6 event 0x88 ces 1
>>>> *Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
>>>> 0x87AB CC_CALL_PROC_REQ:U6_CallProcReq(nlcb)
>>>> *Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: L3_TxSarMsg: InfoLen = 10
>>>> octets 0x080287AB021803A9838E
>>>> *Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: srl_send_l3_pak:
>>>> source_id = Q.931, dest_id = IUA, prim = DL_DATA_REQ
>>>> priv_len = 4 int_id = 0x62D3E3E0 datasize = 26
>>>> *Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: data =
>>>> 0x62D3E3E0000100000240046A00010800
>>>> *Apr 27 00:30:32.103: 080287AB021803A9838E
>>>> *Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: L3_state_change: callref
>>>> 0x87AB old CALL_PRESENT, new IN_CALL_PROC
>>>> *Apr 27 00:30:32.103: ISDN Dc2 SC EVENT: UserIdle: callid 0xE received
>>>> ACCEPT_CALL (0x13)
>>>> *Apr 27 00:30:32.103: EVENT_FROM_ISDN: dchan_idb=0x62D3E3E0,
>>>> call_id=0xE, ces=0x0
>>>> bchan=0xD, event=0x1, cause=0x0
>>>> *Apr 27 00:30:32.103: dev in call to isdn : set dnis_collected &
>>>> fap_notify
>>>> *Apr 27 00:30:32.103: EVENT_FROM_ISDN:(000E): DEV_INCALL at slot 2,
>>>> port 51
>>>> *Apr 27 00:30:32.103: EVENT_FROM_ISDN: decode:calling oct3 0xA1,
>>>> called oct3 0xA1, oct3a 0x0,mask 0x3D
>>>> *Apr 27 00:30:32.103: EVENT_FROM_ISDN: csm_call_info:calling oct3
>>>> 0xA1, called oct3 0xA1, oct3a 0x0,mask 0x3D
>>>> *Apr 27 00:30:32.103: CSM_PROC_IDLE: CSM_EVENT_ISDN_CALL at slot 2,
>>>> port 51
>>>> *Apr 27 00:30:32.103: Mica Modem(2/51): Configure(0x1 = 0x0)
>>>> *Apr 27 00:30:32.103: Mica Modem(2/51): Configure(0x23 = 0x0)
>>>> *Apr 27 00:30:32.103: Mica Modem(2/51): Call Setup
>>>> *Apr 27 00:30:32.103: csm_connect_pri_vdev: TS allocated at bp_stream
>>>> 0, bp_Ch 13, vdev_common 0x625BA2C4 2/51
>>>> *Apr 27 00:30:32.107: ISDN Dc0 SC CC: CCPRI_Go: source id 0x500, call
>>>> id 0x0, event 0x4E (pre-ccb recovery)
>>>> *Apr 27 00:30:32.107: ISDN Dc2 SC CC: CCPRI_Go: call_id 0xE cref
>>>> 0x87AB event 0x4E Source->HOST
>>>> *Apr 27 00:30:32.107: ISDN Dc2 SC CC: CCPCC_CallRoutingIn: executing
>>>> with event = 4E in state = CALL ROUTING_IN
>>>> *Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_Go: source = 0x400, event
>>>> = 0x340, call id = 0xE, int id = 0x2
>>>> *Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
>>>> state 9 event 0x86 ces 1
>>>> *Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
>>>> 0x87AB CC_ALERTING_REQ:U9_AlertingReq(nlcb)
>>>> *Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_TxSarMsg: InfoLen = 5
>>>> octets 0x080287AB01
>>>> *Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: srl_send_l3_pak:
>>>> source_id = Q.931, dest_id = IUA, prim = DL_DATA_REQ
>>>> priv_len = 4 int_id = 0x62D3E3E0 datasize = 21
>>>> *Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: data =
>>>> 0x62D3E3E0000100000240046A00010800
>>>> *Apr 27 00:30:32.107: 080287AB01
>>>> *Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_state_change: callref
>>>> 0x87AB old IN_CALL_PROC, new CALL_RECEIVED
>>>> *Apr 27 00:30:32.175: Mica Modem(2/51): State Transition to Call Setup
>>>> *Apr 27 00:30:32.175: Mica Modem(2/51): Went offhook
>>>> *Apr 27 00:30:32.175: CSM_PROC_IC2_RING: CSM_EVENT_MODEM_OFFHOOK at
>>>> slot 2, port 51
>>>> *Apr 27 00:30:32.175: ISDN Dc2 SC EVENT: process_modem_command:
>>>> received event VOICE_ANS on callid 0xE and bchan 13 ces 0 cause 16
>>>> switch type 25
>>>> *Apr 27 00:30:32.175: ISDN Dc2 SC EVENTd: isdn_send_connect: msg 74,
>>>> call id 0xE, ces 0 bchan 13, call type VOICE
>>>> *Apr 27 00:30:32.175: ISDN Dc0 SC CC: CCPRI_Go: source id 0x500, call
>>>> id 0x0, event 0x4A (pre-ccb recovery)
>>>> *Apr 27 00:30:32.175: ISDN Dc2 SC CC: CCPRI_Go: call_id 0xE cref
>>>> 0x87AB event 0x4A Source->HOST
>>>> *Apr 27 00:30:32.175: ISDN Dc2 SC CC: CCPCC_CallRinging: event 0x4A b
>>>> channel 14 nfas int_id 2 call_id 0xE
>>>> *Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_Go: source = 0x400, event
>>>> = 0x340, call id = 0xE, int id = 0x2
>>>> *Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
>>>> state 7 event 0x81 ces 1
>>>> *Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
>>>> 0x87AB CC_SETUP_RESP:U7_SetupResp(nlcb)
>>>> *Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_TxSarMsg: InfoLen = 5
>>>> octets 0x080287AB07
>>>> *Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: srl_send_l3_pak:
>>>> source_id = Q.931, dest_id = IUA, prim = DL_DATA_REQ
>>>> priv_len = 4 int_id = 0x62D3E3E0 datasize = 21
>>>> *Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: data =
>>>> 0x62D3E3E0000100000240046A00010800
>>>> *Apr 27 00:30:32.179: 080287AB07
>>>> *Apr 27 00:30:32.179: ISDN Dc2 SC LIFd: LIF_StartTimer: timer
>>>> (0x630C8090), ticks (4000), event (0x131C)
>>>> *Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_state_change: callref
>>>> 0x87AB old CALL_RECEIVED, new CONNECT_REQUEST
>>>> *Apr 27 00:30:32.323: ISDN Dc2 SC Q921d: srl_send_l2_pak:
>>>> source_id = IUA, dest_id = Q.931, prim = DL_DATA_IND
>>>> priv_len = 4 int_id = 0x62D3E3E0 datasize = 21
>>>> *Apr 27 00:30:32.323: ISDN Dc2 SC Q921d: data =
>>>> 0x62D3E3E0000000010241040000010000
>>>> *Apr 27 00:30:32.323: 080207AB0F
>>>> *Apr 27 00:30:32.323: ISDN Dc2 SC BACKHAUL: L3IF_rx_L2_pak: received
>>>> data 0x080207AB0F
>>>> *Apr 27 00:30:32.323: ISDN Dc2 SC Q931d: L3_Go: source = 0x20A, event
>>>> = 0x241, call id = 0x0, int id = 0x0
>>>> *Apr 27 00:30:32.323: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
>>>> state 8 event 0xF ces 1
>>>> *Apr 27 00:30:32.323: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
>>>> 0x87AB CONNECT_ACK:U8_ConnectAck(nlcb)
>>>> *Apr 27 00:30:32.323: ISDN Dc2 SC Q931d: L3_state_change: callref
>>>> 0x87AB old CONNECT_REQUEST, new CALL_ACTIVE
>>>> *Apr 27 00:30:32.327: ISDN Dc2 SC CC: CCPRI_Go: source id 0x300, call
>>>> id 0xE, event 0x341 (pre-ccb recovery)
>>>> *Apr 27 00:30:32.327: ISDN Dc2 SC CC: CCPRI_Go: dispatching event 0x92
>>>> call id 0xE cref 0x87AB Source->L3
>>>> *Apr 27 00:30:32.327: ISDN Dc2 SC CC: CCPCC_CallRoutingIn: executing
>>>> with event = 92 in state = CALL ROUTING_IN
>>>> *Apr 27 00:30:32.327: ISDN Dc2 SC EVENT: process_rxstate: ces/callid
>>>> 1/0xE calltype 2 CALL_PROGRESS
>>>> *Apr 27 00:30:32.327: ISDN Dc2 SC PACKET: process_rxstate:
>>>> 0x4E0201000E031E0187
>>>> *Apr 27 00:30:32.327: ISDN Dc2 SC EVENTd: calltrkr_call_connect:
>>>> isdn_info=0x62DD6094, call_id=0xE
>>>> *Apr 27 00:30:32.327: ISDN Dc2 SC EVENTd: calltrkr_call_connect:
>>>> hndl=14l, cntrl_slot=0, cntrl_port=2, cntrl_channel=2.
>>>> *Apr 27 00:30:32.327: ISDN Dc2 SC EVENTd: call_progress:
>>>> CALL_CONNECTED b channel 13 call id 0xE
>>>> *Apr 27 00:30:32.327: EVENT_FROM_ISDN: dchan_idb=0x62D3E3E0,
>>>> call_id=0xE, ces=0x0
>>>> bchan=0xD, event=0x4, cause=0x0
>>>> *Apr 27 00:30:32.327: EVENT_FROM_ISDN:(000E): DEV_CONNECTED at slot 2,
>>>> port 51
>>>> *Apr 27 00:30:32.327: CSM_PROC_IC6_WAIT_FOR_CONNECT:
>>>> CSM_EVENT_ISDN_CONNECTED at slot 2, port 51
>>>> *Apr 27 00:30:32.327: Mica Modem(2/51): Link Initiate
>>>> *Apr 27 00:30:32.327: %ISDN-6-CONNECT: Interface Serial2:13 is now
>>>> connected to 5028157000 N/A
>>>> *Apr 27 00:30:33.419: Mica Modem(2/51): State Transition to Connect
>>>> *Apr 27 00:30:33.435: Mica Modem(2/51): State Transition to V8bis
>>>> Exchange
>>>> *Apr 27 00:30:37.879: Mica Modem(2/51): State Transition to Link
>>>> **Apr 27 00:30:42.147: Mica Modem(2/51): State Transition to Trainup
>>>> *Apr 27 00:30:50.015: Mica Modem(2/51): State Transition to Terminating
>>>> *Apr 27 00:30:50.451: %CALLRECORD-3-MICA_TERSE_CALL_FAILED_REC: DS0
>>>> slot/contr/chan=0/2/13, slot/port=2/51, call_id=E, calling=5028157000,
>>>> called=4343941234, time=17, finl-state=Train Up, disc(modem)=6102
>>>> Condition occurred during call setup/DSP condition/failure in modem
>>>> training up
>>>> **Apr 27 00:30:50.615: Mica Modem(2/51): State Transition to Idle
>>>> *Apr 27 00:30:50.615: Mica Modem(2/51): Went onhook
>>>> *Apr 27 00:30:50.615: CSM_PROC_IC7_OC6_CONNECTED:
>>>> CSM_EVENT_MODEM_ONHOOK at slot 2, port 51
>>>> *Apr 27 00:30:50.615: CSM_PROC_IC7_OC6_CONNECTED: cause code to isdn
>>>> 0x0
>>>> *Apr 27 00:30:50.615: CSM(2/51): Enter csm_enter_idle_state
>>>> *Apr 27 00:30:50.615: VDEV_DEALLOCATE: slot 2, port 51 is deallocated
>>>> *Apr 27 00:30:50.619: ISDN Dc2 SC EVENT: process_modem_command:
>>>> received event VOICE_HANGUP on callid 0xE and bchan 13 ces 0 cause 16
>>>> switch type 25
>>>> *Apr 27 00:30:50.619: ISDN Dc2 SC EVENTd: process_modem_command: call
>>>> id 0xE ces may have changed is now 1
>>>> *Apr 27 00:30:50.623: ISDN Dc2 SC EVENTd: process_disconnect: call id
>>>> 0xE, call type is VOICE, b_idb 0x62DCDA0C, ces 1, cause Normal call
>>>> clearing(0x10)
>>>> *Apr 27 00:30:50.623: %ISDN-6-DISCONNECT: Interface Serial2:13
>>>> disconnected from 5028157000 , call lasted 18 seconds
>>>> *Apr 27 00:30:50.623: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
>>>> isdn_info=0x62DD6094, call_id=0xE
>>>> *Apr 27 00:30:50.623: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
>>>> hndl=14l, cause=16, Normal call clearing
>>>> *Apr 27 00:30:50.623: ISDN Dc2 SC EVENTd: process_disconnect: Raw
>>>> Release Message 0x4BFF01000E040802FF900803809081
>>>> *Apr 27 00:31:00.211: ISDN Dc2 SC Q921d: srl_send_l2_pak:
>>>> source_id = IUA, dest_id = Q.931, prim = DL_DATA_IND
>>>> priv_len = 4 int_id = 0x62D3E3E0 datasize = 25
>>>> *Apr 27 00:31:00.215: ISDN Dc2 SC Q921d: data =
>>>> 0x62D3E3E0000000010241040000010000
>>>> *Apr 27 00:31:00.215: 080207AB4D08028010
>>>> *Apr 27 00:31:00.215: ISDN Dc2 SC BACKHAUL: L3IF_rx_L2_pak: received
>>>> data 0x080207AB4D08028010
>>>> *Apr 27 00:31:00.215: ISDN Dc2 SC Q931d: L3_Go: source = 0x20A, event
>>>> = 0x241, call id = 0x0, int id = 0x0
>>>> *Apr 27 00:31:00.215: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
>>>> state 10 event 0x4D ces 1
>>>> *Apr 27 00:31:00.215: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
>>>> 0x87AB RELEASE:Ux_Release(nlcb)
>>>> *Apr 27 00:31:00.215: ISDN Dc2 SC Q931d: L3_state_change: callref
>>>> 0x87AB old CALL_ACTIVE, new RELEASE_REQUEST
>>>> *Apr 27 00:31:00.215: ISDN Dc2 SC CC: CCPRI_Go: source id 0x300, call
>>>> id 0xE, event 0x341 (pre-ccb recovery)
>>>> *Apr 27 00:31:00.215: ISDN Dc2 SC CC: CCPRI_Go: dispatching event 0x97
>>>> call id 0xE cref 0x87AB Source->L3
>>>> *Apr 27 00:31:00.215: ISDN Dc2 SC CC: CCPCC_CallConnected: event 0x97
>>>> b channel 14 nfas int_id 2 call_id 0xE
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: CC_CHAN_GetUserpri: using
>>>> call_id = 0xE
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC CCd: CC_CHAN_ReleaseChanpri: channel
>>>> 14
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC TGRM: get_tgrm_avail_state: idb
>>>> 0x62D3E3E0 bchan 14 service_state 0 call_state 0 false busy 0x0 dsl 2
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC TGRM: update_tgrm_call_status: idb
>>>> 0x62D3E3E0 bchan 14 availability state 1 call state(prev,new) (2,0),
>>>> dsl
>>>> 2
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC TGRM: update_tgrm_call_status:
>>>> Calling TGRM with tgrm_call_isdn_update: idb 0x62D3E3E0 bchan 14 call
>>>> state 0 call type 0 call dir 1
>>>> *Apr 27 00:31:00.219: ISDN EVENTd: cc_clear_free_list freed 0x6339ABFC
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: L3_Go: source = 0x400, event
>>>> = 0x340, call id = 0xE, int id = 0x2
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
>>>> state 19 event 0xAB ces 1
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
>>>> 0x87AB CC_REL_COMP_REQ:U19_RelCompReq(nlcb)
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: L3_TxSarMsg: InfoLen = 5
>>>> octets 0x080287AB5A
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: srl_send_l3_pak:
>>>> source_id = Q.931, dest_id = IUA, prim = DL_DATA_REQ
>>>> priv_len = 4 int_id = 0x62D3E3E0 datasize = 21
>>>> *Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: data =
>>>> 0x62D3E3E0000100000240046A00010806
>>>> *Apr 27 00:31:00.219: 080287AB5A
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC Q931d: L3_state_change: callref
>>>> 0x87AB old RELEASE_REQUEST, new NULL_STATE
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC EVENT: process_rxstate: ces/callid
>>>> 1/0xE calltype 2 CALL_DISC
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC PACKET: process_rxstate:
>>>> 0x4B0201000E0308011008028010
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: process_rxstate: cause=0x10
>>>> (16), cause_present=1
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
>>>> isdn_info=0x62D469E0, call_id=0xE
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
>>>> isdn_info=0x62DD6094, call_id=0xE
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
>>>> isdn_info=0x62D469E0, call_id=0xE
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
>>>> isdn_info=0x62DD6094, call_id=0xE
>>>> *Apr 27 00:31:00.223: EVENT_FROM_ISDN: dchan_idb=0x62D3E3E0,
>>>> call_id=0xE, ces=0x1
>>>> bchan=0xD, event=0x0, cause=0x10
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: process_disc_ack: call id
>>>> 0xE, ces 1, call type VOICE cause 0x10
>>>> *Apr 27 00:31:00.223: ISDN Dc0 SC CC: CCPRI_Go: source id 0x500, call
>>>> id 0x0, event 0x57 (pre-ccb recovery)
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC SERROR: CCPRI_Go: call id 0xE event
>>>> 0x57 No ccb Source->HOST
>>>> *Apr 27 00:31:00.223: ISDN Dc2 SC EVENT: process_rxstate: ces/callid
>>>> 1/0xE calltype 2 CALL_CLEARED
>>>> *Apr 27 00:31:00.227: ISDN Dc2 SC PACKET: process_rxstate:
>>>> 0x570201000E0008028010
>>>> *Apr 27 00:31:00.227: ISDN Dc2 SC EVENTd: process_rxstate: cause=0x10
>>>> (16), cause_present=1
>>>> *Apr 27 00:31:00.227: ISDN Dc2 SC EVENTd: calltrkr_call_cleared:
>>>> isdn_info=0x62D469E0, call_id=0xE
>>>> *Apr 27 00:31:00.227: ISDN Dc2 SC EVENTd: calltrkr_call_cleared:
>>>> isdn_info=0x62DD6094, call_id=0xE
>>>> *Apr 27 00:31:00.227: EVENT_FROM_ISDN: dchan_idb=0x62D3E3E0,
>>>> call_id=0xE, ces=0x1
>>>> bchan=0xD, event=0x0, cause=0x10
>>>> ------------------------------------------------------------------------
>>>>
>>>> _______________________________________________
>>>> cisco-nas mailing list
>>>> cisco-nas at puck.nether.net
>>>> https://puck.nether.net/mailman/listinfo/cisco-nas
>>>>
>>>
>
More information about the cisco-nas
mailing list