Date: Thu, 25 Oct 2001 09:48:55 +0200 From: Christoph Splittgerber <cs@sdata.de> To: freebsd-isdn@FreeBSD.org Subject: problems with Fritz! PCI Message-ID: <3BD7C3E7.DCB9E6BC@sdata.de>
next in thread | raw e-mail | index | archive | help
This is a multi-part message in MIME format.
--------------9ADE5E41C1F43AC34772D97F
Content-Type: text/plain; charset=us-ascii
Content-Transfer-Encoding: 7bit
Hallo,
we have been running i4b for years now without many problems but hell of a
sudden since a few weeks we have to reboot the machine almost every day like a
WinXX host :-(.
I do not have the slightest idea what the logfile entries mean, and I'm
searching for some help. I include an extract from /var/log/messages from the
last 3 days, my isdnd.rc and a dmesg output.
Here we go ...
/kernel: i4b: unit 0, assigned TEI = 115 = 0x73
isdnd[223]: ERR FSM ILLEGAL STATE, event=msg-con-ind: oldstate=waitdialretry
=> newstate=Illegal State]
/kernel: i4b-L3 next_l3state: FSM illegal state, state = ST_U6 - In Pres,
event = EV_DISCRQ - L4 DISC REQ!
isdnd[223]: ERR recover_illegal: ERROR, entry sdata attempting disconnect!
isdnd[223]: ERR recover_illegal: ERROR, entry sdata - reset state/cdid!
isdnd[223]: ERR FSM ILLEGAL STATE, event=msg-con-ind: oldstate=waitdialretry
=> newstate=Illegal State]
/kernel: i4b-L4 T400_timeout: cr = 13
/kernel: i4b-L3 next_l3state: FSM illegal state, state = ST_U6 - In Pres,
event = EV_DISCRQ - L4 DISC REQ!
isdnd[223]: ERR recover_illegal: ERROR, entry sdata attempting disconnect!
isdnd[223]: ERR recover_illegal: ERROR, entry sdata - reset state/cdid!
isdnd[223]: ERR decr_free_channels: controller [0] already 0 free chans!
isdnd[223]: ERR set_channel_busy: controller [0] invalid channel [-1]!
/kernel: i4b-L3 i4b_decode_q931_cs0_ie: IE ChannelID, Channel NOT free!!
/kernel: i4b-L3 F_SIGN: FSM function F_SIGN executing
isdnd[223]: ERR set_channel_busy: controller [0] invalid channel [-1]!
/kernel: i4b-L4 T400_timeout: cr = 14
isdnd[223]: WRN msg_disconnect_ind: cdid not found
isdnd[223]: WRN msg_disconnect_ind: cdid not found
isdnd[223]: ERR FSM ILLEGAL STATE, event=msg-con-ind: oldstate=waitdialretry
=> newstate=Illegal State]
/kernel: i4b-L3 next_l3state: FSM illegal state, state = ST_U6 - In Pres,
event = EV_DISCRQ - L4 DISC REQ!
isdnd[223]: ERR recover_illegal: ERROR, entry sdata attempting disconnect!
isdnd[223]: ERR recover_illegal: ERROR, entry sdata - reset state/cdid!
/kernel: i4b-L3 i4b_decode_q931_cs0_ie: IE ChannelID, Channel NOT free!!
/kernel: i4b-L3 F_SIGN: FSM function F_SIGN executing
/kernel: i4b-L4 T400_timeout: cr = 15
/kernel: i4b-L3 i4b_decode_q931_cs0_ie: IE ChannelID, Channel NOT free!!
isdnd[223]: ERR decr_free_channels: controller [0] already 0 free chans!
isdnd[223]: WRN msg_disconnect_ind: cdid not found
kernel: i4b-L2 i4b_rxd_ack: ((N(R)-1)=0) != (UA=1) !!!
... this somehow recovered but then:
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L1 ifpi_ph_data_req: still in state F3!
/kernel: i4b-L3 T303_timeout: SETUP not answered, cr = 30
/kernel: i4b-L2 i4b_rxd_ack: ((N(R)-1)=127) != (UA=0) !!!
/kernel: i4b-L2 i4b_mph_status_ind: unit 0, persistent deactivation!
/kernel: i4b-L3 i4b_mdl_status_ind: STI_PDEACT: unit 0 TEI = 0 = 0x00
isdnd[223]: ERR set_channel_busy: controller [0] invalid channel [-1]!
isdnd[223]: ERR msg_pdeact_ind: set_channel_idle failed!
/kernel: i4b-L1 ifpi_ph_data_req: still in state F3!
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L2 F_ILL: FSM function F_ILL executing
/kernel: i4b-L2 i4b_next_l2state: FSM illegal state, state = ST_TEI_UNAS,
event = EV_T200EXP!
/kernel: i4b: unit 0, assigned TEI = 116 = 0x74
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L3 T313_timeout: CONN ACK not received, cr = 105
/kernel: i4b-L3 F_SIGN: FSM function F_SIGN executing
/kernel: i4b-L2 i4b_mph_status_ind: unit 0, persistent deactivation!
/kernel: i4b-L3 i4b_mdl_status_ind: STI_PDEACT: unit 0 TEI = 0 = 0x00
/kernel: i4b-L1 ifpi_ph_data_req: still in state F3!
/kernel: i4b: unit 0, assigned TEI = 117 = 0x75
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L1 ifpi_ph_data_req: still in state F3!
/kernel: i4b-L3 T303_timeout: SETUP not answered, cr = 92
/kernel: i4b-L2 i4b_rxd_ack: ((N(R)-1)=127) != (UA=0) !!!
/kernel: i4b-L2 i4b_mdl_error_ind: unit = 0, location = F_TR07
/kernel: i4b-L2 i4b_mdl_error_ind: error = MDL_ERR_F: peer initiated
re-establishment - SABME
/kernel: i4b-L2 i4b_i_frame_queued_up: ERROR, mbuf NULL after IF_DEQUEUE
/kernel: i4b-L2 i4b_mph_status_ind: unit 0, persistent deactivation!
/kernel: i4b-L3 i4b_mdl_status_ind: STI_PDEACT: unit 0 TEI = 0 = 0x00
isdnd[223]: ERR set_channel_busy: controller [0] invalid channel [-1]!
isdnd[223]: ERR msg_pdeact_ind: set_channel_idle failed!
/kernel: i4b: unit 0, assigned TEI = 64 = 0x40
/kernel: i4b-L1 ifpi_ph_data_req: No Space in TX FIFO, state = F7 Activated
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L1 ifpi_ph_data_req: No Space in TX FIFO, state = F7 Activated
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L3 T303_timeout: SETUP not answered, cr = 10
/kernel: i4b-L2 i4b_rxd_ack: ((N(R)-1)=127) != (UA=0) !!!
/kernel: i4b-L2 i4b_mdl_error_ind: unit = 0, location = F_TR07
/kernel: i4b-L2 i4b_mdl_error_ind: error = MDL_ERR_F: peer initiated
re-establishment - SABME
... and at this point the link was unusable and we hat to reboot !
Than again ....
/kernel: i4b-L2 i4b_rxd_ack: ((N(R)-1)=15) != (UA=16) !!!
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L1 ifpi_ph_data_req: still in state F3!
/kernel: i4b-L3 T303_timeout: SETUP not answered, cr = 63
/kernel: i4b-L2 i4b_rxd_ack: ((N(R)-1)=127) != (UA=0) !!!
/kernel: i4b-L2 i4b_mdl_error_ind: unit = 0, location = F_TR07
/kernel: i4b-L2 i4b_mdl_error_ind: error = MDL_ERR_F: peer initiated
re-establishment - SABME
/kernel: i4b-L2 i4b_i_frame_queued_up: ERROR, mbuf NULL after IF_DEQUEUE
/kernel: i4b-L2 i4b_mph_status_ind: unit 0, persistent deactivation!
/kernel: i4b-L3 i4b_mdl_status_ind: STI_PDEACT: unit 0 TEI = 0 = 0x00
isdnd[214]: ERR set_channel_busy: controller [0] invalid channel [-1]!
isdnd[214]: ERR msg_pdeact_ind: set_channel_idle failed!
/kernel: i4b: unit 0, assigned TEI = 67 = 0x43
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L1 ifpi_ph_data_req: still in state F3!
/kernel: i4b-L3 T303_timeout: SETUP not answered, cr = 103
/kernel: i4b-L2 i4b_rxd_ack: ((N(R)-1)=127) != (UA=0) !!!
/kernel: i4b-L2 i4b_mdl_error_ind: unit = 0, location = F_TR07
/kernel: i4b-L2 i4b_mdl_error_ind: error = MDL_ERR_F: peer initiated
re-establishment - SABME
/kernel: i4b-L2 i4b_i_frame_queued_up: ERROR, mbuf NULL after IF_DEQUEUE
/kernel: i4b-L2 i4b_mph_status_ind: unit 0, persistent deactivation!
/kernel: i4b-L3 i4b_mdl_status_ind: STI_PDEACT: unit 0 TEI = 0 = 0x00
isdnd[214]: ERR set_channel_busy: controller [0] invalid channel [-1]!
isdnd[214]: ERR msg_pdeact_ind: set_channel_idle failed!
/kernel: i4b: unit 0, assigned TEI = 68 = 0x44
... this again somehow recovered after a while but ...
/kernel: i4b-L1 ifpi_ph_data_req: No Space in TX FIFO, state = F7 Activated
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L3 next_l3state: FSM illegal state, state = ST_U6 - In Pres,
event = EV_DISCRQ - L4 DISC REQ!
isdnd[214]: ERR FSM ILLEGAL STATE, event=msg-con-ind: oldstate=waitdialretry
=> newstate=Illegal State]
isdnd[214]: ERR recover_illegal: ERROR, entry sdata attempting disconnect!
isdnd[214]: ERR recover_illegal: ERROR, entry sdata - reset state/cdid!
/kernel: i4b-L3 i4b_decode_q931_cs0_ie: IE ChannelID, Channel NOT free!!
/kernel: i4b-L4 T400_timeout: cr = 22
isdnd[214]: ERR FSM ILLEGAL STATE, event=msg-con-ind: oldstate=waitdialretry
=> newstate=Illegal State]
/kernel: i4b-L3 next_l3state: FSM illegal state, state = ST_U6 - In Pres,
event = EV_DISCRQ - L4 DISC REQ!
isdnd[214]: ERR recover_illegal: ERROR, entry sdata attempting disconnect!
isdnd[214]: ERR recover_illegal: ERROR, entry sdata - reset state/cdid!
isdnd[214]: ERR decr_free_channels: controller [0] already 0 free chans!
/kernel: i4b-L3 i4b_decode_q931_cs0_ie: IE ChannelID, Channel NOT free!!
/kernel: i4b-L3 F_SIGN: FSM function F_SIGN executing
/kernel: i4b-L4 T400_timeout: cr = 24
isdnd[214]: WRN msg_disconnect_ind: cdid not found
isdnd[214]: WRN msg_disconnect_ind: cdid not found
/kernel: i4b-L2 i4b_T200_timeout: unit 0, RC = 0
/kernel: i4b-L3 T303_timeout: SETUP not answered, cr = 29
/kernel: i4b-L2 i4b_rxd_ack: ((N(R)-1)=127) != (UA=0) !!!
/kernel: i4b-L2 i4b_mdl_error_ind: unit = 0, location = F_TR07
/kernel: i4b-L2 i4b_mdl_error_ind: error = MDL_ERR_F: peer initiated
re-establishment - SABME
/kernel: i4b-L2 i4b_i_frame_queued_up: ERROR, mbuf NULL after IF_DEQUEUE
... again we had to reboot to get the isdn subsystem running again.
I include a dmesg.txt and isdndrc.txt as an attachment in the hope that it is
of any help.
Thank's very much in advance,
Christoph
Splittgerber
--------------9ADE5E41C1F43AC34772D97F
Content-Type: text/plain; charset=us-ascii;
name="dmesg.txt"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline;
filename="dmesg.txt"
Copyright (c) 1992-2001 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
FreeBSD 4.4-RC #0: Wed Sep 5 17:54:46 CEST 2001
root@:/usr/obj/usr/src/sys/GALILEO
Timecounter "i8254" frequency 1193182 Hz
CPU: Pentium III/Pentium III Xeon/Celeron (752.83-MHz 686-class CPU)
Origin = "GenuineIntel" Id = 0x686 Stepping = 6
Features=0x383f9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE>
real memory = 536788992 (524208K bytes)
avail memory = 518983680 (506820K bytes)
Preloaded elf kernel "kernel" at 0xc039a000.
Pentium Pro MTRR support enabled
Using $PIR table, 8 entries at 0xc00f0e80
npx0: <math processor> on motherboard
npx0: INT 16 interface
pcib0: <Intel 82443BX (440 BX) host to PCI bridge> on motherboard
pci0: <PCI bus> on pcib0
pcib1: <Intel 82443BX (440 BX) PCI-PCI (AGP) bridge> at device 1.0 on pci0
pci1: <PCI bus> on pcib1
pci1: <ATI Mach64-GM graphics accelerator> at 0.0 irq 11
isab0: <Intel 82371AB PCI to ISA bridge> at device 4.0 on pci0
isa0: <ISA bus> on isab0
pci0: <Intel PIIX4 ATA controller> at 4.1
uhci0: <Intel 82371AB/EB (PIIX4) USB controller> port 0xb400-0xb41f irq 12 at device 4.2 on pci0
usb0: <Intel 82371AB/EB (PIIX4) USB controller> on uhci0
usb0: USB revision 1.0
uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
uhub1: ALCOR Generic USB Hub, class 9/0, rev 1.10/1.00, addr 2
uhub1: 4 ports with 4 removable, self powered
chip1: <Intel 82371AB Power management controller> port 0xe800-0xe80f at device 4.3 on pci0
pci0: <unknown card> (vendor=0x105a, dev=0x0d30) at 7.0 irq 10
ifpi0: <AVM Fritz!Card PCI> port 0x9400-0x941f mem 0xe0800000-0xe080001f irq 12 at device 9.0 on pci0
ifpi0: ISAC 2085 Version A1/A2 or 2086/2186 Version 1.1 (IOM-2)
ifpi0: passive stack unit 0
ahc0: <Adaptec 29160N Ultra160 SCSI adapter> port 0x9000-0x90ff mem 0xe0000000-0xe0000fff irq 10 at device 11.0 on pci0
aic7892: Ultra160 Wide Channel A, SCSI Id=7, 32/255 SCBs
vr0: <VIA VT6102 Rhine II 10/100BaseTX> port 0x8800-0x88ff mem 0xdf800000-0xdf8000ff irq 12 at device 13.0 on pci0
vr0: Ethernet address: 00:05:5d:09:ed:15
miibus0: <MII bus> on vr0
ukphy0: <Generic IEEE 802.3u media interface> on miibus0
ukphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
orm0: <Option ROMs> at iomem 0xc0000-0xc7fff,0xd4000-0xd7fff on isa0
fdc0: <NEC 72065B or clone> at port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on isa0
fdc0: FIFO enabled, 8 bytes threshold
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
kbd0 at atkbd0
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0
sio0: type 16550A
sio1 at port 0x2f8-0x2ff irq 3 on isa0
sio1: type 16550A
ppc0: <Parallel port> at port 0x378-0x37f irq 7 on isa0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/9 bytes threshold
plip0: <PLIP network interface> on ppbus0
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
i4btel: 2 ISDN telephony interface device(s) attached
i4brbch: 4 raw B channel access device(s) attached
i4bipr: 4 IP over raw HDLC ISDN device(s) attached (VJ header compression)
i4bctl: ISDN system control port attached
i4btrc: 4 ISDN trace device(s) attached
i4bisppp: 4 ISDN SyncPPP device(s) attached (VJ header compression)
i4b: ISDN call control device attached
IP packet filtering initialized, divert enabled, rule-based forwarding enabled, default to deny, logging limited to 1000 packets/entry by default
Waiting 8 seconds for SCSI devices to settle
sa0 at ahc0 bus 0 target 6 lun 0
sa0: <HP C5683A C908> Removable Sequential Access SCSI-2 device
sa0: 40.000MB/s transfers (20.000MHz, offset 32, 16bit)
da0 at ahc0 bus 0 target 0 lun 0
da0: <IBM DDYS-T36950N S96H> Fixed Direct Access SCSI-3 device
da0: 160.000MB/s transfers (80.000MHz, offset 63, 16bit), Tagged Queueing Enabled
da0: 35003MB (71687340 512 byte sectors: 255H 63S/T 4462C)
Mounting root from ufs:/dev/da0s1a
cd0 at ahc0 bus 0 target 4 lun 0
cd0: <TEAC CD-ROM CD-532S 1.0A> Removable CD-ROM SCSI-2 device
cd0: 20.000MB/s transfers (20.000MHz, offset 16)
cd0: Attempt to query device size failed: NOT READY, Medium not present
i4b: unit 0, assigned TEI = 69 = 0x45
--------------9ADE5E41C1F43AC34772D97F
Content-Type: text/plain; charset=us-ascii;
name="isdndrc.txt"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline;
filename="isdndrc.txt"
entry
name = sdata
usrdevicename = ipr
usrdeviceunit = 0
isdncontroller = 0
isdnchannel = -1
direction = inout
local-phone-incoming = 126
remote-phone-incoming = 00711xxxxxxxx
local-phone-dialout = 126
remote-phone-dialout = 0010190711xxxxxxxx
remdial-handling = first
dialin-reaction = accept
dialout-type = normal
callbackwait = 1
b1protocol = hdlc
ratetype = 0
unitlength = 60
unitlengthsrc = conf
idletime-incoming = 60
idletime-outgoing = 20
earlyhangup = 2
dialretries = 3
dialrandincr = off
recoverytime = 5
usedown = off
downtries = 5
downtime = 180
connectprog = "ntpsync"
#disconnectprog = "ip-down"
entry
name = portus
usrdevicename = isp
usrdeviceunit = 0
isdncontroller = 0
isdnchannel = -1
direction = in
local-phone-incoming = 126
remote-phone-incoming = 0xxxxxx
local-phone-dialout = 126
remote-phone-dialout = 0xxxxxx
dialin-reaction = accept
dialout-type = normal
b1protocol = hdlc
ratetype = 0
unitlength = 90
unitlengthsrc = conf
idletime-incoming = 600
idletime-outgoing = 30
earlyhangup = 2
dialretries = 3
dialrandincr = on
recoverytime = 5
usedown = off
downtries = 5
downtime = 600
connectprog = "isp0-up"
disconnectprog = "isp0-down"
--------------9ADE5E41C1F43AC34772D97F--
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?3BD7C3E7.DCB9E6BC>