Skip site navigation (1)Skip section navigation (2)
Date:      Tue, 15 Feb 2000 17:12:39 +0100 (CET)
From:      jan@sparud.net (Jan Sparud)
To:        freebsd-isdn@freebsd.org
Subject:   Still problems with sppp+answering machine
Message-ID:  <14505.31479.834993.255113@hinken.sparud.net>

next in thread | raw e-mail | index | archive | help

As I mentioned in an earlier message I have problems with the
answering machine causing an sppp connection to freeze.

I have not been able to find the reason for it to happen. If someone
in-the-know would like to take a look at the following (lengthy) log
messages, to see if something looks fishy, it would be much appreciated.

The scenario is that an sppp link is opened (isp0, I4BPPP) and I start to
ping out through isp0. I have setup isdnd.rc so that when I call from
my mobile phone, an answering machine script /etc/isdn/dummy should take
care of the call. The dummy script is as follows:

#!/bin/sh
exit 0

The script doesn't open the tel device at all, yet when started by
isdnd (because I called with the mobile phone) the ping stops, and the
sppp connection is frozen. (The same thing happens if I use a "real"
answering machine instead of the dummy one.)

My enviroment is NetBSD/i386-current, i4b 0.90, AVM Fritz! PCI.

The following log messages are from isdndebug -m and isdnd -l -L
/var/log/messages -d 0x3fb. The rx buffer overflows in the end looks
suspicious to me. A problem is that for most of the debug output it's
not easy to see which channel it belongs to.

/Jan Sparud

