<!DOCTYPE html PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN">
<html>
<head>
  <meta content="text/html;charset=ISO-8859-1" http-equiv="Content-Type">
  <title></title>
</head>
<body bgcolor="#ffffff" text="#000000">
Hi Joseph,<br>
<br>
(Boy, it's been awhile since this list has gotten a legitimate
question!)<br>
<br>
>From the debugs, it looks like this call into modem 2/51 simply failed
to train.&nbsp; 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.<br>
<br>
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.<br>
<br>
If so, then I suppose this would be a DSP bug of some sort.&nbsp; Could try
upgrading to the last portware (2.9.5.0) if you're not already running
it.&nbsp; If that doesn't clear it up, then could configure Modem Recovery.<br>
<br>
Configuring MICA Modem Recovery<br>
<a class="moz-txt-link-freetext" href="http://www.cisco.com/en/US/tech/tk801/tk36/technologies_tech_note09186a0080094cac.shtml">http://www.cisco.com/en/US/tech/tk801/tk36/technologies_tech_note09186a0080094cac.shtml</a><br>
<br>
Hth,<br>
<br>
Aaron<br>
<br>
----<br>
<br>
<blockquote cite="mid:081301c906e2$03cf90c0$0a2118d8@engineering01"
 type="cite">
  <meta http-equiv="Content-Type" content="text/html; ">
  <meta content="MSHTML 6.00.6000.16705" name="GENERATOR">
  <style></style>
  <div><font face="Arial" size="2">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.</font></div>
  <div>&nbsp;</div>
  <div><font face="Arial" size="2">Here's the "debug modem csm" output
from a failed call. I've bolded what I assume is the important text.</font></div>
  <div>&nbsp;</div>
  <div><font face="Arial" size="2">as5300_frv_1#<br>
