From owner-freebsd-current Thu Nov 25 14:38:36 1999 Delivered-To: freebsd-current@freebsd.org Received: from shrewd.knigma.org (shrewd.demon.co.uk [212.229.151.45]) by hub.freebsd.org (Postfix) with ESMTP id CC34D14D21; Thu, 25 Nov 1999 14:37:15 -0800 (PST) (envelope-from markk@knigma.org) Received: from lap.knigma.org (lap.knigma.org [10.128.148.202]) by shrewd.knigma.org (8.9.3/8.9.3) with SMTP id WAA00280; Thu, 25 Nov 1999 22:37:22 GMT (envelope-from markk@knigma.org) Message-ID: Date: Thu, 25 Nov 1999 22:36:01 +0000 To: freebsd-current@freebsd.org, freebsd-isdn@freebsd.org From: Mark Knight Subject: Re: Panic caused by mbuf exhaustion in i4b with AVM PCI References: <199911252210.XAA12659@peedub.muc.de> In-Reply-To: <199911252210.XAA12659@peedub.muc.de> MIME-Version: 1.0 X-Mailer: Turnpike Integrated Version 4.02 U Sender: owner-freebsd-current@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG In article <199911252210.XAA12659@peedub.muc.de>, Gary Jennejohn writes >Can you a) increase the size of the message buffer in your config file >(options MSGBUF_SIZE=81920, for example) b) turn on ALL the >trace in the kernel with isdndebug c) cause the panic to happen again >and get a crash dump ? And here it is... This represents a single call into i4b, clearing down part way through the outgoing message. I did't leave it to panic but that would have only been a matter of time. Mounting root from ufs:/dev/ad0a i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L3-i4b_decode_q931: Call Ref, len 1, val 1, flag 0 i4b-L4-reserve_cd: found free cd - index=0 cdid=1 i4b-L3-rx SETUP: unit 0, cr = 0x01 i4b-L3-i4b_decode_q931_cs0_ie: IEI_SENDCOMPL i4b-L3-i4b_decode_q931_cs0_ie: IEI_BEARERCAP - Telephony i4b-L3-i4b_decode_q931_cs0_ie: IEI_CHANNELID - channel 1, exclusive = 1 i4b-L3-i4b_decode_q931_cs0_ie: IEI_PROGRESSINDICATOR i4b-L3-i4b_decode_q931_cs0_ie: IEI_CALLED = 316055 i4b-L3-next_l3state: L3 FSM event [EV_SETUP - rxd SETUP]: [ST_U0 - Null => ST_U6 - In Pres] i4b-L3-F_00H: FSM function F_00H executing i4b-L4-T400_start: cr = 1 i4b-L4-cd_by_cdid: found cdid - index=0 cdid=1 cr=1 i4b-L4-T400_stop: cr = 1 i4b-L4-i4bioctl: I4B_CONNECT_RESP max_idle_time set to 5 seconds i4b-L4-cd_by_cdid: found cdid - index=0 cdid=1 cr=1 i4b-L4-T400_stop: cr = 1 i4b-L3-next_l3state: L3 FSM event [EV_SETACRS - L4 accept RSP]: [ST_U6 - In Pres => ST_SUSE - Subroutine sets state] i4b-L3-F_06E: FSM function F_06E executing i4b-L2-DL-ESTABLISH-REQ: unit 0 i4b-L2-i4b_next_l2state: FSM event [EV_DLESTRQ]: [ST_TEI_UNAS/0 => ST_EST_AW_TEI/2] i4b-L2-F_TU01: FSM function F_TU01 executing i4b-L2-MDL-ASSIGN-IND: unit 0 i4b-L2-i4b_tei_assign: tx TEI ID_Request i4b-L2-i4b_T202_start: unit 0 i4b-L1-ph_data_req: ISAC_TX_ACTIVE set i4b-L3-T313_start: cr = 1 i4b-L1-isic_isac_irq: unit 0: ista = 0x10 i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L2-i4b_T202_stop: unit 0 i4b-L3-i4b_mdl_status_ind: unit = 0, status = 3, parm = 115 i4b-L3-i4b_mdl_status_ind: STI_TEIASG: unit 0 TEI = 115 = 0x73 i4b: unit 0, assigned TEI = 115 = 0x73 i4b-L2-i4b_tei_rx_frame: TEI ID Assign - TEI = 115 i4b-L2-i4b_next_l2state: FSM event [EV_MDASGRQ]: [ST_EST_AW_TEI/2 => ST_AW_EST/4] i4b-L2-F_TE03: FSM function F_TE03 executing i4b-L2-i4b_tx_sabme: tx SABME, tei = 115 i4b-L1-ph_data_req: ISAC_TX_ACTIVE set i4b-L2-i4b_T200_restart: unit 0 i4b-L1-isic_isac_irq: unit 0: ista = 0x10 i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L2-i4b_rxd_u_frame: UA, sapi = 0, tei = 115 i4b-L2-F_AE09: FSM function F_AE09 executing i4b-L3-i4b_mdl_status_ind: unit = 0, status = 2, parm = 1 i4b-L3-i4b_mdl_status_ind: STI_L2STAT: unit 0 layer 2 = up i4b-L2-i4b_T200_stop: unit 0 i4b-L2-i4b_next_l2state: FSM S-event [EV_RXUA]: [ST_AW_EST => ST_MULTIFR] i4b-L2-i4b_next_l2state: FSM executing postfsmfunc! i4b-L2-DL-ESTABLISH-CONF: unit 0 i4b-L3-i4b_dl_establish_cnf: unit=0, index=0 cdid=1 cr=1 i4b-L3-next_l3state: L3 FSM event [EV_DLESTCF - L2 DL_Est_Cnf]: [ST_IWA - In Wait EST-Accept => ST_U8 - In ConReq] i4b-L3-F_DECF2: FSM function F_DECF2 executing i4b-L3-tx CONNECT: unit 0, cr = 0x01 i4b-L1-ph_data_req: ISAC_TX_ACTIVE set i4b-L2-i4b_T200_start: unit 0 i4b-L1-isic_isac_irq: unit 0: ista = 0x10 i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L2-i4b_rxd_s_frame: rx'd RR, N(R) = 1 i4b-L2-F_MF17: FSM function F_MF17 executing i4b-L2-i4b_T200_stop: unit 0 i4b-L2-i4b_next_l2state: FSM S-event [EV_RXRR]: [ST_MULTIFR => ST_MULTIFR] i4b-L1-isic_isac_irq: unit 0: ista = 0x81 i4b-L1-isic_isac_exir_hdlr: EXIRQ Rx Frame Overflow i4b-L3-i4b_decode_q931: Call Ref, len 1, val 1, flag 0 i4b-L4-cd_by_unitcr: found cd, index=0 cdid=1 cr=1 i4b-L3-rx CONNECT-ACK: unit 0, cr = 0x01 i4b-L3-next_l3state: L3 FSM event [EV_CONACK - rxd CONN ACK]: [ST_U8 - In ConReq => ST_U10 - Active] i4b-L3-F_08R: FSM function F_08R executing i4b-L3-T313_stop: cr = 1 i4b-L4-i4b_l4_connect_active_ind: last_active/connect_time=943568877 i4b-L1-avma1pp_bchannel_setup: unit=0, channel=1, activate i4b-L1-avma1pp_hscx_init: unit=0, channel=1, activate i4b-L1-avma1pp_hscx_init: BPROT_NONE?? i4b-L4-i4b_l4_setup_timeout: 943568877: direction 1, shorthold algorithm 0 i4b-L4-i4b_l4_setup_timeout: 943568877: incoming-call, setup max_idle_time to 5 i4b-L2-i4b_tx_rr_response: tx RR, unit = 0 i4b-L1-ph_data_req: ISAC_TX_ACTIVE set i4b-L2-i4b_T200_stop: unit 0 i4b-L1-isic_isac_irq: unit 0: ista = 0x10 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568877: incoming-call, activity, last_active=943568877, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568878: incoming-call, activity, last_active=943568878, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568878: incoming-call, activity, last_active=943568878, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568879: incoming-call, activity, last_active=943568879, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568879: incoming-call, activity, last_active=943568879, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568880: incoming-call, activity, last_active=943568880, max_idle=5 i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568880: incoming-call, activity, last_active=943568880, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568881: incoming-call, activity, last_active=943568881, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568881: incoming-call, activity, last_active=943568881, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568882: incoming-call, activity, last_active=943568882, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L4-i4b_idle_check: 943568882: incoming-call, activity, last_active=943568882, max_idle=5 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L3-i4b_decode_q931: Call Ref, len 1, val 1, flag 0 i4b-L4-cd_by_unitcr: found cd, index=0 cdid=1 cr=1 i4b-L3-rx DISCONNECT: unit 0, cr = 0x01 i4b-L3-i4b_decode_q931_cs0_ie: IEI_CAUSE = 16 i4b-L3-i4b_decode_q931_cs0_ie: IEI_PROGRESSINDICATOR i4b-L3-next_l3state: L3 FSM event [EV_DISCONN - rxd DISC]: [ST_U10 - Active => ST_U12 - Disc Ind] i4b-L3-F_DISC: FSM function F_DISC executing i4b-L3-T303_stop: cr = 1 i4b-L3-T305_stop: cr = 1 i4b-L3-T308_stop: cr = 1 i4b-L3-T309_stop: cr = 1 i4b-L3-T310_stop: cr = 1 i4b-L3-T313_stop: cr = 1 i4b-L3-tx RELEASE: unit 0, cr = 0x01 i4b-L1-ph_data_req: ISAC_TX_ACTIVE set i4b-L2-i4b_T200_start: unit 0 i4b-L3-T308_start: cr = 1 i4b-L1-isic_isac_irq: unit 0: ista = 0x90 i4b-L2-i4b_rxd_s_frame: rx'd RR, N(R) = 2 i4b-L2-F_MF17: FSM function F_MF17 executing i4b-L2-i4b_T200_stop: unit 0 i4b-L2-i4b_next_l2state: FSM S-event [EV_RXRR]: [ST_MULTIFR => ST_MULTIFR] i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L3-i4b_decode_q931: Call Ref, len 1, val 1, flag 0 i4b-L4-cd_by_unitcr: found cd, index=0 cdid=1 cr=1 i4b-L3-rx RELEASE-COMPLETE: unit 0, cr = 0x01 i4b-L3-next_l3state: L3 FSM event [EV_RELCOMP - rxd REL COMPL]: [ST_U12 - Disc Ind => ST_U0 - Null] i4b-L3-F_RELCP: FSM function F_RELCP executing i4b-L3-T303_stop: cr = 1 i4b-L3-T305_stop: cr = 1 i4b-L3-T308_stop: cr = 1 i4b-L3-T309_stop: cr = 1 i4b-L3-T310_stop: cr = 1 i4b-L3-T313_stop: cr = 1 i4b-L1-avma1pp_hscx_init: unit=0, channel=1, deactivate i4b-L1-avma1pp_bchannel_setup: unit=0, channel=1, deactivate i4b-L4-freecd_by_cd: releasing cd - index=0 cdid=1 cr=1 i4b-L2-i4b_tx_rr_response: tx RR, unit = 0 i4b-L1-ph_data_req: ISAC_TX_ACTIVE set i4b-L2-i4b_T200_stop: unit 0 i4b-L1-isic_isac_irq: unit 0: ista = 0x10 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L1-avma1pp_hscx_intr: receive data overflow i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L1-isic_isac_irq: unit 0: ista = 0x80 i4b-L1-avma1pp_hscx_intr: receive data overflow -- Mark Knight To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-current" in the body of the message