15.02.2000 16:48:15 DBG msg_dialout: dial req from isp, unit 0
15.02.2000 16:48:15 DBG setup_dialout: entry I4BPPP ok!
15.02.2000 16:48:15 DBG find_by_device_for_dialout: found entry 0!
Feb 15 16:48:15 gbox /netbsd: i4b-L4-reserve_cd: found free cd - index=0 cdid=131
15.02.2000 16:48:15 DBG FSM event [msg-dialout]: [idle => dialing]
15.02.2000 16:48:15 DBG F_DIAL: local dial out request
15.02.2000 16:48:15 DBG select_first_dialno: only one no, no = 020990299
15.02.2000 16:48:15 CHD 00131 I4BPPP rate 60 sec/unit (unitlen unknown)
15.02.2000 16:48:15 DBG sendm_connect_req: ctrl = 0, chan = 1
15.02.2000 16:48:15 DBG decr_free_channels: ctrl 0, now 1 chan free
15.02.2000 16:48:15 CHD 00131 I4BPPP dialing out from 7083541 to 020990299
Feb 15 16:48:15 gbox /netbsd: i4b-L4-cd_by_cdid: found cdid - index=0 cdid=131 cr=0
Feb 15 16:48:15 gbox /netbsd: i4b-L4-i4bioctl: I4B_CONNECT_REQ times, algorithm=0 unitlen=60 idle=0 earlyhup=5
Feb 15 16:48:15 gbox /netbsd: i4b-L4-cd_by_cdid: found cdid - index=0 cdid=131 cr=85
Feb 15 16:48:15 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_SETUPRQ - L4 SETUP REQ]: [ST_U0 - Null => ST_SUSE - Subroutine sets state]
Feb 15 16:48:15 gbox /netbsd: i4b-L3-F_00A: FSM function F_00A executing
15.02.2000 16:48:15 DBG msg_l12stat_ind: unit 0, layer 2, state 1
Feb 15 16:48:15 gbox /netbsd: i4b-L2-DL-ESTABLISH-REQ: unit 0
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_next_l2state: FSM event [EV_DLESTRQ]: [ST_TEI_ASGD/3 => ST_AW_EST/4]
Feb 15 16:48:15 gbox /netbsd: i4b-L2-F_T01: FSM function F_T01 executing
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_tx_sabme: tx SABME, tei = 69
Feb 15 16:48:15 gbox /netbsd: i4b-L1-ph_data_req: ISAC_TX_ACTIVE set
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_T200_restart: unit 0
Feb 15 16:48:15 gbox /netbsd: i4b-L3-T303_start: cr = 85
Feb 15 16:48:15 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:15 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_rxd_u_frame: UA, sapi = 0, tei = 69
Feb 15 16:48:15 gbox /netbsd: i4b-L2-F_AE09: FSM function F_AE09 executing
Feb 15 16:48:15 gbox /netbsd: i4b-L3-i4b_mdl_status_ind: unit = 0, status = 2, parm = 1
Feb 15 16:48:15 gbox /netbsd: i4b-L3-i4b_mdl_status_ind: STI_L2STAT: unit 0 layer 2 = up
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_T200_stop: unit 0
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_next_l2state: FSM S-event [EV_RXUA]: [ST_AW_EST => ST_MULTIFR]
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_next_l2state: FSM executing postfsmfunc!
Feb 15 16:48:15 gbox /netbsd: i4b-L2-DL-ESTABLISH-CONF: unit 0
Feb 15 16:48:15 gbox /netbsd: i4b-L3-i4b_dl_establish_cnf: unit=0, index=0 cdid=131 cr=85
Feb 15 16:48:15 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_DLESTCF - L2 DL_Est_Cnf]: [ST_OW - Out Wait EST => ST_U1 - Out Init]
Feb 15 16:48:15 gbox /netbsd: i4b-L3-F_DECF1: FSM function F_DECF1 executing
Feb 15 16:48:15 gbox /netbsd: i4b-L3-tx SETUP: unit 0, cr = 0x55
Feb 15 16:48:15 gbox /netbsd: i4b-L1-ph_data_req: ISAC_TX_ACTIVE set
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_T200_start: unit 0
Feb 15 16:48:15 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:15 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:15 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_rxd_s_frame: rx'd RR, N(R) = 1
Feb 15 16:48:15 gbox /netbsd: i4b-L2-F_MF17: FSM function F_MF17 executing
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_T200_stop: unit 0
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_next_l2state: FSM S-event [EV_RXRR]: [ST_MULTIFR => ST_MULTIFR]
Feb 15 16:48:15 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
15.02.2000 16:48:15 DBG set_channel_busy: controller [0] channel B2 set to BUSY!
15.02.2000 16:48:15 CHD 00131 I4BPPP outgoing call proceeding (ctl 0, ch 1)
Feb 15 16:48:15 gbox /netbsd: i4b-L3-i4b_decode_q931: Call Ref, len 1, val 85, flag 1
Feb 15 16:48:15 gbox /netbsd: i4b-L4-cd_by_unitcr: found cd, index=0 cdid=131 cr=85
Feb 15 16:48:15 gbox /netbsd: i4b-L3-rx CALL-PROC: unit 0, cr = 0x55
Feb 15 16:48:15 gbox /netbsd: i4b-L3-i4b_decode_q931_cs0_ie: IEI_CHANNELID - channel 1, exclusive = 1
Feb 15 16:48:15 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_CALLPRC - rxd CALL PROC]: [ST_U1 - Out Init => ST_U3 - Out Proc]
Feb 15 16:48:15 gbox /netbsd: i4b-L3-F_01M: FSM function F_01M executing
Feb 15 16:48:15 gbox /netbsd: i4b-L3-T303_stop: cr = 85
Feb 15 16:48:15 gbox /netbsd: i4b-L3-T310_start: cr = 85
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_tx_rr_response: tx RR, unit = 0
Feb 15 16:48:15 gbox /netbsd: i4b-L1-ph_data_req: ISAC_TX_ACTIVE set
Feb 15 16:48:15 gbox /netbsd: i4b-L2-i4b_T200_stop: unit 0
Feb 15 16:48:15 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:16 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:16 gbox /netbsd: i4b-L3-i4b_decode_q931: Call Ref, len 1, val 85, flag 1
Feb 15 16:48:16 gbox /netbsd: i4b-L4-cd_by_unitcr: found cd, index=0 cdid=131 cr=85
Feb 15 16:48:16 gbox /netbsd: i4b-L3-rx ALERT: unit 0, cr = 0x55
Feb 15 16:48:16 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_ALERT - rxd ALERT]: [ST_U3 - Out Proc => ST_U4 - Out Delv]
Feb 15 16:48:16 gbox /netbsd: i4b-L3-F_03N: FSM function F_03N executing
Feb 15 16:48:16 gbox /netbsd: i4b-L3-T310_stop: cr = 85
Feb 15 16:48:16 gbox /netbsd: i4b-L2-i4b_tx_rr_response: tx RR, unit = 0
Feb 15 16:48:16 gbox /netbsd: i4b-L1-ph_data_req: ISAC_TX_ACTIVE set
Feb 15 16:48:16 gbox /netbsd: i4b-L2-i4b_T200_stop: unit 0
Feb 15 16:48:16 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:16 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
15.02.2000 16:48:16 DBG set_channel_busy: controller [0] channel B2 already busy!
15.02.2000 16:48:16 CHD 00131 I4BPPP outgoing call active (ctl 0, ch 1, isp0)
15.02.2000 16:48:16 DBG FSM event [msg-con-act-ind]: [dialing => connected]
15.02.2000 16:48:16 DBG F_DOK: dial out ok
15.02.2000 16:48:16 DBG select_this_dialno: index = 0, no = 020990299
Feb 15 16:48:16 gbox /netbsd: i4b-L3-i4b_decode_q931: Call Ref, len 1, val 85, flag 1
Feb 15 16:48:16 gbox /netbsd: i4b-L4-cd_by_unitcr: found cd, index=0 cdid=131 cr=85
Feb 15 16:48:16 gbox /netbsd: i4b-L3-rx CONNECT: unit 0, cr = 0x55
Feb 15 16:48:16 gbox /netbsd: i4b-L3-i4b_decode_q931_cs0_ie: IEI_DATETIME = 0002151648
Feb 15 16:48:16 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_CONNECT - rxd CONNECT]: [ST_U4 - Out Delv => ST_U10 - Active]
Feb 15 16:48:16 gbox /netbsd: i4b-L3-F_04O: FSM function F_04O executing
Feb 15 16:48:16 gbox /netbsd: i4b-L3-tx CONNECT-ACK: unit 0, cr = 0x55
Feb 15 16:48:16 gbox /netbsd: i4b-L1-ph_data_req: ISAC_TX_ACTIVE set
Feb 15 16:48:16 gbox /netbsd: i4b-L2-i4b_T200_start: unit 0
Feb 15 16:48:16 gbox /netbsd: i4b-L4-i4b_l4_connect_active_ind: last_active/connect_time=950629696
Feb 15 16:48:16 gbox /netbsd: i4b-L1-avma1pp_bchannel_setup: unit=0, channel=1, activate
Feb 15 16:48:16 gbox /netbsd: i4b-L1-avma1pp_hscx_init: unit=0, channel=1, activate
Feb 15 16:48:16 gbox /netbsd: i4b-L1-avma1pp_hscx_init: BPROT_RHDLC
Feb 15 16:48:16 gbox /netbsd: i4b-L4-i4b_l4_setup_timeout: 950629696: direction 0, shorthold algorithm 0
Feb 15 16:48:16 gbox /netbsd: i4b-L4-i4b_l4_setup_timeout: no idle_timeout configured
Feb 15 16:48:16 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:16 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:16 gbox /netbsd: i4b-L2-i4b_rxd_s_frame: rx'd RR, N(R) = 2
Feb 15 16:48:16 gbox /netbsd: i4b-L2-F_MF17: FSM function F_MF17 executing
Feb 15 16:48:16 gbox /netbsd: i4b-L2-i4b_T200_stop: unit 0
Feb 15 16:48:16 gbox /netbsd: i4b-L2-i4b_next_l2state: FSM S-event [EV_RXRR]: [ST_MULTIFR => ST_MULTIFR]
15.02.2000 16:48:16 DBG isp0: switched to state 3
Feb 15 16:48:21 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x40
Feb 15 16:48:21 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
15.02.2000 16:48:21 DBG find_matching_entry_incoming: entry 0, myno 0317083541 != incomingno 031182043
15.02.2000 16:48:21 DBG find_matching_entry_incoming: entry 1, remno 317439817 != incomingfromno 708182143
15.02.2000 16:48:21 DBG find_matching_entry_incoming: entry 2, remno 31182043 != incomingfromno 708182143
15.02.2000 16:48:21 DBG find_matching_entry_incoming: 708182143 - screening user provided, verified & passed
15.02.2000 16:48:21 DBG decr_free_channels: ctrl 0, now 0 chan free
15.02.2000 16:48:21 CHD 00132 I4BAMMOBILE answering: incoming call from 708182143 to 031182043 ()
15.02.2000 16:48:21 DBG FSM event [msg-con-ind]: [idle => accepted]
15.02.2000 16:48:21 DBG F_MCI: tx SETUP_RESP_ACCEPT
15.02.2000 16:48:21 DBG sendm_connect_resp: sent CONNECT_RESP
Feb 15 16:48:21 gbox /netbsd: i4b-L3-i4b_decode_q931: Call Ref, len 1, val 48, flag 0
Feb 15 16:48:21 gbox /netbsd: i4b-L4-reserve_cd: found free cd - index=1 cdid=132
Feb 15 16:48:21 gbox /netbsd: i4b-L3-rx SETUP: unit 0, cr = 0x30
Feb 15 16:48:21 gbox /netbsd: i4b-L3-i4b_decode_q931_cs0_ie: IEI_SENDCOMPL
Feb 15 16:48:21 gbox /netbsd: i4b-L3-i4b_decode_q931_cs0_ie: IEI_BEARERCAP - Telephony
Feb 15 16:48:21 gbox /netbsd: i4b-L3-i4b_decode_q931_cs0_ie: IEI_CHANNELID - channel 0, exclusive = 1
Feb 15 16:48:21 gbox /netbsd: i4b-L3-i4b_decode_q931_cs0_ie: IEI_CALLINGPN = 708182143
Feb 15 16:48:21 gbox /netbsd: i4b-L3-i4b_decode_q931_cs0_ie: IEI_CALLED = 031182043
Feb 15 16:48:21 gbox /netbsd: i4b-L3-i4b_decode_q931_cs0_ie: IEI_HLCOMPAT
Feb 15 16:48:21 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_SETUP - rxd SETUP]: [ST_U0 - Null => ST_U6 - In Pres]
Feb 15 16:48:21 gbox /netbsd: i4b-L3-F_00H: FSM function F_00H executing
Feb 15 16:48:21 gbox /netbsd: i4b-L4-T400_start: cr = 48
Feb 15 16:48:21 gbox /netbsd: i4b-L4-cd_by_cdid: found cdid - index=1 cdid=132 cr=48
Feb 15 16:48:21 gbox /netbsd: i4b-L4-T400_stop: cr = 48
Feb 15 16:48:21 gbox /netbsd: i4b-L4-i4bioctl: I4B_CONNECT_RESP max_idle_time set to 0 seconds
Feb 15 16:48:21 gbox /netbsd: i4b-L4-cd_by_cdid: found cdid - index=1 cdid=132 cr=48
Feb 15 16:48:21 gbox /netbsd: i4b-L4-T400_stop: cr = 48
Feb 15 16:48:21 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_SETACRS - L4 accept RSP]: [ST_U6 - In Pres => ST_SUSE - Subroutine sets state]
Feb 15 16:48:21 gbox /netbsd: i4b-L3-F_06E: FSM function F_06E executing
Feb 15 16:48:21 gbox /netbsd: i4b-L3-tx CONNECT: unit 0, cr = 0x30
Feb 15 16:48:21 gbox /netbsd: i4b-L1-ph_data_req: ISAC_TX_ACTIVE set
Feb 15 16:48:21 gbox /netbsd: i4b-L2-i4b_T200_start: unit 0
Feb 15 16:48:21 gbox /netbsd: i4b-L3-T313_start: cr = 48
Feb 15 16:48:21 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:21 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:21 gbox /netbsd: i4b-L2-i4b_rxd_s_frame: rx'd RR, N(R) = 3
Feb 15 16:48:21 gbox /netbsd: i4b-L2-F_MF17: FSM function F_MF17 executing
Feb 15 16:48:21 gbox /netbsd: i4b-L2-i4b_T200_stop: unit 0
Feb 15 16:48:21 gbox /netbsd: i4b-L2-i4b_next_l2state: FSM S-event [EV_RXRR]: [ST_MULTIFR => ST_MULTIFR]
Feb 15 16:48:21 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
15.02.2000 16:48:21 DBG set_channel_busy: controller [0] channel B1 set to BUSY!
15.02.2000 16:48:21 CHD 00132 I4BAMMOBILE incoming call active (ctl 0, ch 0, tel0)
15.02.2000 16:48:21 DBG FSM event [msg-con-act-ind]: [accepted => connected]
15.02.2000 16:48:21 DBG F_MCAI: Connection active!
15.02.2000 16:48:21 DBG exec_answer: prog=[dummy]
15.02.2000 16:48:21 DBG exec_prog: /etc/isdn/dummy, args: -D /dev/i4btel0 -d 031182043 -s 708182143
15.02.2000 16:48:21 DBG normal child (pid=9805) termination, exitstat = 0
15.02.2000 16:48:21 DBG sigchild_handler: scheduling hangup for cdid 132, pid 9805
Feb 15 16:48:21 gbox /netbsd: i4b-L3-i4b_decode_q931: Call Ref, len 1, val 48, flag 0
Feb 15 16:48:21 gbox /netbsd: i4b-L4-cd_by_unitcr: found cd, index=1 cdid=132 cr=48
Feb 15 16:48:21 gbox /netbsd: i4b-L3-rx CONNECT-ACK: unit 0, cr = 0x30
Feb 15 16:48:21 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_CONACK - rxd CONN ACK]: [ST_U8 - In ConReq => ST_U10 - Active]
Feb 15 16:48:21 gbox /netbsd: i4b-L3-F_08R: FSM function F_08R executing
Feb 15 16:48:21 gbox /netbsd: i4b-L3-T313_stop: cr = 48
Feb 15 16:48:21 gbox /netbsd: i4b-L4-i4b_l4_connect_active_ind: last_active/connect_time=950629701
Feb 15 16:48:21 gbox /netbsd: i4b-L1-avma1pp_bchannel_setup: unit=0, channel=0, activate
Feb 15 16:48:21 gbox /netbsd: i4b-L1-avma1pp_hscx_init: unit=0, channel=0, activate
Feb 15 16:48:21 gbox /netbsd: i4b-L1-avma1pp_hscx_init: BPROT_NONE??
Feb 15 16:48:21 gbox /netbsd: i4b-L4-i4b_l4_setup_timeout: 950629701: direction 1, shorthold algorithm 0
Feb 15 16:48:21 gbox /netbsd: i4b-L4-i4b_l4_setup_timeout: no idle_timeout configured
Feb 15 16:48:21 gbox /netbsd: i4b-L2-i4b_tx_rr_response: tx RR, unit = 0
Feb 15 16:48:21 gbox /netbsd: i4b-L1-ph_data_req: ISAC_TX_ACTIVE set
Feb 15 16:48:21 gbox /netbsd: i4b-L2-i4b_T200_stop: unit 0
Feb 15 16:48:21 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:21 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:21 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:21 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:21 gbox /netbsd: i4b-L1-avma1pp_hscx_intr: RAWHDLC rx buffer overflow in RPF, in_len=2048
15.02.2000 16:48:22 DBG handle_active: entry I4BAMMOBILE, hangup request!
15.02.2000 16:48:22 DBG FSM event [disconnect-req]: [connected => waitdisconnect]
15.02.2000 16:48:22 DBG F_DRQ: local disconnect request
Feb 15 16:48:22 gbox /netbsd: i4b-L1-avma1pp_hscx_intr: RAWHDLC rx buffer overflow in RPF, in_len=2048
Feb 15 16:48:22 gbox /netbsd: i4b-L4-cd_by_cdid: found cdid - index=1 cdid=132 cr=48
15.02.2000 16:48:22 DBG sendm_disconnect_req: sent DISCONNECT_REQ
Feb 15 16:48:22 gbox /netbsd: i4b-L4-cd_by_cdid: found cdid - index=1 cdid=132 cr=48
Feb 15 16:48:22 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_DISCRQ - L4 DISC REQ]: [ST_U10 - Active => ST_U11 - Disc Req]
Feb 15 16:48:22 gbox /netbsd: i4b-L3-F_DCRQ: FSM function F_DCRQ executing
Feb 15 16:48:22 gbox /netbsd: i4b-L3-tx DISCONNECT: unit 0, cr = 0x30
Feb 15 16:48:22 gbox /netbsd: i4b-L1-ph_data_req: ISAC_TX_ACTIVE set
Feb 15 16:48:22 gbox /netbsd: i4b-L2-i4b_T200_start: unit 0
Feb 15 16:48:22 gbox /netbsd: i4b-L3-T305_start: cr = 48
Feb 15 16:48:22 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:22 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:22 gbox /netbsd: i4b-L2-i4b_rxd_s_frame: rx'd RR, N(R) = 4
Feb 15 16:48:22 gbox /netbsd: i4b-L2-F_MF17: FSM function F_MF17 executing
Feb 15 16:48:22 gbox /netbsd: i4b-L2-i4b_T200_stop: unit 0
Feb 15 16:48:22 gbox /netbsd: i4b-L2-i4b_next_l2state: FSM S-event [EV_RXRR]: [ST_MULTIFR => ST_MULTIFR]
Feb 15 16:48:22 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
15.02.2000 16:48:22 CHD 00132 I4BAMMOBILE incoming call disconnected (local)
15.02.2000 16:48:22 CHD 00132 I4BAMMOBILE cause 0: normal call clearing (I4B)
15.02.2000 16:48:22 CHD 00132 I4BAMMOBILE connected 1 seconds
15.02.2000 16:48:22 DBG set_channel_idle: controller [0] channel B1 set to IDLE!
15.02.2000 16:48:22 DBG incr_free_channels: ctrl 0, now 1 chan free
15.02.2000 16:48:22 DBG FSM event [msg-disc-ind]: [waitdisconnect => idle]
15.02.2000 16:48:22 DBG F_MDI: disconnect indication, local disconnected
Feb 15 16:48:22 gbox /netbsd: i4b-L3-i4b_decode_q931: Call Ref, len 1, val 48, flag 0
Feb 15 16:48:22 gbox /netbsd: i4b-L4-cd_by_unitcr: found cd, index=1 cdid=132 cr=48
Feb 15 16:48:22 gbox /netbsd: i4b-L3-rx RELEASE: unit 0, cr = 0x30
Feb 15 16:48:22 gbox /netbsd: i4b-L3-next_l3state: L3 FSM event [EV_RELEASE - rxd REL]: [ST_U11 - Disc Req => ST_U0 - Null]
Feb 15 16:48:22 gbox /netbsd: i4b-L3-F_11J: FSM function F_11J executing
Feb 15 16:48:22 gbox /netbsd: i4b-L3-T305_stop: cr = 48
Feb 15 16:48:22 gbox /netbsd: i4b-L3-tx RELEASE-COMPLETE: unit 0, cr = 0x30
Feb 15 16:48:22 gbox /netbsd: i4b-L1-ph_data_req: ISAC_TX_ACTIVE set
Feb 15 16:48:22 gbox /netbsd: i4b-L2-i4b_T200_start: unit 0
Feb 15 16:48:22 gbox /netbsd: i4b-L1-avma1pp_hscx_init: unit=0, channel=0, deactivate
Feb 15 16:48:22 gbox /netbsd: i4b-L1-avma1pp_hscx_init: 0 NOT deactivated
Feb 15 16:48:22 gbox /netbsd: i4b-L1-avma1pp_bchannel_setup: unit=0, channel=0, deactivate
Feb 15 16:48:22 gbox /netbsd: i4b-L4-freecd_by_cd: releasing cd - index=1 cdid=132 cr=48
Feb 15 16:48:22 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x10
Feb 15 16:48:22 gbox /netbsd: i4b-L1-avma1pp_hscx_intr: RAWHDLC rx buffer overflow in RPF, in_len=2048
Feb 15 16:48:22 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:22 gbox /netbsd: i4b-L2-i4b_rxd_s_frame: rx'd RR, N(R) = 5
Feb 15 16:48:22 gbox /netbsd: i4b-L2-F_MF17: FSM function F_MF17 executing
Feb 15 16:48:22 gbox /netbsd: i4b-L2-i4b_T200_stop: unit 0
Feb 15 16:48:22 gbox /netbsd: i4b-L2-i4b_next_l2state: FSM S-event [EV_RXRR]: [ST_MULTIFR => ST_MULTIFR]
Feb 15 16:48:22 gbox /netbsd: i4b-L1-avma1pp_hscx_intr: RAWHDLC rx buffer overflow in RPF, in_len=2048
Feb 15 16:48:24 gbox last message repeated 6 times
Feb 15 16:48:24 gbox /netbsd: i4b-L1-isic_isac_irq: unit 0: ista = 0x80
Feb 15 16:48:24 gbox /netbsd: i4b-L1-avma1pp_hscx_intr: RAWHDLC rx buffer overflow in RPF, in_len=2048


To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-isdn" in the body of the message




Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?14505.31479.834993.255113>