*Apr 27 00:30:32.087: ISDN Dc2 SC Q921d: srl_send_l2_pak:<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; source_id = IUA, dest_id = Q.931, prim = DL_DATA_IND<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; priv_len = 4 int_id = 0x62D3E3E0 datasize = 62<br>
*Apr 27 00:30:32.087: ISDN Dc2 SC Q921d: data =&nbsp;
0x62D3E3E0000000010241040000010000<br>
*Apr 27 00:30:32.091:&nbsp;&nbsp; 080207AB0504038090A21803A1838E1E<br>
*Apr 27 00:30:32.091:&nbsp;&nbsp; 0281836C0CA181353032383135373030<br>
*Apr 27 00:30:32.091:&nbsp;&nbsp; 30700BA134333433393431323334<br>
*Apr 27 00:30:32.091: ISDN Dc2 SC BACKHAUL: L3IF_rx_L2_pak: received
data 0x080207AB0504038090A21803A1838E1E<br>
*Apr 27 00:30:32.091:&nbsp;&nbsp; 0281836C0CA181353032383135373030<br>
*Apr 27 00:30:32.091:&nbsp;&nbsp; 30700BA134333433393431323334<br>
*Apr 27 00:30:32.091: ISDN Dc2 SC Q931d: L3_Go: source = 0x20A, event =
0x241, call id = 0x0, int id = 0x0<br>
*Apr 27 00:30:32.091: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
state 0 event 0x5 ces 1<br>
*Apr 27 00:30:32.091: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
0x87AB SETUP:U0_Setup(nlcb)<br>
*Apr 27 00:30:32.091: ISDN Dc2 SC Q931d: L3_state_change: callref
0x87AB old NULL_STATE, new CALL_PRESENT<br>
*Apr 27 00:30:32.091: ISDN Dc2 SC CC: CCPRI_Go: source id 0x300, call
id 0xE, event 0x341 (pre-ccb recovery)<br>
*Apr 27 00:30:32.091: ISDN Dc2 SC CC: CCPRI_Go: call id 0x2 cref 0xE
event 0x87AB Source-&gt;L3<br>
*Apr 27 00:30:32.095: ISDN Dc2 SC CC: CCPCC_CallIdle: event 0x90 b
channel 0 nfas int_id 0 call_id 0xE<br>
*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<br>
*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<br>
*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<br>
*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<br>
*Apr 27 00:30:32.095: ISDN Dc2 SC EVENT: process_rxstate: ces/callid
1/0xE calltype 2 CALL_INCOMING<br>
*Apr 27 00:30:32.095: ISDN Dc2 SC PACKET: process_rxstate:&nbsp;
0x4C0201000E1A04040000100218042003<br>
*Apr 27 00:30:32.095:&nbsp;&nbsp; 0E02700C020134333433393431323334<br>
*Apr 27 00:30:32.095:&nbsp;&nbsp; 04038090A21803A1838E1E0281836C0C<br>
*Apr 27 00:30:32.095:&nbsp;&nbsp; A18135303238313537303030700BA134<br>
*Apr 27 00:30:32.095:&nbsp;&nbsp; 333433393431323334<br>
*Apr 27 00:30:32.095: ISDN Dc2 SC EVENTd: calltrkr_incoming_call:
call_id=0xE<br>
*Apr 27 00:30:32.095: ISDN Dc2 SC EVENTd: calltrkr_setup_received:
isdn_info=1658087904l, call_id=0xE ANSWER<br>
*Apr 27 00:30:32.099: ISDN Dc2 SC EVENTd: calltrkr_setup_received:
isdn_info=1658675348l, call_id=0xE ANSWER<br>
*Apr 27 00:30:32.099: ISDN Dc2 SC EVENTd: call_incoming: b channel 13,
call type is VOICE ULAW<br>
*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<br>
*Apr 27 00:30:32.099: VDEV_ALLOCATE: 2/51 is allocated<br>
*Apr 27 00:30:32.099: ISDN Dc2 SC EVENTd: isdn_fromdialer: RM returned
call_type 1 resource type 0 response 2<br>
*Apr 27 00:30:32.099: ISDN Dc0 SC CC: CCPRI_Go: source id 0x500, call
id 0x0, event 0x4A (pre-ccb recovery)<br>
*Apr 27 00:30:32.099: ISDN Dc2 SC CC: CCPRI_Go: call_id 0xE cref 0x87AB
event 0x4A Source-&gt;HOST<br>
*Apr 27 00:30:32.099: ISDN Dc2 SC CC: CCPCC_CallOffered: event = 0x4A b
channel 14 nfas int_id 2 call_id 0xE<br>
*Apr 27 00:30:32.099: ISDN Dc2 SC Q931d: L3_Go: source = 0x400, event =
0x340, call id = 0xE, int id = 0x2<br>
*Apr 27 00:30:32.099: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
state 6 event 0x88 ces 1<br>
*Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
0x87AB CC_CALL_PROC_REQ:U6_CallProcReq(nlcb)<br>
*Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: L3_TxSarMsg: InfoLen = 10
octets 0x080287AB021803A9838E<br>
*Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: srl_send_l3_pak:<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; source_id = Q.931, dest_id = IUA, prim = DL_DATA_REQ<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; priv_len = 4 int_id = 0x62D3E3E0 datasize = 26<br>
*Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: data =&nbsp;
0x62D3E3E0000100000240046A00010800<br>
*Apr 27 00:30:32.103:&nbsp;&nbsp; 080287AB021803A9838E<br>
*Apr 27 00:30:32.103: ISDN Dc2 SC Q931d: L3_state_change: callref
0x87AB old CALL_PRESENT, new IN_CALL_PROC<br>
*Apr 27 00:30:32.103: ISDN Dc2 SC EVENT: UserIdle: callid 0xE received
ACCEPT_CALL (0x13)<br>
*Apr 27 00:30:32.103: EVENT_FROM_ISDN: dchan_idb=0x62D3E3E0,
call_id=0xE, ces=0x0<br>
&nbsp;&nbsp; bchan=0xD, event=0x1, cause=0x0<br>
*Apr 27 00:30:32.103:&nbsp; dev in call to isdn : set dnis_collected &amp;
fap_notify<br>
*Apr 27 00:30:32.103: EVENT_FROM_ISDN:(000E): DEV_INCALL at slot 2,
port 51<br>
*Apr 27 00:30:32.103: EVENT_FROM_ISDN: decode:calling oct3 0xA1, called
oct3 0xA1, oct3a 0x0,mask 0x3D<br>
*Apr 27 00:30:32.103: EVENT_FROM_ISDN: csm_call_info:calling oct3 0xA1,
called oct3 0xA1, oct3a 0x0,mask 0x3D<br>
*Apr 27 00:30:32.103: CSM_PROC_IDLE: CSM_EVENT_ISDN_CALL at slot 2,
port 51<br>
*Apr 27 00:30:32.103: Mica Modem(2/51): Configure(0x1 = 0x0)<br>
*Apr 27 00:30:32.103: Mica Modem(2/51): Configure(0x23 = 0x0)<br>
*Apr 27 00:30:32.103: Mica Modem(2/51): Call Setup<br>
*Apr 27 00:30:32.103: csm_connect_pri_vdev: TS allocated at bp_stream
0, bp_Ch 13, vdev_common 0x625BA2C4 2/51<br>
*Apr 27 00:30:32.107: ISDN Dc0 SC CC: CCPRI_Go: source id 0x500, call
id 0x0, event 0x4E (pre-ccb recovery)<br>
*Apr 27 00:30:32.107: ISDN Dc2 SC CC: CCPRI_Go: call_id 0xE cref 0x87AB
event 0x4E Source-&gt;HOST<br>
*Apr 27 00:30:32.107: ISDN Dc2 SC CC: CCPCC_CallRoutingIn: executing
with event = 4E in state = CALL ROUTING_IN<br>
*Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_Go: source = 0x400, event =
0x340, call id = 0xE, int id = 0x2<br>
*Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
state 9 event 0x86 ces 1<br>
*Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
0x87AB CC_ALERTING_REQ:U9_AlertingReq(nlcb)<br>
*Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_TxSarMsg: InfoLen = 5
octets 0x080287AB01<br>
*Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: srl_send_l3_pak:<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; source_id = Q.931, dest_id = IUA, prim = DL_DATA_REQ<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; priv_len = 4 int_id = 0x62D3E3E0 datasize = 21<br>
*Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: data =&nbsp;
0x62D3E3E0000100000240046A00010800<br>
*Apr 27 00:30:32.107:&nbsp;&nbsp; 080287AB01<br>
*Apr 27 00:30:32.107: ISDN Dc2 SC Q931d: L3_state_change: callref
0x87AB old IN_CALL_PROC, new CALL_RECEIVED<br>
*Apr 27 00:30:32.175: Mica Modem(2/51): State Transition to Call Setup<br>
*Apr 27 00:30:32.175: Mica Modem(2/51): Went offhook<br>
*Apr 27 00:30:32.175: CSM_PROC_IC2_RING: CSM_EVENT_MODEM_OFFHOOK at
slot 2, port 51<br>
*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<br>
*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<br>
*Apr 27 00:30:32.175: ISDN Dc0 SC CC: CCPRI_Go: source id 0x500, call
id 0x0, event 0x4A (pre-ccb recovery)<br>
*Apr 27 00:30:32.175: ISDN Dc2 SC CC: CCPRI_Go: call_id 0xE cref 0x87AB
event 0x4A Source-&gt;HOST<br>
*Apr 27 00:30:32.175: ISDN Dc2 SC CC: CCPCC_CallRinging: event 0x4A b
channel 14 nfas int_id 2 call_id 0xE<br>
*Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_Go: source = 0x400, event =
0x340, call id = 0xE, int id = 0x2<br>
*Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
state 7 event 0x81 ces 1<br>
*Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
0x87AB CC_SETUP_RESP:U7_SetupResp(nlcb)<br>
*Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_TxSarMsg: InfoLen = 5
octets 0x080287AB07<br>
*Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: srl_send_l3_pak:<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; source_id = Q.931, dest_id = IUA, prim = DL_DATA_REQ<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; priv_len = 4 int_id = 0x62D3E3E0 datasize = 21<br>
*Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: data =&nbsp;
0x62D3E3E0000100000240046A00010800<br>
*Apr 27 00:30:32.179:&nbsp;&nbsp; 080287AB07<br>
*Apr 27 00:30:32.179: ISDN Dc2 SC LIFd: LIF_StartTimer: timer
(0x630C8090), ticks (4000), event (0x131C)<br>
*Apr 27 00:30:32.179: ISDN Dc2 SC Q931d: L3_state_change: callref
0x87AB old CALL_RECEIVED, new CONNECT_REQUEST<br>
*Apr 27 00:30:32.323: ISDN Dc2 SC Q921d: srl_send_l2_pak:<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; source_id = IUA, dest_id = Q.931, prim = DL_DATA_IND<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; priv_len = 4 int_id = 0x62D3E3E0 datasize = 21<br>
*Apr 27 00:30:32.323: ISDN Dc2 SC Q921d: data =&nbsp;
0x62D3E3E0000000010241040000010000<br>
*Apr 27 00:30:32.323:&nbsp;&nbsp; 080207AB0F<br>
*Apr 27 00:30:32.323: ISDN Dc2 SC BACKHAUL: L3IF_rx_L2_pak: received
data 0x080207AB0F<br>
*Apr 27 00:30:32.323: ISDN Dc2 SC Q931d: L3_Go: source = 0x20A, event =
0x241, call id = 0x0, int id = 0x0<br>
*Apr 27 00:30:32.323: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
state 8 event 0xF ces 1<br>
*Apr 27 00:30:32.323: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
0x87AB CONNECT_ACK:U8_ConnectAck(nlcb)<br>
*Apr 27 00:30:32.323: ISDN Dc2 SC Q931d: L3_state_change: callref
0x87AB old CONNECT_REQUEST, new CALL_ACTIVE<br>
*Apr 27 00:30:32.327: ISDN Dc2 SC CC: CCPRI_Go: source id 0x300, call
id 0xE, event 0x341 (pre-ccb recovery)<br>
*Apr 27 00:30:32.327: ISDN Dc2 SC CC: CCPRI_Go: dispatching event 0x92
call id 0xE cref 0x87AB Source-&gt;L3<br>
*Apr 27 00:30:32.327: ISDN Dc2 SC CC: CCPCC_CallRoutingIn: executing
with event = 92 in state = CALL ROUTING_IN<br>
*Apr 27 00:30:32.327: ISDN Dc2 SC EVENT: process_rxstate: ces/callid
1/0xE calltype 2 CALL_PROGRESS<br>
*Apr 27 00:30:32.327: ISDN Dc2 SC PACKET: process_rxstate:&nbsp;
0x4E0201000E031E0187<br>
*Apr 27 00:30:32.327: ISDN Dc2 SC EVENTd: calltrkr_call_connect:
isdn_info=0x62DD6094, call_id=0xE<br>
*Apr 27 00:30:32.327: ISDN Dc2 SC EVENTd: calltrkr_call_connect:
hndl=14l, cntrl_slot=0, cntrl_port=2, cntrl_channel=2.<br>
*Apr 27 00:30:32.327: ISDN Dc2 SC EVENTd: call_progress: CALL_CONNECTED
b channel 13 call id 0xE<br>
*Apr 27 00:30:32.327: EVENT_FROM_ISDN: dchan_idb=0x62D3E3E0,
call_id=0xE, ces=0x0<br>
&nbsp;&nbsp; bchan=0xD, event=0x4, cause=0x0<br>
*Apr 27 00:30:32.327: EVENT_FROM_ISDN:(000E): DEV_CONNECTED at slot 2,
port 51<br>
*Apr 27 00:30:32.327: CSM_PROC_IC6_WAIT_FOR_CONNECT:
CSM_EVENT_ISDN_CONNECTED at slot 2, port 51<br>
*Apr 27 00:30:32.327: Mica Modem(2/51): Link Initiate<br>
*Apr 27 00:30:32.327: %ISDN-6-CONNECT: Interface Serial2:13 is now
connected to 5028157000 N/A<br>
*Apr 27 00:30:33.419: Mica Modem(2/51): State Transition to Connect<br>
*Apr 27 00:30:33.435: Mica Modem(2/51): State Transition to V8bis
Exchange<br>
*Apr 27 00:30:37.879: Mica Modem(2/51): State Transition to Link<br>
  <strong><font color="#ff0000">*Apr 27 00:30:42.147: Mica Modem(2/51):
