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>