[cisco-voip] Fwd: PRI E1 outgoing call failer

[kh MOh]

 HI all
 
 i'm recently planing to migrate from my 4 port
 AS5350
 to another 8 Port AS5350 every thing work fin but
 when
 i made an outgoing call it gives a bussy out signal,
 please advice.Attached debug output for q931
 
 
 		
 __________________________________ 
 25 10:06:11.601: CT: begin periodic check for
> too-old history table nodes
> Prepaid2-Khart#
> Prepaid2-Khart#
> Prepaid2-Khart#
> Prepaid2-Khart#
> Prepaid2-Khart#
> Prepaid2-Khart#
> Prepaid2-Khart#
> Prepaid2-Khart#
> Prepaid2-Khart#
> Dec 25 10:08:06.989: %TRUNK_CLOCK-6-TRUNK: Switching
> to the trunk clock on slot 3 port 1 priority 206
> Prepaid2-Khart#
> Dec 25 10:08:17.621: %CONTROLLER-5-UPDOWN:
> Controller E1 3/1, changed state to up
> Dec 25 10:08:17.621: ISDN Se3/1:15: Outgoing call id
> = 0x8006, dsl 1
> Dec 25 10:08:17.621: %CSM-5-PRI: add PRI at slot 3,
> unit 1, channel 15 with index 0
> Dec 25 10:08:17.621: isdn_pri_t1_state_change(): dsl
> 1 newstate = 4
> Dec 25 10:08:17.621: ISDN Se3/1:15:
> Serv_set_intserv, state 0 locale 1
> Dec 25 10:08:17.621: ISDN Recvd L1 prim 1 dsl 1
> state 3 ctrl_state 4
> Dec 25 10:08:17.621: ISDN Se3/1:15: L1 is IF_ACTIVE
> Dec 25 
> Prepaid2-Khart#10:08:17.621:
> isdn_pri_t1_state_change(): dsl 1 newstate = 4
> Dec 25 10:08:17.621: ISDN Se3/1:15:
> Serv_set_intserv, state 0 locale 1
> Dec 25 10:08:17.621: Pri_Activate_L2: dsl Se3/1:15
> states = 4, 4
> Dec 25 10:08:17.621: ISDN Recvd L1 prim 1 dsl 1
> state 1 ctrl_state 4
> Dec 25 10:08:17.621: ISDN Se3/1:15: got
> ISDN_PH_ACT_IND on an active interface?
> Dec 25 10:08:17.625: %ISDN-6-LAYER2UP: Layer 2 for
> Interface Se3/1:15, TEI 0 changed to up
> Dec 25 10:08:17.625: isdn_pri_t1_state_change(): dsl
> 1 newstate = 4
Prepaid2-Khart#
> Dec 25 10:08:17.625: ISDN Se3/1:15:
> Serv_set_intserv, state 0 locale 1
> Dec 25 10:08:18.589: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DC0
> Dec 25 10:08:18.589:         Sending Complete
> Dec 25 10:08:18.589:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:08:18.589:         Channel ID i = 0xA98381
> Dec 25 10:08:18.589:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:08:18.589:         Calling Party Number i
> = 0x2183, '185630452', Plan:ISDN, Type:National
> Dec 25 10:
> Prepaid2-Khart#08:18.589:         Called Party
> Number i = 0xC1, '180980', Plan:ISDN,
> Type:Subscriber(local)
> Dec 25 10:08:18.589: ISDN Se3/1:15: Incoming call id
> = 0x04BC, dsl 1
> Dec 25 10:08:18.589: ISDN Se3/1:15: NegotiateBchan:
> bchan 1 intid 0 serv_st 0 chan_st 0 callid 0x0000 ev
> 0x90 n/w? 0
> Dec 25 10:08:18.589: Negotiated int_id 0 bchan 0
> cr=0x9DC0 callid=0x04BC lo_chan 1 final int_id/bchan
> 0/1 cause 0x0
> Dec 25 10:08:18.589: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BC CALL_INCOMING
> Dec 25 10:08:18.589: ISDN Se3/1
> Prepaid2-Khart#:15: CALL_INCOMING dsl 1 bchan 0
> Dec 25 10:08:18.589: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 0
> Dec 25 10:08:18.589: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:08:18.589: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:08:18.593: ISDN Se3/1:15: Event:  Received
> a VOICE call from 185630452 on B0 at 64 Kb/s Tone
> Value 0
> Dec 25 10:08:18.593: CT: setup: issued hndl=1156 for
> call_cat=2(Modem), called=180980, calling=185630452
> Dec 25
> Prepaid2-Khart# 10:08:18.593: CT: Successfully
> inserted active call-record entry hndl=1156 into
> table.
> Dec 25 10:08:18.593: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:18.593: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1156)
> Dec 25 10:08:18.593: CT: get found active entry
> (hndl=1156); copied & returned
> Dec 25 10:08:18.593: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:18.593: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1156)
> Dec 25 10:08:18.593: CT: g
> Prepaid2-Khart#et found active entry (hndl=1156);
> copied & returned
> Dec 25 10:08:18.593: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:18.593: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1156)
> Dec 25 10:08:18.593: CT: get found active entry
> (hndl=1156); copied & returned
> Dec 25 10:08:18.593: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:18.593: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1156)
> Dec 25 10:08:18.593: CT: get found active entry
> (hndl=1156); 
> Prepaid2-Khart#copied & returned
> Dec 25 10:08:18.597: CT: ds0 allocated: hndl=1156,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:08:18.597: CT: ds0 allocated: hndl=1156,
>   idb=0x6392AE14(Se3/1:0), call_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/0,
> Dec 25 10:08:18.597: CT: set AAA session id:
>   hndl=1156, sess_id=1159
> Dec 25 10:08:18.597: CT: set AAA session id:
>   hndl=1156, sess_id=1159
> Dec 25 10:08:18.609: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4BC,
> Prepaid2-Khart# bchan 0, call type VOICE
> Dec 25 10:08:18.609: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4BC, bchan
> 0, call type VOICE
> Dec 25 10:08:18.609: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DC0
> Dec 25 10:08:18.609:         Channel ID i = 0xA98381
> Dec 25 10:08:18.609: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DC0
> Dec 25 10:08:18.641: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DC0
> Dec 25 10:08:18.645: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BC CALL_PROGRESS
> Dec 2
> Prepaid2-Khart#5 10:08:18.645: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:08:18.645: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4BC, bchan 0, dsl 1
> Dec 25 10:08:18.645: CT: call connected: hndl=1156,
>   idb=0x6392AE14(Se3/1:0), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/0
> Dec 25 10:08:19.621: ISDN Se3/1:15 EVENT:
> isdn_sw_cstate: State = 4, Old State = 0
> Dec 25 10:08:19.621: Pri_Activate_L2: dsl Se3/1:15
> states = 4, 4
> Dec 25 10:08:25.053: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref
> Prepaid2-Khart# = 0x1DC1
> Dec 25 10:08:25.053:         Sending Complete
> Dec 25 10:08:25.053:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:08:25.053:         Channel ID i = 0xA98382
> Dec 25 10:08:25.053:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:08:25.053:         Calling Party Number i
> = 0x2183, '187525973', Plan:ISDN, Type:National
> Dec 25 10:08:25.053:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:08:25.053: ISDN Se3/1:15: Incoming
> Prepaid2-Khart# call id = 0x04BD, dsl 1
> Dec 25 10:08:25.053: ISDN Se3/1:15: NegotiateBchan:
> bchan 2 intid 0 serv_st 0 chan_st 0 callid 0x0000 ev
> 0x90 n/w? 0
> Dec 25 10:08:25.053: Negotiated int_id 0 bchan 0
> cr=0x9DC1 callid=0x04BD lo_chan 2 final int_id/bchan
> 0/2 cause 0x0
> Dec 25 10:08:25.053: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BD CALL_INCOMING
> Dec 25 10:08:25.053: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 1
> Dec 25 10:08:25.057: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 1
> Dec 25 10:08:25.0
> Prepaid2-Khart#57: ISDN Se3/1:15: Event: Checking if
> call is not ISDN end to end
> Dec 25 10:08:25.057: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:08:25.057: ISDN Se3/1:15: Event:  Received
> a VOICE call from 187525973 on B1 at 64 Kb/s Tone
> Value 0
> Dec 25 10:08:25.057: CT: setup: issued hndl=1157 for
> call_cat=2(Modem), called=180980, calling=187525973
> Dec 25 10:08:25.057: CT: Successfully inserted
> active call-record entry hndl=1157 into table.
> Dec 25 10:08:25.057: CT: indeterminate service ty
> Prepaid2-Khart#pe (1); returning tx(0)/rx(0)
> Dec 25 10:08:25.057: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1157)
> Dec 25 10:08:25.057: CT: get found active entry
> (hndl=1157); copied & returned
> Dec 25 10:08:25.057: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:25.057: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1157)
> Dec 25 10:08:25.057: CT: get found active entry
> (hndl=1157); copied & returned
> Dec 25 10:08:25.057: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 
> Prepaid2-Khart#25 10:08:25.057: CT: calc'd
> tx(0)/rx(0) deltas for actv call (hndl=1157)
> Dec 25 10:08:25.057: CT: get found active entry
> (hndl=1157); copied & returned
> Dec 25 10:08:25.057: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:25.057: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1157)
> Dec 25 10:08:25.057: CT: get found active entry
> (hndl=1157); copied & returned
> Dec 25 10:08:25.061: CT: ds0 allocated: hndl=1157,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/cha
> Prepaid2-Khart#n=3/1/1/15,
> Dec 25 10:08:25.061: CT: ds0 allocated: hndl=1157,
>   idb=0x63933760(Se3/1:1), call_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/1,
> Dec 25 10:08:25.061: CT: set AAA session id:
>   hndl=1157, sess_id=1160
> Dec 25 10:08:25.061: CT: set AAA session id:
>   hndl=1157, sess_id=1160
> Dec 25 10:08:25.073: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4BD, bchan
> 1, call type VOICE
> Dec 25 10:08:25.073: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4BD, bchan
> 1, call type VOICE
> Dec 
> Prepaid2-Khart#25 10:08:25.073: ISDN Se3/1:15: TX ->
>  CALL_PROC pd = 8  callref = 0x9DC1
> Dec 25 10:08:25.073:         Channel ID i = 0xA98382
> Dec 25 10:08:25.073: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DC1
> Dec 25 10:08:25.105: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DC1
> Dec 25 10:08:25.109: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BD CALL_PROGRESS
> Dec 25 10:08:25.109: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:08:25.109: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4BD,
> Prepaid2-Khart# bchan 1, dsl 1
> Dec 25 10:08:25.109: CT: call connected: hndl=1157,
>   idb=0x63933760(Se3/1:1), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/1
> Dec 25 10:08:28.513: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DC2
> Dec 25 10:08:28.513:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:08:28.513:         Channel ID i = 0xA98383
> Dec 25 10:08:28.513:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:08:28.513:         Calling Party Number i
> = 0x2183, '187595458'
> Prepaid2-Khart#, Plan:ISDN, Type:National
> Dec 25 10:08:28.513:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:08:28.513: ISDN Se3/1:15: Incoming call id
> = 0x04BE, dsl 1
> Dec 25 10:08:28.513: ISDN Se3/1:15: NegotiateBchan:
> bchan 3 intid 0 serv_st 0 chan_st 0 callid 0x0000 ev
> 0x90 n/w? 0
> Dec 25 10:08:28.513: Negotiated int_id 0 bchan 0
> cr=0x9DC2 callid=0x04BE lo_chan 3 final int_id/bchan
> 0/3 cause 0x0
> Dec 25 10:08:28.513: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BE CALL_INC
> Prepaid2-Khart#OMING
> Dec 25 10:08:28.513: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 2
> Dec 25 10:08:28.517: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 2
> Dec 25 10:08:28.517: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:08:28.517: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:08:28.517: ISDN Se3/1:15: Event:  Received
> a VOICE call from 187595458 on B2 at 64 Kb/s Tone
> Value 0
> Dec 25 10:08:28.517: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 
> Prepaid2-Khart#0x4BE, bchan 2, call type VOICE
> Dec 25 10:08:28.517: ISDN Se3/1:15: TX ->  SETUP_ACK
> pd = 8  callref = 0x9DC2
> Dec 25 10:08:28.517:         Channel ID i = 0xA98383
> Dec 25 10:08:28.517: CT: setup: issued hndl=1158 for
> call_cat=2(Modem), called=180980, calling=187595458
> Dec 25 10:08:28.517: CT: Successfully inserted
> active call-record entry hndl=1158 into table.
> Dec 25 10:08:28.517: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:28.521: CT: calc'd tx(0)/rx(0) deltas
> for actv call
> Prepaid2-Khart# (hndl=1158)
> Dec 25 10:08:28.521: CT: get found active entry
> (hndl=1158); copied & returned
> Dec 25 10:08:28.521: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:28.521: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1158)
> Dec 25 10:08:28.521: CT: get found active entry
> (hndl=1158); copied & returned
> Dec 25 10:08:28.521: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:28.521: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1158)
> Dec 25 10:08:28.521: 
> Prepaid2-Khart#CT: get found active entry
> (hndl=1158); copied & returned
> Dec 25 10:08:28.521: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:28.521: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1158)
> Dec 25 10:08:28.521: CT: get found active entry
> (hndl=1158); copied & returned
> Dec 25 10:08:28.521: CT: ds0 allocated: hndl=1158,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:08:28.521: CT: ds0 allocated: hndl=1158,
>   idb=0x6393C1C8(Se3/1:2), cal
> Prepaid2-Khart#l_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/2,
> Dec 25 10:08:28.521: CT: set AAA session id:
>   hndl=1158, sess_id=1161
> Dec 25 10:08:28.521: CT: set AAA session id:
>   hndl=1158, sess_id=1161
> Dec 25 10:08:28.533: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4BE, bchan
> 2, call type VOICE
> Dec 25 10:08:28.533: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4BE, bchan
> 2, call type VOICE
> Dec 25 10:08:28.537: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DC2
> Dec 25 10:08:28.537:
> Prepaid2-Khart# ISDN Se3/1:15: TX ->  CONNECT pd = 8
>  callref = 0x9DC2
> Dec 25 10:08:28.569: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DC2
> Dec 25 10:08:28.573: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BE CALL_PROGRESS
> Dec 25 10:08:28.573: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:08:28.573: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4BE, bchan 2, dsl 1
> Dec 25 10:08:28.573: CT: call connected: hndl=1158,
>   idb=0x6393C1C8(Se3/1:2), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan
> Prepaid2-Khart#=3/1/1/2
> Dec 25 10:08:29.773: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DC3
> Dec 25 10:08:29.773:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:08:29.773:         Channel ID i = 0xA98384
> Dec 25 10:08:29.773:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:08:29.773:         Calling Party Number i
> = 0x2183, '183795412', Plan:ISDN, Type:National
> Dec 25 10:08:29.773:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:08:29.7
> Prepaid2-Khart#73: ISDN Se3/1:15: Incoming call id =
> 0x04BF, dsl 1
> Dec 25 10:08:29.773: ISDN Se3/1:15: NegotiateBchan:
> bchan 4 intid 0 serv_st 0 chan_st 0 callid 0x0000 ev
> 0x90 n/w? 0
> Dec 25 10:08:29.773: Negotiated int_id 0 bchan 0
> cr=0x9DC3 callid=0x04BF lo_chan 4 final int_id/bchan
> 0/4 cause 0x0
> Dec 25 10:08:29.773: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BF CALL_INCOMING
> Dec 25 10:08:29.773: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 3
> Dec 25 10:08:29.777: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, b
> Prepaid2-Khart#chan = 3
> Dec 25 10:08:29.777: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:08:29.777: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:08:29.777: ISDN Se3/1:15: Event:  Received
> a VOICE call from 183795412 on B3 at 64 Kb/s Tone
> Value 0
> Dec 25 10:08:29.777: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4BF, bchan
> 3, call type VOICE
> Dec 25 10:08:29.777: ISDN Se3/1:15: TX ->  SETUP_ACK
> pd = 8  callref = 0x9DC3
> Dec 25 10:08:29.777:         Channel 
> Prepaid2-Khart#ID i = 0xA98384
> Dec 25 10:08:29.777: CT: setup: issued hndl=1159 for
> call_cat=2(Modem), called=180980, calling=183795412
> Dec 25 10:08:29.777: CT: Successfully inserted
> active call-record entry hndl=1159 into table.
> Dec 25 10:08:29.777: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:29.777: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1159)
> Dec 25 10:08:29.777: CT: get found active entry
> (hndl=1159); copied & returned
> Dec 25 10:08:29.777: CT: indeterminate service type
> (1)
> Prepaid2-Khart#; returning tx(0)/rx(0)
> Dec 25 10:08:29.777: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1159)
> Dec 25 10:08:29.777: CT: get found active entry
> (hndl=1159); copied & returned
> Dec 25 10:08:29.781: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:29.781: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1159)
> Dec 25 10:08:29.781: CT: get found active entry
> (hndl=1159); copied & returned
> Dec 25 10:08:29.781: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:
> Prepaid2-Khart#08:29.781: CT: calc'd tx(0)/rx(0)
> deltas for actv call (hndl=1159)
> Dec 25 10:08:29.781: CT: get found active entry
> (hndl=1159); copied & returned
> Dec 25 10:08:29.781: CT: ds0 allocated: hndl=1159,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:08:29.781: CT: ds0 allocated: hndl=1159,
>   idb=0x63944C30(Se3/1:3), call_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/3,
> Dec 25 10:08:29.781: CT: set AAA session id:
>   hndl=1159, sess_id=1162
> Dec 25 10:08:29.781: CT:
> Prepaid2-Khart# set AAA session id:
>   hndl=1159, sess_id=1162
> Dec 25 10:08:29.793: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4BF, bchan
> 3, call type VOICE
> Dec 25 10:08:29.793: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4BF, bchan
> 3, call type VOICE
> Dec 25 10:08:29.793: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DC3
> Dec 25 10:08:29.797: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DC3
> Dec 25 10:08:29.829: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DC3
> Dec 25 10:0
> Prepaid2-Khart#8:29.833: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BF CALL_PROGRESS
> Dec 25 10:08:29.833: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:08:29.833: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4BF, bchan 3, dsl 1
> Dec 25 10:08:29.833: CT: call connected: hndl=1159,
>   idb=0x63944C30(Se3/1:3), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/3
> Dec 25 10:08:30.821: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DC4
> Dec 25 10:08:30.821:         Sending Complete
> Dec 25 10:08:30.8
> Prepaid2-Khart#21:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:08:30.821:         Channel ID i = 0xA98385
> Dec 25 10:08:30.821:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:08:30.821:         Calling Party Number i
> = 0x2183, '187530388', Plan:ISDN, Type:National
> Dec 25 10:08:30.821:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:08:30.821: ISDN Se3/1:15: Incoming call id
> = 0x04C0, dsl 1
> Dec 25 10:08:30.825: ISDN Se3/1:15: NegotiateBcha
> Prepaid2-Khart#n: bchan 5 intid 0 serv_st 0 chan_st
> 0 callid 0x0000 ev 0x90 n/w? 0
> Dec 25 10:08:30.825: Negotiated int_id 0 bchan 0
> cr=0x9DC4 callid=0x04C0 lo_chan 5 final int_id/bchan
> 0/5 cause 0x0
> Dec 25 10:08:30.825: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C0 CALL_INCOMING
> Dec 25 10:08:30.825: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 4
> Dec 25 10:08:30.825: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 4
> Dec 25 10:08:30.825: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 1
> Prepaid2-Khart#0:08:30.825: ISDN Se3/1:15: Event:
> Adjusting speed to user selection 64K
> Dec 25 10:08:30.825: ISDN Se3/1:15: Event:  Received
> a VOICE call from 187530388 on B4 at 64 Kb/s Tone
> Value 0
> Dec 25 10:08:30.825: CT: setup: issued hndl=1160 for
> call_cat=2(Modem), called=180980, calling=187530388
> Dec 25 10:08:30.825: CT: Successfully inserted
> active call-record entry hndl=1160 into table.
> Dec 25 10:08:30.825: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:30.825: CT: calc'd tx(0)/rx(0) 
> Prepaid2-Khart#deltas for actv call (hndl=1160)
> Dec 25 10:08:30.825: CT: get found active entry
> (hndl=1160); copied & returned
> Dec 25 10:08:30.825: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:30.825: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1160)
> Dec 25 10:08:30.825: CT: get found active entry
> (hndl=1160); copied & returned
> Dec 25 10:08:30.829: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:30.829: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1160)
> D
> Prepaid2-Khart#ec 25 10:08:30.829: CT: get found
> active entry (hndl=1160); copied & returned
> Dec 25 10:08:30.829: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:30.829: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1160)
> Dec 25 10:08:30.829: CT: get found active entry
> (hndl=1160); copied & returned
> Dec 25 10:08:30.829: CT: ds0 allocated: hndl=1160,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:08:30.829: CT: ds0 allocated: hndl=1160,
>   idb=0x63
> Prepaid2-Khart#94D698(Se3/1:4), call_cat=1(ISDN
> Sync),
>   slot/port/ds1/chan=3/1/1/4,
> Dec 25 10:08:30.829: CT: set AAA session id:
>   hndl=1160, sess_id=1163
> Dec 25 10:08:30.829: CT: set AAA session id:
>   hndl=1160, sess_id=1163
> Dec 25 10:08:30.841: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4C0, bchan
> 4, call type VOICE
> Dec 25 10:08:30.841: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C0, bchan
> 4, call type VOICE
> Dec 25 10:08:30.841: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DC4
> 
> Prepaid2-Khart#Dec 25 10:08:30.845:         Channel
> ID i = 0xA98385
> Dec 25 10:08:30.845: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DC4
> Dec 25 10:08:30.877: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DC4
> Dec 25 10:08:30.881: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C0 CALL_PROGRESS
> Dec 25 10:08:30.881: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:08:30.881: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4C0, bchan 4, dsl 1
> Dec 25 10:08:30.881: CT: call connected: hndl=1160,
>   idb
> Prepaid2-Khart#=0x6394D698(Se3/1:4),
> call_cat=2(Modem), rate=64000,
>   slot/port/ds1/chan=3/1/1/4
> Dec 25 10:08:35.905: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DC5
> Dec 25 10:08:35.905:         Sending Complete
> Dec 25 10:08:35.905:         Bearer Capability i =
> 0x8090A3
> Dec 25 10:08:35.905:         Channel ID i = 0xA98386
> Dec 25 10:08:35.905:         Calling Party Number i
> = 0x2183, '912720756', Plan:ISDN, Type:National
> Dec 25 10:08:35.905:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subs
> Prepaid2-Khart#criber(local)
> Dec 25 10:08:35.905: ISDN Se3/1:15: Incoming call id
> = 0x04C1, dsl 1
> Dec 25 10:08:35.905: ISDN Se3/1:15: NegotiateBchan:
> bchan 6 intid 0 serv_st 0 chan_st 0 callid 0x0000 ev
> 0x90 n/w? 0
> Dec 25 10:08:35.905: Negotiated int_id 0 bchan 0
> cr=0x9DC5 callid=0x04C1 lo_chan 6 final int_id/bchan
> 0/6 cause 0x0
> Dec 25 10:08:35.905: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C1 CALL_INCOMING
> Dec 25 10:08:35.905: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 5
> Dec 25 10:08:35.905: ISDN Se3/1:15: CALL_INCO
> Prepaid2-Khart#MING: call type is VOICE ALAW, bchan
> = 5
> Dec 25 10:08:35.905: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:08:35.905: ISDN Se3/1:15: Event:  Received
> a VOICE call from 912720756 on B5 at 64 Kb/s Tone
> Value 0
> Dec 25 10:08:35.909: CT: setup: issued hndl=1161 for
> call_cat=2(Modem), called=180980, calling=912720756
> Dec 25 10:08:35.909: CT: Successfully inserted
> active call-record entry hndl=1161 into table.
> Dec 25 10:08:35.909: CT: indeterminate service type
> (1); returning tx(0)
> Prepaid2-Khart#/rx(0)
> Dec 25 10:08:35.909: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1161)
> Dec 25 10:08:35.909: CT: get found active entry
> (hndl=1161); copied & returned
> Dec 25 10:08:35.909: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:35.909: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1161)
> Dec 25 10:08:35.909: CT: get found active entry
> (hndl=1161); copied & returned
> Dec 25 10:08:35.909: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:35.909: CT: ca
> Prepaid2-Khart#lc'd tx(0)/rx(0) deltas for actv call
> (hndl=1161)
> Dec 25 10:08:35.909: CT: get found active entry
> (hndl=1161); copied & returned
> Dec 25 10:08:35.909: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:35.909: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1161)
> Dec 25 10:08:35.909: CT: get found active entry
> (hndl=1161); copied & returned
> Dec 25 10:08:35.909: CT: ds0 allocated: hndl=1161,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:
> Prepaid2-Khart#08:35.909: CT: ds0 allocated:
> hndl=1161,
>   idb=0x63956100(Se3/1:5), call_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/5,
> Dec 25 10:08:35.913: CT: set AAA session id:
>   hndl=1161, sess_id=1164
> Dec 25 10:08:35.913: CT: set AAA session id:
>   hndl=1161, sess_id=1164
> Dec 25 10:08:35.925: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4C1, bchan
> 5, call type VOICE
> Dec 25 10:08:35.925: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C1, bchan
> 5, call type VOICE
> Dec 25 10:08:35.925: ISDN S
> Prepaid2-Khart#e3/1:15: TX ->  CALL_PROC pd = 8 
> callref = 0x9DC5
> Dec 25 10:08:35.925:         Channel ID i = 0xA98386
> Dec 25 10:08:35.925: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DC5
> Dec 25 10:08:35.961: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DC5
> Dec 25 10:08:35.965: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C1 CALL_PROGRESS
> Dec 25 10:08:35.965: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:08:35.965: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4C1, bchan 5, dsl 1
> Dec 25
> Prepaid2-Khart# 10:08:35.965: CT: call connected:
> hndl=1161,
>   idb=0x63956100(Se3/1:5), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/5
> Dec 25 10:08:41.601: CT: begin periodic check for
> too-old history table nodes
> Dec 25 10:08:48.705: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DC6
> Dec 25 10:08:48.705:         Sending Complete
> Dec 25 10:08:48.705:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:08:48.705:         Channel ID i = 0xA98387
> Dec 25 10:08:48.705:         Progress Ind i = 0x8283
> - Orig
> Prepaid2-Khart#ination address is non-ISDN 
> Dec 25 10:08:48.705:         Calling Party Number i
> = 0x2183, '185371309', Plan:ISDN, Type:National
> Dec 25 10:08:48.705:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:08:48.705: ISDN Se3/1:15: Incoming call id
> = 0x04C2, dsl 1
> Dec 25 10:08:48.705: ISDN Se3/1:15: NegotiateBchan:
> bchan 7 intid 0 serv_st 0 chan_st 0 callid 0x0000 ev
> 0x90 n/w? 0
> Dec 25 10:08:48.705: Negotiated int_id 0 bchan 0
> cr=0x9DC6 callid=0x04C2 lo_chan 7 final
> Prepaid2-Khart# int_id/bchan 0/7 cause 0x0
> Dec 25 10:08:48.705: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C2 CALL_INCOMING
> Dec 25 10:08:48.705: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 6
> Dec 25 10:08:48.709: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 6
> Dec 25 10:08:48.709: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:08:48.709: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:08:48.709: ISDN Se3/1:15: Event:  Received
> a VOICE call from 185371309 on
> Prepaid2-Khart# B6 at 64 Kb/s Tone Value 0
> Dec 25 10:08:48.709: CT: setup: issued hndl=1162 for
> call_cat=2(Modem), called=180980, calling=185371309
> Dec 25 10:08:48.709: CT: Successfully inserted
> active call-record entry hndl=1162 into table.
> Dec 25 10:08:48.709: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:48.709: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1162)
> Dec 25 10:08:48.709: CT: get found active entry
> (hndl=1162); copied & returned
> Dec 25 10:08:48.709: CT: indeterminate serv
> Prepaid2-Khart#ice type (1); returning tx(0)/rx(0)
> Dec 25 10:08:48.709: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1162)
> Dec 25 10:08:48.709: CT: get found active entry
> (hndl=1162); copied & returned
> Dec 25 10:08:48.709: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:08:48.713: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1162)
> Dec 25 10:08:48.713: CT: get found active entry
> (hndl=1162); copied & returned
> Dec 25 10:08:48.713: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Prepaid2-Khart#
> Dec 25 10:08:48.713: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1162)
> Dec 25 10:08:48.713: CT: get found active entry
> (hndl=1162); copied & returned
> Dec 25 10:08:48.713: CT: ds0 allocated: hndl=1162,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:08:48.713: CT: ds0 allocated: hndl=1162,
>   idb=0x6395EB68(Se3/1:6), call_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/6,
> Dec 25 10:08:48.713: CT: set AAA session id:
>   hndl=1162, sess_id=1165
> Dec 25 10:08
> Prepaid2-Khart#:48.713: CT: set AAA session id:
>   hndl=1162, sess_id=1165
> Dec 25 10:08:48.725: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4C2, bchan
> 6, call type VOICE
> Dec 25 10:08:48.725: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C2, bchan
> 6, call type VOICE
> Dec 25 10:08:48.729: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DC6
> Dec 25 10:08:48.729:         Channel ID i = 0xA98387
> Dec 25 10:08:48.729: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DC6
> Dec 25 10:08:48.761: ISDN 
> Prepaid2-Khart#Se3/1:15: RX <-  CONNECT_ACK pd = 8 
> callref = 0x1DC6
> Dec 25 10:08:48.765: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C2 CALL_PROGRESS
> Dec 25 10:08:48.765: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:08:48.765: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4C2, bchan 6, dsl 1
> Dec 25 10:08:48.765: CT: call connected: hndl=1162,
>   idb=0x6395EB68(Se3/1:6), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/6
> Dec 25 10:09:03.469: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DC7
> Prepaid2-Khart#
> Dec 25 10:09:03.469:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:09:03.469:         Channel ID i = 0xA98388
> Dec 25 10:09:03.469:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:09:03.469:         Calling Party Number i
> = 0x2183, '183782702', Plan:ISDN, Type:National
> Dec 25 10:09:03.469:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:09:03.469: ISDN Se3/1:15: Incoming call id
> = 0x04C3, dsl 1
> Dec 25 10:09:03.469: ISDN Se3/
> Prepaid2-Khart#1:15: NegotiateBchan: bchan 8 intid 0
> serv_st 0 chan_st 0 callid 0x0000 ev 0x90 n/w? 0
> Dec 25 10:09:03.469: Negotiated int_id 0 bchan 0
> cr=0x9DC7 callid=0x04C3 lo_chan 8 final int_id/bchan
> 0/8 cause 0x0
> Dec 25 10:09:03.469: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C3 CALL_INCOMING
> Dec 25 10:09:03.473: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 7
> Dec 25 10:09:03.473: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 7
> Dec 25 10:09:03.473: ISDN Se3/1:15: Event: Checking
> if call is not ISDN e
> Prepaid2-Khart#nd to end
> Dec 25 10:09:03.473: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:09:03.473: ISDN Se3/1:15: Event:  Received
> a VOICE call from 183782702 on B7 at 64 Kb/s Tone
> Value 0
> Dec 25 10:09:03.473: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C3, bchan
> 7, call type VOICE
> Dec 25 10:09:03.473: ISDN Se3/1:15: TX ->  SETUP_ACK
> pd = 8  callref = 0x9DC7
> Dec 25 10:09:03.473:         Channel ID i = 0xA98388
> Dec 25 10:09:03.477: CT: setup: issued hndl=1163 for
> call_cat=2(Mo
> Prepaid2-Khart#dem), called=180980,
> calling=183782702
> Dec 25 10:09:03.477: CT: Successfully inserted
> active call-record entry hndl=1163 into table.
> Dec 25 10:09:03.477: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:03.477: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1163)
> Dec 25 10:09:03.477: CT: get found active entry
> (hndl=1163); copied & returned
> Dec 25 10:09:03.477: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:03.477: CT: calc'd tx(0)/rx(0) deltas
> for act
> Prepaid2-Khart#v call (hndl=1163)
> Dec 25 10:09:03.477: CT: get found active entry
> (hndl=1163); copied & returned
> Dec 25 10:09:03.477: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:03.477: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1163)
> Dec 25 10:09:03.477: CT: get found active entry
> (hndl=1163); copied & returned
> Dec 25 10:09:03.477: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:03.477: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1163)
> Dec 25 10:09:03
> Prepaid2-Khart#.477: CT: get found active entry
> (hndl=1163); copied & returned
> Dec 25 10:09:03.477: CT: ds0 allocated: hndl=1163,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:09:03.477: CT: ds0 allocated: hndl=1163,
>   idb=0x639675D0(Se3/1:7), call_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/7,
> Dec 25 10:09:03.477: CT: set AAA session id:
>   hndl=1163, sess_id=1166
> Dec 25 10:09:03.477: CT: set AAA session id:
>   hndl=1163, sess_id=1166
> Dec 25 10:09:03.493: ISDN Se3/1:15
> Prepaid2-Khart#: process_pri_simple(): msg 74, call
> id 0x4C3, bchan 7, call type VOICE
> Dec 25 10:09:03.493: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C3, bchan
> 7, call type VOICE
> Dec 25 10:09:03.493: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DC7
> Dec 25 10:09:03.493: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DC7
> Dec 25 10:09:03.525: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DC7
> Dec 25 10:09:03.529: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C3 CALL_PROGRESS
> Dec 25 10:09:
> Prepaid2-Khart#03.529: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:09:03.529: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4C3, bchan 7, dsl 1
> Dec 25 10:09:03.529: CT: call connected: hndl=1163,
>   idb=0x639675D0(Se3/1:7), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/7
> Dec 25 10:09:04.469: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DC8
> Dec 25 10:09:04.469:         Sending Complete
> Dec 25 10:09:04.469:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:09:04.469:         Channel ID
> Prepaid2-Khart# i = 0xA98389
> Dec 25 10:09:04.469:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:09:04.469:         Calling Party Number i
> = 0x2183, '187536039', Plan:ISDN, Type:National
> Dec 25 10:09:04.469:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:09:04.469: ISDN Se3/1:15: Incoming call id
> = 0x04C4, dsl 1
> Dec 25 10:09:04.469: ISDN Se3/1:15: NegotiateBchan:
> bchan 9 intid 0 serv_st 0 chan_st 0 callid 0x0000 ev
> 0x90 n/w? 0
> Dec 25 10:09:0
> Prepaid2-Khart#4.469: Negotiated int_id 0 bchan 0
> cr=0x9DC8 callid=0x04C4 lo_chan 9 final int_id/bchan
> 0/9 cause 0x0
> Dec 25 10:09:04.469: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C4 CALL_INCOMING
> Dec 25 10:09:04.469: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 8
> Dec 25 10:09:04.473: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 8
> Dec 25 10:09:04.473: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:09:04.473: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10
> Prepaid2-Khart#:09:04.473: ISDN Se3/1:15: Event: 
> Received a VOICE call from 187536039 on B8 at 64
> Kb/s Tone Value 0
> Dec 25 10:09:04.473: CT: setup: issued hndl=1164 for
> call_cat=2(Modem), called=180980, calling=187536039
> Dec 25 10:09:04.473: CT: Successfully inserted
> active call-record entry hndl=1164 into table.
> Dec 25 10:09:04.473: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:04.473: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1164)
> Dec 25 10:09:04.473: CT: get found active entry (
> Prepaid2-Khart#hndl=1164); copied & returned
> Dec 25 10:09:04.473: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:04.473: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1164)
> Dec 25 10:09:04.473: CT: get found active entry
> (hndl=1164); copied & returned
> Dec 25 10:09:04.473: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:04.473: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1164)
> Dec 25 10:09:04.473: CT: get found active entry
> (hndl=1164); copied & returned
> Dec 
> Prepaid2-Khart#25 10:09:04.473: CT: indeterminate
> service type (1); returning tx(0)/rx(0)
> Dec 25 10:09:04.473: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1164)
> Dec 25 10:09:04.473: CT: get found active entry
> (hndl=1164); copied & returned
> Dec 25 10:09:04.477: CT: ds0 allocated: hndl=1164,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:09:04.477: CT: ds0 allocated: hndl=1164,
>   idb=0x63970038(Se3/1:8), call_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/8,
> Dec 25 10:0
> Prepaid2-Khart#9:04.477: CT: set AAA session id:
>   hndl=1164, sess_id=1167
> Dec 25 10:09:04.477: CT: set AAA session id:
>   hndl=1164, sess_id=1167
> Dec 25 10:09:04.489: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4C4, bchan
> 8, call type VOICE
> Dec 25 10:09:04.489: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C4, bchan
> 8, call type VOICE
> Dec 25 10:09:04.489: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DC8
> Dec 25 10:09:04.489:         Channel ID i = 0xA98389
> Dec 25 10:09:04.489: ISDN Se3
> Prepaid2-Khart#/1:15: TX ->  CONNECT pd = 8  callref
> = 0x9DC8
> Dec 25 10:09:04.525: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DC8
> Dec 25 10:09:04.529: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C4 CALL_PROGRESS
> Dec 25 10:09:04.529: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:09:04.529: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4C4, bchan 8, dsl 1
> Dec 25 10:09:04.529: CT: call connected: hndl=1164,
>   idb=0x63970038(Se3/1:8), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/8
Prepaid2-Khart#
> Dec 25 10:09:06.409: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DC9
> Dec 25 10:09:06.409:         Sending Complete
> Dec 25 10:09:06.409:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:09:06.409:         Channel ID i = 0xA9838A
> Dec 25 10:09:06.409:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:09:06.409:         Calling Party Number i
> = 0x2183, '187553890', Plan:ISDN, Type:National
> Dec 25 10:09:06.409:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Ty
> Prepaid2-Khart#pe:Subscriber(local)
> Dec 25 10:09:06.409: ISDN Se3/1:15: Incoming call id
> = 0x04C5, dsl 1
> Dec 25 10:09:06.409: ISDN Se3/1:15: NegotiateBchan:
> bchan 10 intid 0 serv_st 0 chan_st 0 callid 0x0000
> ev 0x90 n/w? 0
> Dec 25 10:09:06.409: Negotiated int_id 0 bchan 0
> cr=0x9DC9 callid=0x04C5 lo_chan 10 final
> int_id/bchan 0/10 cause 0x0
> Dec 25 10:09:06.409: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C5 CALL_INCOMING
> Dec 25 10:09:06.409: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 9
> Dec 25 10:09:06.413: ISDN Se3/1:15:
> Prepaid2-Khart# CALL_INCOMING: call type is VOICE
> ALAW, bchan = 9
> Dec 25 10:09:06.413: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:09:06.413: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:09:06.413: ISDN Se3/1:15: Event:  Received
> a VOICE call from 187553890 on B9 at 64 Kb/s Tone
> Value 0
> Dec 25 10:09:06.413: CT: setup: issued hndl=1165 for
> call_cat=2(Modem), called=180980, calling=187553890
> Dec 25 10:09:06.413: CT: Successfully inserted
> active call-record entry hnd
> Prepaid2-Khart#l=1165 into table.
> Dec 25 10:09:06.413: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:06.413: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1165)
> Dec 25 10:09:06.413: CT: get found active entry
> (hndl=1165); copied & returned
> Dec 25 10:09:06.413: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:06.413: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1165)
> Dec 25 10:09:06.413: CT: get found active entry
> (hndl=1165); copied & returned
> Dec 25 10:09:06
> Prepaid2-Khart#.413: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:06.413: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1165)
> Dec 25 10:09:06.413: CT: get found active entry
> (hndl=1165); copied & returned
> Dec 25 10:09:06.413: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:06.413: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1165)
> Dec 25 10:09:06.413: CT: get found active entry
> (hndl=1165); copied & returned
> Dec 25 10:09:06.417: CT: ds0 allocated: hndl=1165,
> Prepaid2-Khart#
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:09:06.417: CT: ds0 allocated: hndl=1165,
>   idb=0x63978AA0(Se3/1:9), call_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/9,
> Dec 25 10:09:06.417: CT: set AAA session id:
>   hndl=1165, sess_id=1168
> Dec 25 10:09:06.417: CT: set AAA session id:
>   hndl=1165, sess_id=1168
> Dec 25 10:09:06.429: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4C5, bchan
> 9, call type VOICE
> Dec 25 10:09:06.429: ISDN Se3/1:15: proces
> Prepaid2-Khart#s_pri_simple(): msg 78, call id
> 0x4C5, bchan 9, call type VOICE
> Dec 25 10:09:06.429: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DC9
> Dec 25 10:09:06.429:         Channel ID i = 0xA9838A
> Dec 25 10:09:06.433: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DC9
> Dec 25 10:09:06.477: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DC9
> Dec 25 10:09:06.477: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C5 CALL_PROGRESS
> Dec 25 10:09:06.477: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:0
> Prepaid2-Khart#9:06.477: ISDN Se3/1:15:
> CALL_PROGRESS: CALL_CONNECTED call id 0x4C5, bchan
> 9, dsl 1
> Dec 25 10:09:06.477: CT: call connected: hndl=1165,
>   idb=0x63978AA0(Se3/1:9), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/9
> Dec 25 10:09:14.533: ISDN Se3/0:15: Outgoing call id
> = 0x8007, dsl 0
> Dec 25 10:09:14.533: ISDN Se3/0:15:
> process_pri_call(): call id 0x8007, number 83445098,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Dec 25 10:09:14.533: callER type/plan overridden by
> call_decod
> Prepaid2-Khart#e
> Dec 25 10:09:14.533: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:14.533: callED type/plan overridden by
> call_decode
> Dec 25 10:09:14.533: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:14.533:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:14.533: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8007, call
> type VOICE
> Dec 25 10:09:14.537: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8007 CALL_REJECTION
> Dec 25 10:09:14.537: ISDN Se3/0:15: LIF_EVENT:
> ces/ca
> Prepaid2-Khart#llid 1/0x8007 CALL_CLEARED
> Dec 25 10:09:14.537: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x8007
> Dec 25 10:09:14.537: no resend setup, no redial
> Dec 25 10:09:14.537: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id = 0x8007).
> Dec 25 10:09:14.537: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:14.537: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:14.537: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -
> Prepaid2-Khart#1, call id 0x8007
> Dec 25 10:09:14.537: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:14.537: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:17.005: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DCA
> Dec 25 10:09:17.005:         Sending Complete
> Dec 25 10:09:17.005:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:09:17.005:  
> Prepaid2-Khart#       Channel ID i = 0xA9838B
> Dec 25 10:09:17.005:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:09:17.005:         Calling Party Number i
> = 0x2183, '183319292', Plan:ISDN, Type:National
> Dec 25 10:09:17.005:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:09:17.005: ISDN Se3/1:15: Incoming call id
> = 0x04C6, dsl 1
> Dec 25 10:09:17.005: ISDN Se3/1:15: NegotiateBchan:
> bchan 11 intid 0 serv_st 0 chan_st 0 callid 0x0000
> ev 0x90 n/w?
> Prepaid2-Khart# 0
> Dec 25 10:09:17.005: Negotiated int_id 0 bchan 0
> cr=0x9DCA callid=0x04C6 lo_chan 11 final
> int_id/bchan 0/11 cause 0x0
> Dec 25 10:09:17.005: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C6 CALL_INCOMING
> Dec 25 10:09:17.009: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 10
> Dec 25 10:09:17.009: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 10
> Dec 25 10:09:17.009: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:09:17.009: ISDN Se3/1:15: Event: Adjusting
> speed to user se
> Prepaid2-Khart#lection 64K
> Dec 25 10:09:17.009: ISDN Se3/1:15: Event:  Received
> a VOICE call from 183319292 on B10 at 64 Kb/s Tone
> Value 0
> Dec 25 10:09:17.009: CT: setup: issued hndl=1166 for
> call_cat=2(Modem), called=180980, calling=183319292
> Dec 25 10:09:17.009: CT: Successfully inserted
> active call-record entry hndl=1166 into table.
> Dec 25 10:09:17.009: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:17.009: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1166)
> Dec 25 10:09:17.009: CT: g
> Prepaid2-Khart#et found active entry (hndl=1166);
> copied & returned
> Dec 25 10:09:17.009: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:17.009: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1166)
> Dec 25 10:09:17.009: CT: get found active entry
> (hndl=1166); copied & returned
> Dec 25 10:09:17.009: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:17.009: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1166)
> Dec 25 10:09:17.009: CT: get found active entry
> (hndl=1166); 
> Prepaid2-Khart#copied & returned
> Dec 25 10:09:17.009: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:17.013: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1166)
> Dec 25 10:09:17.013: CT: get found active entry
> (hndl=1166); copied & returned
> Dec 25 10:09:17.013: CT: ds0 allocated: hndl=1166,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:09:17.013: CT: ds0 allocated: hndl=1166,
>   idb=0x63981508(Se3/1:10), call_cat=1(ISDN Sync),
>   slot/port/ds1/ch
> Prepaid2-Khart#an=3/1/1/10,
> Dec 25 10:09:17.013: CT: set AAA session id:
>   hndl=1166, sess_id=1169
> Dec 25 10:09:17.013: CT: set AAA session id:
>   hndl=1166, sess_id=1169
> Dec 25 10:09:17.025: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4C6, bchan
> 10, call type VOICE
> Dec 25 10:09:17.025: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C6, bchan
> 10, call type VOICE
> Dec 25 10:09:17.029: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DCA
> Dec 25 10:09:17.029:         Channel ID i = 0xA9838B
> De
> Prepaid2-Khart#c 25 10:09:17.029: ISDN Se3/1:15: TX
> ->  CONNECT pd = 8  callref = 0x9DCA
> Dec 25 10:09:17.065: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DCA
> Dec 25 10:09:17.069: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C6 CALL_PROGRESS
> Dec 25 10:09:17.069: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:09:17.069: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4C6, bchan 10, dsl 1
> Dec 25 10:09:17.069: CT: call connected: hndl=1166,
>   idb=0x63981508(Se3/1:10), call_cat=2(Modem),
> rate=64000,
> 
> Prepaid2-Khart#  slot/port/ds1/chan=3/1/1/10
> Dec 25 10:09:19.253: ISDN Se3/0:15: Outgoing call id
> = 0x8008, dsl 0
> Dec 25 10:09:19.253: ISDN Se3/0:15:
> process_pri_call(): call id 0x8008, number
> 0912895137, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:09:19.253: callER type/plan overridden by
> call_decode
> Dec 25 10:09:19.253: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:19.253: callED type/plan overridden by
> call_decode
> Dec 25 10:09:19.253: did't copy oct3a reason: not
> CALLER_NUMBER_
> Prepaid2-Khart#IE
> Dec 25 10:09:19.253:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:19.253: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8008, call
> type VOICE
> Dec 25 10:09:19.253: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8008 CALL_REJECTION
> Dec 25 10:09:19.253: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8008 CALL_CLEARED
> Dec 25 10:09:19.257: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x8008
> Dec 25 10:09:19.257: no resend setup, no redial
> Dec 25 10:09:19.257: ISDN Se3/0:15: Got a d
> Prepaid2-Khart#isconnect on a non-existent call
> (call id = 0x8008).
> Dec 25 10:09:19.257: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:19.257: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:19.257: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x8008
> Dec 25 10:09:19.257: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown c
> Prepaid2-Khart#ause value), Q.xxx=0
> Dec 25 10:09:19.257: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:22.581: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DCB
> Dec 25 10:09:22.581:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:09:22.581:         Channel ID i = 0xA9838C
> Dec 25 10:09:22.581:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:09:22.581:         Calling Party Number i
> = 0x2183, '187491237', Plan:ISDN, Type:National
> Dec 25 10:09:22.581:
> Prepaid2-Khart#         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:09:22.581: ISDN Se3/1:15: Incoming call id
> = 0x04C7, dsl 1
> Dec 25 10:09:22.585: ISDN Se3/1:15: NegotiateBchan:
> bchan 12 intid 0 serv_st 0 chan_st 0 callid 0x0000
> ev 0x90 n/w? 0
> Dec 25 10:09:22.585: Negotiated int_id 0 bchan 0
> cr=0x9DCB callid=0x04C7 lo_chan 12 final
> int_id/bchan 0/12 cause 0x0
> Dec 25 10:09:22.585: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C7 CALL_INCOMING
> Dec 25 10:09:22.585: ISDN Se3/1:15: CA
> Prepaid2-Khart#LL_INCOMING dsl 1 bchan 11
> Dec 25 10:09:22.585: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 11
> Dec 25 10:09:22.585: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:09:22.585: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:09:22.585: ISDN Se3/1:15: Event:  Received
> a VOICE call from 187491237 on B11 at 64 Kb/s Tone
> Value 0
> Dec 25 10:09:22.585: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C7, bchan
> 11, call type VOICE
> Dec 25 1
> Prepaid2-Khart#0:09:22.589: ISDN Se3/1:15: TX -> 
> SETUP_ACK pd = 8  callref = 0x9DCB
> Dec 25 10:09:22.589:         Channel ID i = 0xA9838C
> Dec 25 10:09:22.589: CT: setup: issued hndl=1167 for
> call_cat=2(Modem), called=180980, calling=187491237
> Dec 25 10:09:22.589: CT: Successfully inserted
> active call-record entry hndl=1167 into table.
> Dec 25 10:09:22.589: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:22.589: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1167)
> Dec 25 10:09:22.589: CT: ge
> Prepaid2-Khart#t found active entry (hndl=1167);
> copied & returned
> Dec 25 10:09:22.589: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:22.589: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1167)
> Dec 25 10:09:22.589: CT: get found active entry
> (hndl=1167); copied & returned
> Dec 25 10:09:22.589: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:22.589: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1167)
> Dec 25 10:09:22.589: CT: get found active entry
> (hndl=1167); c
> Prepaid2-Khart#opied & returned
> Dec 25 10:09:22.589: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:22.589: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1167)
> Dec 25 10:09:22.589: CT: get found active entry
> (hndl=1167); copied & returned
> Dec 25 10:09:22.589: CT: ds0 allocated: hndl=1167,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:09:22.589: CT: ds0 allocated: hndl=1167,
>   idb=0x63989F70(Se3/1:11), call_cat=1(ISDN Sync),
>   slot/port/ds1/cha
> Prepaid2-Khart#n=3/1/1/11,
> Dec 25 10:09:22.589: CT: set AAA session id:
>   hndl=1167, sess_id=1170
> Dec 25 10:09:22.589: CT: set AAA session id:
>   hndl=1167, sess_id=1170
> Dec 25 10:09:22.605: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4C7, bchan
> 11, call type VOICE
> Dec 25 10:09:22.605: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C7, bchan
> 11, call type VOICE
> Dec 25 10:09:22.605: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DCB
> Dec 25 10:09:22.605: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8
> Prepaid2-Khart#  callref = 0x9DCB
> Dec 25 10:09:22.641: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DCB
> Dec 25 10:09:22.645: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C7 CALL_PROGRESS
> Dec 25 10:09:22.645: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:09:22.645: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4C7, bchan 11, dsl 1
> Dec 25 10:09:22.645: CT: call connected: hndl=1167,
>   idb=0x63989F70(Se3/1:11), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/11
> Dec 25 10:09:22.869: ISD
> Prepaid2-Khart#N Se3/0:15: Outgoing call id =
> 0x8009, dsl 0
> Dec 25 10:09:22.869: ISDN Se3/0:15:
> process_pri_call(): call id 0x8009, number 83445098,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Dec 25 10:09:22.869: callER type/plan overridden by
> call_decode
> Dec 25 10:09:22.869: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:22.869: callED type/plan overridden by
> call_decode
> Dec 25 10:09:22.869: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:22.869:  ISDN Se3/0:15: GetIdleDMSChan:
> 
> Prepaid2-Khart#Interface De-Activated
> Dec 25 10:09:22.869: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8009, call
> type VOICE
> Dec 25 10:09:22.869: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8009 CALL_REJECTION
> Dec 25 10:09:22.869: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8009 CALL_CLEARED
> Dec 25 10:09:22.869: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x8009
> Dec 25 10:09:22.869: no resend setup, no redial
> Dec 25 10:09:22.869: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id = 0x8009).
> Dec
> Prepaid2-Khart# 25 10:09:22.869: This probably is a
> call that we placed that never got answered.
> Dec 25 10:09:22.869: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:22.869: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x8009
> Dec 25 10:09:22.869: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:22.869: CT: rejected: 
> Prepaid2-Khart#got indication for non-active call
> (hndl=0); ignored
> Dec 25 10:09:25.453: ISDN Se3/0:15: Outgoing call id
> = 0x800A, dsl 0
> Dec 25 10:09:25.453: ISDN Se3/0:15:
> process_pri_call(): call id 0x800A, number
> 0912895137, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:09:25.453: callER type/plan overridden by
> call_decode
> Dec 25 10:09:25.453: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:25.453: callED type/plan overridden by
> call_decode
> Dec 25 10:09:25.453: did't copy oct3a rea
> Prepaid2-Khart#son: not CALLER_NUMBER_IE
> Dec 25 10:09:25.453:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:25.453: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x800A, call
> type VOICE
> Dec 25 10:09:25.453: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800A CALL_REJECTION
> Dec 25 10:09:25.453: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800A CALL_CLEARED
> Dec 25 10:09:25.453: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x800A
> Dec 25 10:09:25.453: no resend setup, no redial
> Dec 25 10:09:25.453:
> Prepaid2-Khart# ISDN Se3/0:15: Got a disconnect on a
> non-existent call (call id = 0x800A).
> Dec 25 10:09:25.453: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:25.453: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:25.453: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x800A
> Dec 25 10:09:25.453: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), c
> Prepaid2-Khart#ode=0x0, text=Unknown cause value),
> Q.xxx=0
> Dec 25 10:09:25.453: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:28.977: ISDN Se3/0:15: Outgoing call id
> = 0x800B, dsl 0
> Dec 25 10:09:28.977: ISDN Se3/0:15:
> process_pri_call(): call id 0x800B, number 83445098,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Dec 25 10:09:28.977: callER type/plan overridden by
> call_decode
> Dec 25 10:09:28.977: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:28.977: callED 
> Prepaid2-Khart#type/plan overridden by call_decode
> Dec 25 10:09:28.977: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:28.977:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:28.977: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x800B, call
> type VOICE
> Dec 25 10:09:28.977: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800B CALL_REJECTION
> Dec 25 10:09:28.977: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800B CALL_CLEARED
> Dec 25 10:09:28.977: ISDN Se3/0:15: received
> CALL_CLEARED  call_id
> Prepaid2-Khart# 0x800B
> Dec 25 10:09:28.977: no resend setup, no redial
> Dec 25 10:09:28.977: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id = 0x800B).
> Dec 25 10:09:28.977: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:28.977: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:28.977: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x800B
> Dec 25 10:09:28.977: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15)
> Prepaid2-Khart#, call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:28.977: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:31.349: ISDN Se3/0:15: Outgoing call id
> = 0x800C, dsl 0
> Dec 25 10:09:31.349: ISDN Se3/0:15:
> process_pri_call(): call id 0x800C, number
> 0912895137, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:09:31.349: callER type/plan overridden by
> call_decode
> Dec 25 10:0
> Prepaid2-Khart#9:31.349: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:31.349: callED type/plan overridden by
> call_decode
> Dec 25 10:09:31.349: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:31.349:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:31.353: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x800C, call
> type VOICE
> Dec 25 10:09:31.353: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800C CALL_REJECTION
> Dec 25 10:09:31.353: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800C 
> Prepaid2-Khart#CALL_CLEARED
> Dec 25 10:09:31.353: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x800C
> Dec 25 10:09:31.353: no resend setup, no redial
> Dec 25 10:09:31.353: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id = 0x800C).
> Dec 25 10:09:31.353: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:31.353: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:31.353: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id 0x8
> Prepaid2-Khart#00C
> Dec 25 10:09:31.353: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:31.353: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:33.693: ISDN Se3/0:15: Outgoing call id
> = 0x800D, dsl 0
> Dec 25 10:09:33.693: ISDN Se3/0:15:
> process_pri_call(): call id 0x800D, number 85388025,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Prepaid2-Khart#
> Dec 25 10:09:33.693: callER type/plan overridden by
> call_decode
> Dec 25 10:09:33.693: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:33.693: callED type/plan overridden by
> call_decode
> Dec 25 10:09:33.693: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:33.693:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:33.693: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x800D, call
> type VOICE
> Dec 25 10:09:33.693: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800D CALL_
> Prepaid2-Khart#REJECTION
> Dec 25 10:09:33.697: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800D CALL_CLEARED
> Dec 25 10:09:33.697: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x800D
> Dec 25 10:09:33.697: no resend setup, no redial
> Dec 25 10:09:33.697: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id = 0x800D).
> Dec 25 10:09:33.697: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:33.697: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:33.697: ISDN S
> Prepaid2-Khart#e3/0:15: CALL_DISC: VOICE ERROR: NULL
> VDEV Common(0xFC): bchan -1, call id 0x800D
> Dec 25 10:09:33.697: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:33.697: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:33.809: ISDN Se3/1:15: RX <- 
> DISCONNECT pd = 8  callref = 0x1DC9
> Dec 25 10:09:33.809:         Cause i = 0x8090 -
> Normal call c
> Prepaid2-Khart#learing
> Dec 25 10:09:33.809:         Progress Ind i = 0x8288
> - In-band info or appropriate now available 
> Dec 25 10:09:33.809: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C5 CALL_DISC
> Dec 25 10:09:33.809: CT: call disconnect: hndl=1165,
>   disc(id=0x2(ISDN), code=0x10, text=Normal call
> clearing), Q.xxx=16
> Dec 25 10:09:33.809: CT: Successfully deleted active
> call-record entry hndl=1165 from table.
> Dec 25 10:09:33.809: CT: TCP entry (hndl=1165) does
> not exists, no need to remove from AVL
> Dec 25 10:09:33.
> Prepaid2-Khart#809: CT: Modem entry (hndl=1165) does
> not exists, no need to remove from AVL
> Dec 25 10:09:33.809: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:33.809: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1165)
> Dec 25 10:09:33.809: CT: storing disc info (id=0x2,
> code=0x10) in hist node (indx=1156, hndl=1165)
> Dec 25 10:09:33.809: CT: Successfully inserted
> history call-record entry hndl=1165 into table.
> Dec 25 10:09:33.809: CT: actv-->hist ok: node
> (hndl=1165) is now (or already wa
> Prepaid2-Khart#s) in history table
> Dec 25 10:09:33.833: ISDN Se3/1:15: TX ->  RELEASE
> pd = 8  callref = 0x9DC9
> Dec 25 10:09:33.849: ISDN Se3/1:15: RX <- 
> RELEASE_COMP pd = 8  callref = 0x1DC9
> Dec 25 10:09:33.849: ISDN Se3/1:15:
> CCPRI_ReleaseCall(): bchan 10, call id 0x4C5, call
> type VOICE
> Dec 25 10:09:33.849: CCPRI_ReleaseChan released
> b_dsl 1 B_Chan 10
> Dec 25 10:09:33.849: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C5 CALL_CLEARED
> Dec 25 10:09:33.849: ISDN Se3/1:15: received
> CALL_CLEARED  call_id 0x4C5
> Dec 25 10:0
> Prepaid2-Khart#9:33.849: no resend setup, no redial
> Dec 25 10:09:34.649: ISDN Se3/0:15: Outgoing call id
> = 0x800E, dsl 0
> Dec 25 10:09:34.649: ISDN Se3/0:15:
> process_pri_call(): call id 0x800E, number 83445098,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Dec 25 10:09:34.649: callER type/plan overridden by
> call_decode
> Dec 25 10:09:34.649: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:34.649: callED type/plan overridden by
> call_decode
> Dec 25 10:09:34.649: did't copy oct3a reason: not
> CALLER_NU
> Prepaid2-Khart#MBER_IE
> Dec 25 10:09:34.649:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:34.649: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x800E, call
> type VOICE
> Dec 25 10:09:34.649: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800E CALL_REJECTION
> Dec 25 10:09:34.649: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800E CALL_CLEARED
> Dec 25 10:09:34.649: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x800E
> Dec 25 10:09:34.649: no resend setup, no redial
> Dec 25 10:09:34.649: ISDN Se3/0:15: Go
> Prepaid2-Khart#t a disconnect on a non-existent call
> (call id = 0x800E).
> Dec 25 10:09:34.649: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:34.649: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:34.649: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x800E
> Dec 25 10:09:34.649: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unkn
> Prepaid2-Khart#own cause value), Q.xxx=0
> Dec 25 10:09:34.649: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:36.145: ISDN Se3/0:15: Outgoing call id
> = 0x800F, dsl 0
> Dec 25 10:09:36.149: ISDN Se3/0:15:
> process_pri_call(): call id 0x800F, number
> 0912684180, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:09:36.149: callER type/plan overridden by
> call_decode
> Dec 25 10:09:36.149: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:36.149: callED type/plan overri
> Prepaid2-Khart#dden by call_decode
> Dec 25 10:09:36.149: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:36.149:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:36.149: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x800F, call
> type VOICE
> Dec 25 10:09:36.149: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800F CALL_REJECTION
> Dec 25 10:09:36.149: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x800F CALL_CLEARED
> Dec 25 10:09:36.149: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x800F
> Dec 25 
> Prepaid2-Khart#10:09:36.149: no resend setup, no
> redial
> Dec 25 10:09:36.149: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id = 0x800F).
> Dec 25 10:09:36.149: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:36.149: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:36.149: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x800F
> Dec 25 10:09:36.153: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unk
> Prepaid2-Khart#nown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:36.153: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:37.009: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DCC
> Dec 25 10:09:37.009:         Sending Complete
> Dec 25 10:09:37.009:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:09:37.009:         Channel ID i = 0xA9838D
> Dec 25 10:09:37.009:         Progress Ind i = 0x8283
> - Origination address is 
> Prepaid2-Khart#non-ISDN 
> Dec 25 10:09:37.009:         Calling Party Number i
> = 0x2183, '187553890', Plan:ISDN, Type:National
> Dec 25 10:09:37.009:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> Dec 25 10:09:37.009: ISDN Se3/1:15: Incoming call id
> = 0x04C8, dsl 1
> Dec 25 10:09:37.009: ISDN Se3/1:15: NegotiateBchan:
> bchan 13 intid 0 serv_st 0 chan_st 0 callid 0x0000
> ev 0x90 n/w? 0
> Dec 25 10:09:37.009: Negotiated int_id 0 bchan 0
> cr=0x9DCC callid=0x04C8 lo_chan 13 final
> int_id/bchan 0/1
> Prepaid2-Khart#3 cause 0x0
> Dec 25 10:09:37.009: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C8 CALL_INCOMING
> Dec 25 10:09:37.009: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 12
> Dec 25 10:09:37.013: ISDN Se3/1:15: CALL_INCOMING:
> call type is VOICE ALAW, bchan = 12
> Dec 25 10:09:37.013: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:09:37.013: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:09:37.013: ISDN Se3/1:15: Event:  Received
> a VOICE call from 187553890 on B12 at 64 Kb/
> Prepaid2-Khart#s Tone Value 0
> Dec 25 10:09:37.013: CT: setup: issued hndl=1168 for
> call_cat=2(Modem), called=180980, calling=187553890
> Dec 25 10:09:37.013: CT: Successfully inserted
> active call-record entry hndl=1168 into table.
> Dec 25 10:09:37.013: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:37.013: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1168)
> Dec 25 10:09:37.013: CT: get found active entry
> (hndl=1168); copied & returned
> Dec 25 10:09:37.013: CT: indeterminate service type
> (1);
> Prepaid2-Khart# returning tx(0)/rx(0)
> Dec 25 10:09:37.013: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1168)
> Dec 25 10:09:37.013: CT: get found active entry
> (hndl=1168); copied & returned
> Dec 25 10:09:37.013: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:09:37.013: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1168)
> Dec 25 10:09:37.013: CT: get found active entry
> (hndl=1168); copied & returned
> Dec 25 10:09:37.017: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:0
> Prepaid2-Khart#9:37.017: CT: calc'd tx(0)/rx(0)
> deltas for actv call (hndl=1168)
> Dec 25 10:09:37.017: CT: get found active entry
> (hndl=1168); copied & returned
> Dec 25 10:09:37.017: CT: ds0 allocated: hndl=1168,
>   idb=0x639AC910(Se3/1:15), call_cat=2(Modem),
>   slot/port/ds1/chan=3/1/1/15,
> Dec 25 10:09:37.017: CT: ds0 allocated: hndl=1168,
>   idb=0x639929D8(Se3/1:12), call_cat=1(ISDN Sync),
>   slot/port/ds1/chan=3/1/1/12,
> Dec 25 10:09:37.017: CT: set AAA session id:
>   hndl=1168, sess_id=1171
> Dec 25 10:09:37.017: CT
> Prepaid2-Khart#: set AAA session id:
>   hndl=1168, sess_id=1171
> Dec 25 10:09:37.029: ISDN Se3/1:15:
> process_pri_simple(): msg 74, call id 0x4C8, bchan
> 12, call type VOICE
> Dec 25 10:09:37.029: ISDN Se3/1:15:
> process_pri_simple(): msg 78, call id 0x4C8, bchan
> 12, call type VOICE
> Dec 25 10:09:37.029: ISDN Se3/1:15: TX ->  CALL_PROC
> pd = 8  callref = 0x9DCC
> Dec 25 10:09:37.029:         Channel ID i = 0xA9838D
> Dec 25 10:09:37.029: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DCC
> Dec 25 10:09:37.069: ISDN Se3/1:15:
> Prepaid2-Khart# RX <-  CONNECT_ACK pd = 8  callref =
> 0x1DCC
> Dec 25 10:09:37.073: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C8 CALL_PROGRESS
> Dec 25 10:09:37.073: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:09:37.073: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4C8, bchan 12, dsl 1
> Dec 25 10:09:37.073: CT: call connected: hndl=1168,
>   idb=0x639929D8(Se3/1:12), call_cat=2(Modem),
> rate=64000,
>   slot/port/ds1/chan=3/1/1/12
> Dec 25 10:09:37.921: ISDN Se3/0:15: Outgoing call id
> = 0x8010, dsl 0
> Dec 25 10
> Prepaid2-Khart#:09:37.921: ISDN Se3/0:15:
> process_pri_call(): call id 0x8010, number
> 0912895137, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:09:37.921: callER type/plan overridden by
> call_decode
> Dec 25 10:09:37.921: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:37.921: callED type/plan overridden by
> call_decode
> Dec 25 10:09:37.921: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:37.921:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:37.921: ISDN Se3
> Prepaid2-Khart#/0:15: CCPRI_ReleaseCall(): bchan 0,
> call id 0x8010, call type VOICE
> Dec 25 10:09:37.921: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8010 CALL_REJECTION
> Dec 25 10:09:37.921: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8010 CALL_CLEARED
> Dec 25 10:09:37.921: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x8010
> Dec 25 10:09:37.921: no resend setup, no redial
> Dec 25 10:09:37.921: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id = 0x8010).
> Dec 25 10:09:37.921: This probably is a call that we
> pla
> Prepaid2-Khart#ced that never got answered.
> Dec 25 10:09:37.921: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:37.921: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x8010
> Dec 25 10:09:37.921: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:37.921: CT: rejected: got indication
> for non-active call (hndl=0);
> ignored
Prepaid2-Khart#
> Dec 25 10:09:38.093: ISDN Se3/0:15: Outgoing call id
> = 0x8011, dsl 0
> Dec 25 10:09:38.093: ISDN Se3/0:15:
> process_pri_call(): call id 0x8011, number
> 0911231158, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:09:38.093: callER type/plan overridden by
> call_decode
> Dec 25 10:09:38.093: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:38.093: callED type/plan overridden by
> call_decode
> Dec 25 10:09:38.093: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:38.093:  ISD
> Prepaid2-Khart#N Se3/0:15: GetIdleDMSChan: Interface
> De-Activated
> Dec 25 10:09:38.093: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8011, call
> type VOICE
> Dec 25 10:09:38.093: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8011 CALL_REJECTION
> Dec 25 10:09:38.093: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8011 CALL_CLEARED
> Dec 25 10:09:38.093: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x8011
> Dec 25 10:09:38.093: no resend setup, no redial
> Dec 25 10:09:38.097: ISDN Se3/0:15: Got a disconnect
> on a non-existent c
> Prepaid2-Khart#all (call id = 0x8011).
> Dec 25 10:09:38.097: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:38.097: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:38.097: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x8011
> Dec 25 10:09:38.097: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 
> Prepaid2-Khart#10:09:38.097: CT: rejected: got
> indication for non-active call (hndl=0); ignored
> Dec 25 10:09:40.361: ISDN Se3/0:15: Outgoing call id
> = 0x8012, dsl 0
> Dec 25 10:09:40.361: ISDN Se3/0:15:
> process_pri_call(): call id 0x8012, number 83445098,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Dec 25 10:09:40.361: callER type/plan overridden by
> call_decode
> Dec 25 10:09:40.361: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:40.365: callED type/plan overridden by
> call_decode
> Dec 25 10:09:40
> Prepaid2-Khart#.365: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:40.365:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:40.365: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8012, call
> type VOICE
> Dec 25 10:09:40.365: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8012 CALL_REJECTION
> Dec 25 10:09:40.365: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8012 CALL_CLEARED
> Dec 25 10:09:40.365: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x8012
> Dec 25 10:09:40.365: no resend setup, no re
> Prepaid2-Khart#dial
> Dec 25 10:09:40.365: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id = 0x8012).
> Dec 25 10:09:40.365: This probably is a call that we
> placed that never got answered.
> Dec 25 10:09:40.365: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:09:40.365: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x8012
> Dec 25 10:09:41.633: ISDN Se3/0:15: Outgoing call id
> = 0x8013, dsl 0
> Dec 25 10:09:41.633: ISDN Se3/0:15:
> process_pri_call(): c
> Prepaid2-Khart#all id 0x8013, number 0912684180,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Dec 25 10:09:41.633: callER type/plan overridden by
> call_decode
> Dec 25 10:09:41.633: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:41.633: callED type/plan overridden by
> call_decode
> Dec 25 10:09:41.633: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:41.633:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:41.633: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8
> Prepaid2-Khart#013, call type VOICE
> Dec 25 10:09:41.681: no resend setup, no redial
> Dec 25 10:09:43.585: ISDN Se3/0:15: Outgoing call id
> = 0x8014, dsl 0
> Dec 25 10:09:43.585: ISDN Se3/0:15:
> process_pri_call(): call id 0x8014, number 85388025,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Dec 25 10:09:43.585: callER type/plan overridden by
> call_decode
> Dec 25 10:09:43.589: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:43.589: callED type/plan overridden by
> call_decode
> Dec 25 10:09:43.589: did't
> Prepaid2-Khart# copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:43.589:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:43.589: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8014, call
> type VOICERELEASE_COMP pd = 8  callref = 0x1DC0
> Dec 25 10:09:44.441: ISDN Se3/1:15:
> CCPRI_ReleaseCall(): bchan 1, call id 0x4BC, call
> type VOICE
> Dec 25 10:09:44.441: CCPRI_ReleaseChan released
> b_dsl 1 B_Chan 1
> Dec 25 10:09:44.441: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BC CALL_CLEARED
> Dec 25 10:09
> Prepaid2-Khart#:44.441: ISDN Se3/1:15: received
> CALL_CLEARED  call_id 0x4BC
> Dec 25 10:09:44.441: no resend setup, no redial
> Dec 25 10:09:45.325: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DCD
> Dec 25 10:09:45.325:         Sending Complete
> Dec 25 10:09:45.325:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:09:45.325:         Channel ID i = 0xA9838E
> Dec 25 10:09:45.325:         Progress Ind i = 
> Dec 25 10:09:46.197: ISDN Se3/1:15: RX <-  RELEASE
> pd = 8  callref = 0x1DC2
> Dec 25 10:09:46.201: ISDN Se3/1:15: CC
> Prepaid2-Khart#PRI_ReleaseCall(): bchan 3, call id
> 0x4BE, call type VOICE
> Dec 25 10:09:46.201: CCPRI_ReleaseChan released
> b_dsl 1 B_Chan 3
> Dec 25 10:09:46.201: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4BE CALL_CLEARED
> Dec 25 10:09:46.201: ISDN Se3/1:15: received
> CALL_CLEARED  call_id 0x4BE
> Dec 25 10:09:46.201: no resend setup, no redial
> Dec 25 10:09:46.201: ISDN Se3/1:15: TX -> 
> RELEASE_COMP pd = 8  callref = 0x9DC2
> Dec 25 10:09:47.333: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>  
> Prepaid2-Khart# slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:47.333: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:49.509: ISDN Se3/0:15: Outgoing call id
> = 0x8018, dsl 0
> Dec 25 10:09:49.509: ISDN Se3/0:15:
> process_pri_call(): call id 0x8018, number 09123,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Dec 25 10:09:49.509: callER type/plan overridden by
> call_decode
> Dec 25 10:09:49.509: copied oct3a [0x83] 
> Prepaid2-Khart#for CALLER_NUMBER_IE
> Dec 25 10:09:49.509: callED type/plan overridden by
> call_decode
> Dec 25 10:09:49.509: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:49.509:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:49.509: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8018, call
> type VOICE
> Dec 25 10:09:49.513: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> Prepaid2-Khart# value), Q.xxx=0
> Dec 25 10:09:49.513: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:50.933: ISDN Se3/0:15: Outgoing call id
> = 0x8019, dsl 0
> Dec 25 10:09:50.933: ISDN Se3/0:15:
> process_pri_call(): call id 0x8019, number
> 0912684180, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:09:50.933: callER type/plan overridden by
> call_decode
> Dec 25 10:09:50.933: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:50.933: callED type/plan overridden by
> c
> Prepaid2-Khart#all_decode
> Dec 25 10:09:50.933: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:09:50.933:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:09:50.933: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8019, call
> type VOICE
> Dec 25 10:09:50.933: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:50.933: CT: rejected: got indication
> for non
> Prepaid2-Khart#-active call (hndl=0); ignored
> Dec 25 10:09:51.673: ISDN Se3/0:15: Outgoing call id
> = 0x801A, dsl 0
> Dec 25 10:09:51.673: ISDN Se3/0:15:
> process_pri_call(): call id 0x801A, number
> 0911231158, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:09:51.673: callER type/plan overridden by
> call_decode
> Dec 25 10:09:51.673: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:09:52.753: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15
> Prepaid2-Khart#,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:52.753: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:54.517: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:09:54.517: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:56.177: %CALLTRKR-6-CALL_RECORD:
> ct_hndl=1156, servi
> Prepaid2-Khart#ce=None, origin=Answer,
> category=Modem, DS0 slot/port/ds1/chan=3/1/1/0,
> called=180980, calling=185630452, resource
> slot/port=(n/a)/(n/a), userid=(n/a), ip=0.0.0.0,
> mask=0.0.0.0, account id=1159, setup=12/25/2004
> 10:08:18, conn=0.06, phys=0.00, service=0.00,
> authen=0.00, init-rx/tx b-rate=64000/64000, rx/tx
> chars=0/0, time=85.80, disc subsys=ISDN, disc
> code=0x10, disc text=Normal call clearing
> Dec 25 10:09:56.177: %CALLTRKR-6-CALL_RECORD:
> ct_hndl=1158, service=None, origin=Answer,
> category=Modem, DS0 slot/
> Prepaid2-Khart#port/ds1/chan=3/1/1/2, called=180980,
> calling=187595458, resource slot/port=(n/a)/(n/a),
> userid=(n/a), ip=0.0.0.0, mask=0.0.0.0, account
> id=1161, setup=12/25/2004 10:08:28, conn=0.06,
> phys=0.00, service=0.00, authen=0.00, init-rx/tx
> b-rate=64000/64000, rx/tx chars=0/0, time=77.64,
> disc subsys=ISDN, disc code=0x66, disc text=Recovery
> on timer expiry
> Dec 25 10:09:56.841: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x
> Prepaid2-Khart#0, text=Unknown cause value), Q.xxx=0
> Dec 25 10:09:56.841: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:09:57.545: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DCF
> Dec 25 10:09:57.545:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:09:57.545:         Channel ID i = 0xA98391
> Dec 25 10:09:57.545:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:09:57.545:         Calling Party Number i
> = 0x2183, '187595458', Plan:ISDN, Type:NationalRELEA
> Prepaid2-Khart#SE_COMP pd = 8  callref = 0x1DCC
> Dec 25 10:09:59.385: ISDN Se3/1:15:
> CCPRI_ReleaseCall(): bchan 13, call id 0x4C8, call
> type VOICE
> Dec 25 10:09:59.385: CCPRI_ReleaseChan released
> b_dsl 1 B_Chan 13
> Dec 25 10:09:59.385: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C8 CALL_CLEARED
> Dec 25 10:09:59.385: ISDN Se3/1:15: received
> CALL_CLEARED  call_id 0x4C8
> Dec 25 10:09:59.385: no resend setup, no redial
> Dec 25 10:10:00.833: ISDN Se3/0:15: Outgoing call id
> = 0x801E, dsl 0
> Dec 25 10:10:00.833: ISDN Se3/0:15: pr
> Prepaid2-Khart#ocess_pri_call(): call id 0x801E,
> number 00919849109388, speed 0, call type VOICE,
> redialed? f, csm call? f, pdata? t
> Dec 25 10:10:00.833: callER type/plan overridden by
> call_decode
> Dec 25 10:10:00.833: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:10:00.833: callED type/plan overridden by
> call_decode
> Dec 25 10:10:00.833: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:10:00.833:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:10:00.833: ISDN Se3/0:15:
> CCPRI_ReleaseCall(
> Prepaid2-Khart#): bchan 0, call id 0x801E, call type
> VOICE
> Dec 25 10:10:00.837: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:10:00.837: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:10:05.521: ISDN Se3/1:15: RX <- 
> DISCONNECT pd = 8  callref = 0x1DCD
> Dec 25 10:10:05.521:         Cause i = 0x8090 -
> Normal call clearing
> Dec 25 10:10:05.521:         
> Prepaid2-Khart#Progress Ind i = 0x8288 - In-band
> info or appropriate now available 
> Dec 25 10:10:05.521: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C9 CALL_DISC
> Dec 25 10:10:05.521: CT: call disconnect: hndl=1169,
>   disc(id=0x2(ISDN), code=0x10, text=Normal call
> clearing), Q.xxx=16
> Dec 25 10:10:05.521: CT: Successfully deleted active
> call-record entry hndl=1169 from table.
> Dec 25 10:10:05.521: CT: TCP entry (hndl=1169) does
> not exists, no need to remove from AVL
> Dec 25 10:10:05.521: CT: Modem entry (hndl=1169)
> does 
> Prepaid2-Khart#not exists, no need to remove from
> AVL
> Dec 25 10:10:05.521: CT: indeterminate service type
> (1); returning tx(0)/rx(0)
> Dec 25 10:10:05.521: CT: calc'd tx(0)/rx(0) deltas
> for actv call (hndl=1169)
> Dec 25 10:10:05.525: CT: storing disc info (id=0x2,
> code=0x10) in hist node (indx=1161, hndl=1169)
> Dec 25 10:10:05.525: CT: Successfully inserted
> history call-record entry hndl=1169 into table.
> Dec 25 10:10:05.525: CT: actv-->hist ok: node
> (hndl=1169) is now (or already was) in history table
> Dec 25 10:10:05.5
> Prepaid2-Khart#45: ISDN Se3/1:15: TX ->  RELEASE pd
> = 8  callref = 0x9DCD
> Dec 25 10:10:05.561: ISDN Se3/1:15: RX <- 
> RELEASE_COMP pd = 8  callref = 0x1DCD
> Dec 25 10:10:05.561: ISDN Se3/1:15:
> CCPRI_ReleaseCall(): bchan 14, call id 0x4C9, call
> type VOICE
> Dec 25 10:10:05.561: CCPRI_ReleaseChan released
> b_dsl 1 B_Chan 14
> Dec 25 10:10:05.561: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C9 CALL_CLEARED
> Dec 25 10:10:05.561: ISDN Se3/1:15: received
> CALL_CLEARED  call_id 0x4C9
> Dec 25 10:10:05.561: no resend setup, no redial
> 
> Prepaid2-Khart#Dec 25 10:10:05.825: ISDN Se3/0:15:
> Outgoing call id = 0x801F, dsl 0
> Dec 25 10:10:05.825: ISDN Se3/0:15:
> process_pri_call(): call id 0x801F, number
> 0911231158, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:10:05.825: callER type/plan overridden by
> call_decode
> Dec 25 10:10:05.825: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:10:05.825: callED type/plan overridden by
> call_decode
> Dec 25 10:10:05.825: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:10:05.825:  ISDN 
> Prepaid2-Khart#Se3/0:15: GetIdleDMSChan: Interface
> De-Activated
> Dec 25 10:10:05.825: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x801F, call
> type VOICE
> Dec 25 10:10:05.825: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x801F CALL_REJECTION
> Dec 25 10:10:05.825: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x801F CALL_CLEARED
> Dec 25 10:10:05.829: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x801F
> Dec 25 10:10:06.197: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0
> Prepaid2-Khart#/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:10:06.197: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:10:09.169: ISDN Se3/1:15: RX <- 
> DISCONNECT pd = 8  callref = 0x1DC7
> Dec 25 10:10:09.169:         Cause i = 0x8090 -
> Normal call clearing
> Dec 25 10:10:09.169:         Progress Ind i = 0x8288
> - In-band info or appropriate now available 
> Dec 25 10:10:09.169:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10
> Prepaid2-Khart#:10:09.169: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C3 CALL_DISC
> Dec 25 10:10:09.173: CT: call disconnect: hndl=1163,
>   disc(id=0x2(ISDN), code=0x10, text=Normal call
> clearing), Q.xxx=16
> Dec 25 10:10:09.173: CT: Successfully deleted active
> call-record entry hndl=1163 from table.
> Dec 25 10:10:09.393: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C2 CALL_CLEARED
> Dec 25 10:10:09.393: ISDN Se3/1:15: received
> CALL_CLEARED  call_id 0x4C2
> Dec 25 10:10:09.393: no resend setup, no redial
> Dec 25 10:10:11.473: I
> Prepaid2-Khart#SDN Se3/1:15: RX <-  SETUP pd = 8 
> callref = 0x1DD0
> Dec 25 10:10:11.473:         Sending Complete
> Dec 25 10:10:11.473:         Bearer Capability i =
> 0x9090A3
> Dec 25 10:10:11.473:         Channel ID i = 0xA98392
> Dec 25 10:10:11.473:         Progress Ind i = 0x8283
> - Origination address is non-ISDN 
> Dec 25 10:10:11.473:         Calling Party Number i
> = 0x2183, '183313034', Plan:ISDN, Type:National
> Dec 25 10:10:11.473:         Called Party Number i =
> 0xC1, '180980', Plan:ISDN, Type:Subscriber(local)
> De
> Prepaid2-Khart#c 25 10:10:11.473: ISDN Se3/1:15:
> Incoming call id = 0x04CC, dsl 1
> Dec 25 10:10:11.473: ISDN Se3/1:15: NegotiateBchan:
> bchan 18 intid 0 serv_st 0 chan_st 0 callid 0x0000
> ev 0x90 n/w? 0
> Dec 25 10:10:11.473: Negotiated int_id 0 bchan 0
> cr=0x9DD0 callid=0x04CC lo_chan 18 final
> int_id/bchan 0/18 cause 0x0
> Dec 25 10:10:11.473: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4CC CALL_INCOMING
> Dec 25 10:10:11.473: ISDN Se3/1:15: CALL_INCOMING
> dsl 1 bchan 17
> Dec 25 10:10:11.477: ISDN Se3/1:15: CALL_INCOMING:
> call ty
> Prepaid2-Khart#pe is VOICE ALAW, bchan = 17
> Dec 25 10:10:11.477: ISDN Se3/1:15: Event: Checking
> if call is not ISDN end to end
> Dec 25 10:10:11.477: ISDN Se3/1:15: Event: Adjusting
> speed to user selection 64K
> Dec 25 10:10:13.161: ISDN Se3/0:15: Outgoing call id
> = 0x8021, dsl 0
> Dec 25 10:10:13.161: ISDN Se3/0:15:
> process_pri_call(): call id 0x8021, number
> 0912684180, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:10:13.161: callER type/plan overridden by
> call_decode
> Dec 25 10:10:13.161: copied
> Prepaid2-Khart# oct3a [0x83] for CALLER_NUMBER_IE
> Dec 25 10:10:13.161: callED type/plan overridden by
> call_decode
> Dec 25 10:10:13.161: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:10:13.161:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:10:13.161: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8021, call
> type VOICE
> Dec 25 10:10:13.165: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text
> Prepaid2-Khart#=Unknown cause value), Q.xxx=0
> Dec 25 10:10:13.165: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:10:15.681: ISDN Se3/0:15: Outgoing call id
> = 0x8022, dsl 0
> Dec 25 10:10:15.681: ISDN Se3/0:15:
> process_pri_call(): call id 0x8022, number
> 09128955137, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:10:15.681: callER type/plan overridden by
> call_decode
> Dec 25 10:10:15.681: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:10:15.681: callED type/plan 
> Prepaid2-Khart#overridden by call_decode
> Dec 25 10:10:15.681: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:10:15.681:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:10:15.681: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8022, call
> type VOICE
> Dec 25 10:10:15.681: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8022 CALL_REJECTION
> Dec 25 10:10:15.681: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8022 CALL_CLEARED
> Dec 25 10:10:15.685: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x8022
> D
> Prepaid2-Khart#ec 25 10:10:15.685: no resend setup,
> no redial
> Dec 25 10:10:15.685: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id = 0x8022).
> Dec 25 10:10:15.685: This probably is a call that we
> placed that never got answered.
> Dec 25 10:10:15.685: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:10:15.685: ISDN Se3/0:15: CALL_DISC: VOICE
> ERROR: NULL VDEV Common(0xFC): bchan -1, call id
> 0x8022
> Dec 25 10:10:18.405: ISDN Se3/0:15: Outgoing call id
> = 0x8024, dsl 0
> Dec 25 10:10:18
> Prepaid2-Khart#.405: ISDN Se3/0:15:
> process_pri_call(): call id 0x8024, number
> 0918316768, speed 0, call type VOICE, redialed? f,
> csm call? f, pdata? t
> Dec 25 10:10:18.405: callER type/plan overridden by
> call_decode
> Dec 25 10:10:18.405: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:10:18.405: callED type/plan overridden by
> call_decode
> Dec 25 10:10:18.405: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:10:18.405:  ISDN Se3/0:15: GetIdleDMSChan:
> Interface De-Activated
> Dec 25 10:10:18.405: ISDN Se3/0:15:
> Prepaid2-Khart# CCPRI_ReleaseCall(): bchan 0, call
> id 0x8024, call type VOICE
> Dec 25 10:10:18.409: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:10:18.409: CT: rejected: got indication
> for non-active call (hndl=0); ignored
> Dec 25 10:10:20.189: ISDN Se3/1:15: RX <- 
> DISCONNECT pd = 8  callref = 0x1DC8
> Dec 25 10:10:20.189:         Cause i = 0x8090 -
> Normal call clearing
> Dec 25 10:
> Prepaid2-Khart#10:20.189:         Progress Ind i =
> 0x8288 - In-band info or appropriate now available 
> Dec 25 10:10:20.189: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C4 CALL_DISC
> Dec 25 10:10:20.189: CT: call disconnect: hndl=1164,
>   disc(id=0x2(ISDN), code=0x10, text=Normal call
> clearing), Q.xxx=16
> Dec 25 10:10:20.189: CT: Successfully deleted active
> call-record entry hndl=1164 from table.
> Dec 25 10:10:20.193: CT: TCP entry (hndl=1164) does
> not exists, no need to remove from AVLRELEASE_COMP
> pd = 8  callref = 0x1DC8
> Prepaid2-Khart#
> Dec 25 10:10:20.229: ISDN Se3/1:15:
> CCPRI_ReleaseCall(): bchan 9, call id 0x4C4, call
> type VOICE
> Dec 25 10:10:20.229: CCPRI_ReleaseChan released
> b_dsl 1 B_Chan 9
> Dec 25 10:10:20.229: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4C4 CALL_CLEARED
> Dec 25 10:10:20.229: ISDN Se3/1:15: received
> CALL_CLEARED  call_id 0x4C4
> Dec 25 10:10:20.229: no resend setup, no redial
> Dec 25 10:10:20.973: ISDN Se3/1:15: RX <-  SETUP pd
> = 8  callref = 0x1DD1
> Dec 25 10:10:20.973:         Sending Complete
> Dec 25 10:10:20.973:
> Prepaid2-Khart#         Bearer Capability i =
> 0x9090A3
> Dec 25 10:10:20.973:         Channel ID i = 0xA98393
> Dec 25 10:10:20.973:         Progress Ind i = 
> Dec 25 10:10:21.317: CT: call rejected: hndl=0,
>   idb=0x635EEBD0(Se3/0:15), call_cat=0(Unknown),
>   slot/port/ds1/chan=3/0/0/15,
>   disc(id=0x2(ISDN), code=0x0, text=Unknown cause
> value), Q.xxx=0
> Dec 25 10:10:21.317: CT: rejected: got indication
> for non-active call (hndl=0); ignoredCALL_PROC pd =
> 8  callref = 0x9DD2
> Dec 25 10:10:23.093:         Channel ID i = 0xA
> Prepaid2-Khart#98394
> Dec 25 10:10:23.093: ISDN Se3/1:15: TX ->  CONNECT
> pd = 8  callref = 0x9DD2
> Dec 25 10:10:23.133: ISDN Se3/1:15: RX <- 
> CONNECT_ACK pd = 8  callref = 0x1DD2
> Dec 25 10:10:23.133: ISDN Se3/1:15: LIF_EVENT:
> ces/callid 1/0x4CE CALL_PROGRESS
> Dec 25 10:10:23.133: ISDN Se3/1:15: event
> CALL_PROGRESS dsl 1
> Dec 25 10:10:23.133: ISDN Se3/1:15: CALL_PROGRESS:
> CALL_CONNECTED call id 0x4CE, bchan 19, dsl 1
> Dec 25 10:10:23.133: CT: call connected: hndl=1174,
>   idb=0x639CF2B0(Se3/1:19), call_cat=2(Modem), rate
> Prepaid2-Khart#=64000,
>   slot/port/ds1/chan=3/1/1/19
> Dec 25 10:10:26.181: %CALLTRKR-6-CALL_RECORD:
> ct_hndl=1164, service=None, origin=Answer,
> category=Modem, DS0 slot/port/ds1/chan=3/1/1/8,
> called=180980, calling=187536039, resource
> slot/port=(n/a)/(n/a), userid=(n/a), ip=0.0.0.0,
> mask=0.0.0.0, account id=1167, setup=12/25/2004
> 10:09:04, conn=0.06, phys=0.00, service=0.00,
> authen=0.00, init-rx/tx b-rate=64000/64000, rx/tx
> chars=0/0, time=75.72, disc subsys=ISDN, disc
> code=0x10, disc text=Normal call clearing
> Dec 25 10
> Prepaid2-Khart#:10:26.877: ISDN Se3/0:15: Outgoing
> call id = 0x8027, dsl 0
> Dec 25 10:10:26.877: ISDN Se3/0:15:
> process_pri_call(): call id 0x8027, number 85388025,
> speed 0, call type VOICE, redialed? f, csm call? f,
> pdata? t
> Dec 25 10:10:26.881: callER type/plan overridden by
> call_decode
> Dec 25 10:10:26.881: copied oct3a [0x83] for
> CALLER_NUMBER_IE
> Dec 25 10:10:26.881: callED type/plan overridden by
> call_decode
> Dec 25 10:10:26.881: did't copy oct3a reason: not
> CALLER_NUMBER_IE
> Dec 25 10:10:26.881:  ISDN Se3/0:15: G
> Prepaid2-Khart#etIdleDMSChan: Interface De-Activated
> Dec 25 10:10:26.881: ISDN Se3/0:15:
> CCPRI_ReleaseCall(): bchan 0, call id 0x8027, call
> type VOICE
> Dec 25 10:10:26.881: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8027 CALL_REJECTION
> Dec 25 10:10:26.881: ISDN Se3/0:15: LIF_EVENT:
> ces/callid 1/0x8027 CALL_CLEARED
> Dec 25 10:10:26.881: ISDN Se3/0:15: received
> CALL_CLEARED  call_id 0x8027
> Dec 25 10:10:26.881: no resend setup, no redial
> Dec 25 10:10:26.881: ISDN Se3/0:15: Got a disconnect
> on a non-existent call (call id 
> Prepaid2-Khart#= 0x8027).
> Dec 25 10:10:26.881: This probably is a call that we
> placed that never got answered.
> Dec 25 10:10:26.881: ISDN Se3/0:15: EVENT to
> CSM:DEV_IDLE: calltype=VOICE, bchan=-1
> Dec 25 10:10:26.881: ISDN Se3/0:15: CALL_DISC: VOICE
<