State Transition to Trainup<br>
*Apr 27 00:30:50.015: Mica Modem(2/51): State Transition to Terminating<br>
*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<br>
  </font></strong>*Apr 27 00:30:50.615: Mica Modem(2/51): State
Transition to Idle<br>
*Apr 27 00:30:50.615: Mica Modem(2/51): Went onhook<br>
*Apr 27 00:30:50.615: CSM_PROC_IC7_OC6_CONNECTED:
CSM_EVENT_MODEM_ONHOOK at slot 2, port 51<br>
*Apr 27 00:30:50.615: CSM_PROC_IC7_OC6_CONNECTED: cause code to isdn 0x0<br>
*Apr 27 00:30:50.615: CSM(2/51): Enter csm_enter_idle_state<br>
*Apr 27 00:30:50.615: VDEV_DEALLOCATE: slot 2, port 51 is deallocated<br>
*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<br>
*Apr 27 00:30:50.619: ISDN Dc2 SC EVENTd: process_modem_command: call
id 0xE ces may have changed is now 1<br>
*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)<br>
*Apr 27 00:30:50.623: %ISDN-6-DISCONNECT: Interface Serial2:13&nbsp;
disconnected from 5028157000 , call lasted 18 seconds<br>
*Apr 27 00:30:50.623: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
isdn_info=0x62DD6094, call_id=0xE<br>
*Apr 27 00:30:50.623: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
hndl=14l, cause=16, Normal call clearing<br>
*Apr 27 00:30:50.623: ISDN Dc2 SC EVENTd: process_disconnect: Raw
Release Message 0x4BFF01000E040802FF900803809081<br>
*Apr 27 00:31:00.211: ISDN Dc2 SC Q921d: srl_send_l2_pak:<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; source_id = IUA, dest_id = Q.931, prim = DL_DATA_IND<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; priv_len = 4 int_id = 0x62D3E3E0 datasize = 25<br>
*Apr 27 00:31:00.215: ISDN Dc2 SC Q921d: data =&nbsp;
0x62D3E3E0000000010241040000010000<br>
*Apr 27 00:31:00.215:&nbsp;&nbsp; 080207AB4D08028010<br>
*Apr 27 00:31:00.215: ISDN Dc2 SC BACKHAUL: L3IF_rx_L2_pak: received
data 0x080207AB4D08028010<br>
*Apr 27 00:31:00.215: ISDN Dc2 SC Q931d: L3_Go: source = 0x20A, event =
0x241, call id = 0x0, int id = 0x0<br>
*Apr 27 00:31:00.215: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
state 10 event 0x4D ces 1<br>
*Apr 27 00:31:00.215: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
0x87AB RELEASE:Ux_Release(nlcb)<br>
*Apr 27 00:31:00.215: ISDN Dc2 SC Q931d: L3_state_change: callref
0x87AB old CALL_ACTIVE, new RELEASE_REQUEST<br>
*Apr 27 00:31:00.215: ISDN Dc2 SC CC: CCPRI_Go: source id 0x300, call
id 0xE, event 0x341 (pre-ccb recovery)<br>
*Apr 27 00:31:00.215: ISDN Dc2 SC CC: CCPRI_Go: dispatching event 0x97
call id 0xE cref 0x87AB Source-&gt;L3<br>
*Apr 27 00:31:00.215: ISDN Dc2 SC CC: CCPCC_CallConnected: event 0x97 b
channel 14 nfas int_id 2 call_id 0xE<br>
*Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: CC_CHAN_GetUserpri: using
call_id = 0xE<br>
*Apr 27 00:31:00.219: ISDN Dc2 SC CCd: CC_CHAN_ReleaseChanpri: channel
14<br>
*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<br>
*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<br>
*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<br>
*Apr 27 00:31:00.219: ISDN&nbsp; EVENTd: cc_clear_free_list freed 0x6339ABFC<br>
*Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: L3_Go: source = 0x400, event =
0x340, call id = 0xE, int id = 0x2<br>
*Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: L3_Go: call_id 0xE cr 0x87AB
state 19 event 0xAB ces 1<br>
*Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: L3_ProcessEvent: callref =
0x87AB CC_REL_COMP_REQ:U19_RelCompReq(nlcb)<br>
*Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: L3_TxSarMsg: InfoLen = 5
octets 0x080287AB5A<br>
*Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: srl_send_l3_pak:<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; source_id = Q.931, dest_id = IUA, prim = DL_DATA_REQ<br>
&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; priv_len = 4 int_id = 0x62D3E3E0 datasize = 21<br>
*Apr 27 00:31:00.219: ISDN Dc2 SC Q931d: data =&nbsp;
0x62D3E3E0000100000240046A00010806<br>
*Apr 27 00:31:00.219:&nbsp;&nbsp; 080287AB5A<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC Q931d: L3_state_change: callref
0x87AB old RELEASE_REQUEST, new NULL_STATE<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC EVENT: process_rxstate: ces/callid
1/0xE calltype 2 CALL_DISC<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC PACKET: process_rxstate:&nbsp;
0x4B0201000E0308011008028010<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: process_rxstate: cause=0x10
(16), cause_present=1<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
isdn_info=0x62D469E0, call_id=0xE<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
isdn_info=0x62DD6094, call_id=0xE<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
isdn_info=0x62D469E0, call_id=0xE<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: calltrkr_call_disconnected:
isdn_info=0x62DD6094, call_id=0xE<br>
*Apr 27 00:31:00.223: EVENT_FROM_ISDN: dchan_idb=0x62D3E3E0,
call_id=0xE, ces=0x1<br>
&nbsp;&nbsp; bchan=0xD, event=0x0, cause=0x10<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC EVENTd: process_disc_ack: call id
0xE, ces 1, call type VOICE cause 0x10<br>
*Apr 27 00:31:00.223: ISDN Dc0 SC CC: CCPRI_Go: source id 0x500, call
id 0x0, event 0x57 (pre-ccb recovery)<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC SERROR: CCPRI_Go: call id 0xE event
0x57 No ccb Source-&gt;HOST<br>
*Apr 27 00:31:00.223: ISDN Dc2 SC EVENT: process_rxstate: ces/callid
1/0xE calltype 2 CALL_CLEARED<br>
*Apr 27 00:31:00.227: ISDN Dc2 SC PACKET: process_rxstate:&nbsp;
0x570201000E0008028010<br>
*Apr 27 00:31:00.227: ISDN Dc2 SC EVENTd: process_rxstate: cause=0x10
(16), cause_present=1<br>
*Apr 27 00:31:00.227: ISDN Dc2 SC EVENTd: calltrkr_call_cleared:
isdn_info=0x62D469E0, call_id=0xE<br>
*Apr 27 00:31:00.227: ISDN Dc2 SC EVENTd: calltrkr_call_cleared:
isdn_info=0x62DD6094, call_id=0xE<br>
*Apr 27 00:31:00.227: EVENT_FROM_ISDN: dchan_idb=0x62D3E3E0,
call_id=0xE, ces=0x1<br>
&nbsp;&nbsp; bchan=0xD, event=0x0, cause=0x10<br>
  </font></div>
  <pre wrap="">
<hr size="4" width="90%">
_______________________________________________
cisco-nas mailing list
<a class="moz-txt-link-abbreviated" href="mailto:cisco-nas@puck.nether.net">cisco-nas@puck.nether.net</a>
<a class="moz-txt-link-freetext" href="https://puck.nether.net/mailman/listinfo/cisco-nas">https://puck.nether.net/mailman/listinfo/cisco-nas</a></pre>
</blockquote>
<br>
</body>
</html>