<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.0 Transitional//EN">
<HTML><HEAD>
<META http-equiv=Content-Type content="text/html; charset=iso-8859-1">
<META content="MSHTML 6.00.6000.16705" name=GENERATOR>
<STYLE></STYLE>
</HEAD>
<BODY>
<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><FONT face=Arial size=2></FONT>&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><FONT face=Arial size=2></FONT>&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></BODY></HTML>