Messages in this thread |  | | Date | Tue, 2 Oct 2001 02:23:05 -0700 (PDT) | From | Ryan Mack <> | Subject | [BUG] 2.4.10/0.9.10 "VFS: brelse: Trying to free free buffer" |
| |
Unfortunately I don't have an oops or a stack trace to report where this message came from, but at least maybe this report can alert someone who can spot the bug in the code. This occurred under light usage on an ext3 partition built on a 2 disk RAID-1 mirrored array.
The array was either two IDE disks using data-ordered mode or two SCSI disks using data-journal mode.
Relevant files attached.
Thanks, Ryan Mack Linux version 2.4.10 (root@mackman.net) (gcc version 2.96 20000731 (Red Hat Linux 7.1 2.96-85)) #1 SMP Mon Sep 24 02:37:41 PDT 2001 BIOS-provided physical RAM map: BIOS-e820: 0000000000000000 - 00000000000a0000 (usable) BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) BIOS-e820: 0000000000100000 - 000000002fffe000 (usable) BIOS-e820: 000000002fffe000 - 0000000030000000 (reserved) BIOS-e820: 00000000fec00000 - 00000000fec10000 (reserved) BIOS-e820: 00000000fee00000 - 00000000fee10000 (reserved) BIOS-e820: 00000000ffe00000 - 0000000100000000 (reserved) found SMP MP-table at 000fe710 hm, page 000fe000 reserved twice. hm, page 000ff000 reserved twice. hm, page 000f0000 reserved twice. On node 0 totalpages: 196606 zone(0): 4096 pages. zone(1): 192510 pages. zone(2): 0 pages. Intel MultiProcessor Specification v1.4 Virtual Wire compatibility mode. OEM ID: DELL Product ID: WS 410 APIC at: 0xFEE00000 Processor #0 Pentium(tm) Pro APIC version 17 Processor #1 Pentium(tm) Pro APIC version 17 I/O APIC #2 Version 17 at 0xFEC00000. Processors: 2 Kernel command line: BOOT_IMAGE=linux ro root=900 BOOT_FILE=/boot/vmlinuz rootflags=data=journal Initializing CPU#0 Detected 497.436 MHz processor. Console: colour VGA+ 80x25 Calibrating delay loop... 992.87 BogoMIPS Memory: 771836k/786424k available (1015k kernel code, 14204k reserved, 295k data, 196k init, 0k highmem) Dentry-cache hash table entries: 131072 (order: 8, 1048576 bytes) Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) Mount-cache hash table entries: 16384 (order: 5, 131072 bytes) Buffer-cache hash table entries: 65536 (order: 6, 262144 bytes) Page-cache hash table entries: 262144 (order: 8, 1048576 bytes) CPU: Before vendor init, caps: 0383fbff 00000000 00000000, vendor = 0 CPU: L1 I cache: 16K, L1 D cache: 16K CPU: L2 cache: 512K Intel machine check architecture supported. Intel machine check reporting enabled on CPU#0. CPU: After vendor init, caps: 0383fbff 00000000 00000000 00000000 CPU: After generic, caps: 0383fbff 00000000 00000000 00000000 CPU: Common caps: 0383fbff 00000000 00000000 00000000 Enabling fast FPU save and restore... done. Enabling unmasked SIMD FPU exception support... done. Checking 'hlt' instruction... OK. POSIX conformance testing by UNIFIX mtrr: v1.40 (20010327) Richard Gooch (rgooch@atnf.csiro.au) mtrr: detected mtrr type: Intel CPU: Before vendor init, caps: 0383fbff 00000000 00000000, vendor = 0 CPU: L1 I cache: 16K, L1 D cache: 16K CPU: L2 cache: 512K Intel machine check reporting enabled on CPU#0. CPU: After vendor init, caps: 0383fbff 00000000 00000000 00000000 CPU: After generic, caps: 0383fbff 00000000 00000000 00000000 CPU: Common caps: 0383fbff 00000000 00000000 00000000 CPU0: Intel Pentium III (Katmai) stepping 03 per-CPU timeslice cutoff: 1461.79 usecs. enabled ExtINT on CPU#0 ESR value before enabling vector: 00000040 ESR value after enabling vector: 00000000 Booting processor 1/1 eip 2000 Initializing CPU#1 masked ExtINT on CPU#1 ESR value before enabling vector: 00000000 ESR value after enabling vector: 00000000 Calibrating delay loop... 992.87 BogoMIPS CPU: Before vendor init, caps: 0383fbff 00000000 00000000, vendor = 0 CPU: L1 I cache: 16K, L1 D cache: 16K CPU: L2 cache: 512K Intel machine check reporting enabled on CPU#1. CPU: After vendor init, caps: 0383fbff 00000000 00000000 00000000 CPU: After generic, caps: 0383fbff 00000000 00000000 00000000 CPU: Common caps: 0383fbff 00000000 00000000 00000000 CPU1: Intel Pentium III (Katmai) stepping 03 Total of 2 processors activated (1985.74 BogoMIPS). ENABLING IO-APIC IRQs ...changing IO-APIC physical APIC ID to 2 ... ok. init IO_APIC IRQs IO-APIC (apicid-pin) 2-0, 2-13, 2-20, 2-21, 2-22, 2-23 not connected. ..TIMER: vector=0x31 pin1=2 pin2=0 number of MP IRQ sources: 41. number of IO-APIC #2 registers: 24. testing the IO APIC.......................
IO APIC #2...... .... register #00: 02000000 ....... : physical APIC id: 02 .... register #01: 00170011 ....... : max redirection entries: 0017 ....... : IO APIC version: 0011 .... register #02: 00000000 ....... : arbitration: 00 .... IRQ redirection table: NR Log Phy Mask Trig IRR Pol Stat Dest Deli Vect: 00 000 00 1 0 0 0 0 0 0 00 01 003 03 0 0 0 0 0 1 1 39 02 003 03 0 0 0 0 0 1 1 31 03 003 03 0 0 0 0 0 1 1 41 04 003 03 0 0 0 0 0 1 1 49 05 003 03 0 0 0 0 0 1 1 51 06 003 03 0 0 0 0 0 1 1 59 07 003 03 0 0 0 0 0 1 1 61 08 003 03 0 0 0 0 0 1 1 69 09 003 03 0 0 0 0 0 1 1 71 0a 003 03 0 0 0 0 0 1 1 79 0b 003 03 0 0 0 0 0 1 1 81 0c 003 03 0 0 0 0 0 1 1 89 0d 000 00 1 0 0 0 0 0 0 00 0e 003 03 0 0 0 0 0 1 1 91 0f 003 03 0 0 0 0 0 1 1 99 10 003 03 1 1 0 1 0 1 1 A1 11 003 03 1 1 0 1 0 1 1 A9 12 003 03 1 1 0 1 0 1 1 B1 13 003 03 1 1 0 1 0 1 1 B9 14 000 00 1 0 0 0 0 0 0 00 15 000 00 1 0 0 0 0 0 0 00 16 000 00 1 0 0 0 0 0 0 00 17 000 00 1 0 0 0 0 0 0 00 IRQ to pin mappings: IRQ0 -> 0:2 IRQ1 -> 0:1 IRQ3 -> 0:3 IRQ4 -> 0:4 IRQ5 -> 0:5 IRQ6 -> 0:6 IRQ7 -> 0:7 IRQ8 -> 0:8 IRQ9 -> 0:9 IRQ10 -> 0:10 IRQ11 -> 0:11 IRQ12 -> 0:12 IRQ14 -> 0:14 IRQ15 -> 0:15 IRQ16 -> 0:16 IRQ17 -> 0:17 IRQ18 -> 0:18 IRQ19 -> 0:19 .................................... done. Using local APIC timer interrupts. calibrating APIC timer ... ..... CPU clock speed is 497.4466 MHz. ..... host bus clock speed is 99.4891 MHz. cpu: 0, clocks: 994891, slice: 331630 CPU0<T0:994880,T1:663248,D:2,S:331630,C:994891> cpu: 1, clocks: 994891, slice: 331630 CPU1<T0:994880,T1:331616,D:4,S:331630,C:994891> checking TSC synchronization across CPUs: passed. PCI: PCI BIOS revision 2.10 entry at 0xfcf1e, last bus=2 PCI: Using configuration type 1 PCI: Probing PCI hardware Unknown bridge resource 0: assuming transparent PCI: Using IRQ router PIIX [8086/7110] at 00:07.0 PCI->APIC IRQ transform: (B0,I7,P3) -> 19 PCI->APIC IRQ transform: (B0,I17,P0) -> 19 PCI->APIC IRQ transform: (B1,I0,P0) -> 16 PCI->APIC IRQ transform: (B2,I10,P0) -> 18 PCI->APIC IRQ transform: (B2,I14,P0) -> 18 Limiting direct PCI/PCI transfers. Linux NET4.0 for Linux 2.4 Based upon Swansea University Computer Society NET3.039 Starting kswapd Journalled Block Device driver loaded pty: 256 Unix98 ptys configured block: 128 slots per queue, batch=16 Uniform Multi-Platform E-IDE driver Revision: 6.31 ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx PIIX4: IDE controller on PCI bus 00 dev 39 PIIX4: chipset revision 1 PIIX4: not 100% native mode: will probe irqs later ide0: BM-DMA at 0xffa0-0xffa7, BIOS settings: hda:DMA, hdb:pio ide1: BM-DMA at 0xffa8-0xffaf, BIOS settings: hdc:DMA, hdd:pio hda: IBM-DTLA-307045, ATA DISK drive hdc: IBM-DTLA-307045, ATA DISK drive ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 ide1 at 0x170-0x177,0x376 on irq 15 hda: 90069840 sectors (46116 MB) w/1916KiB Cache, CHS=5606/255/63, UDMA(33) hdc: 90069840 sectors (46116 MB) w/1916KiB Cache, CHS=89355/16/63, UDMA(33) Partition check: hda: hda1 hdc: [PTBL] [5606/255/63] hdc1 SCSI subsystem driver Revision: 1.00 scsi0 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 6.2.1 <Adaptec aic7890/91 Ultra2 SCSI adapter> aic7890/91: Ultra2 Wide Channel A, SCSI Id=7, 32/255 SCBs
scsi1 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 6.2.1 <Adaptec aic7880 Ultra SCSI adapter> aic7880: Ultra Wide Channel A, SCSI Id=7, 16/255 SCBs
Vendor: IBM Model: DDYS-T09170N Rev: S96H Type: Direct-Access ANSI SCSI revision: 03 Vendor: IBM Model: DDYS-T09170N Rev: S96H Type: Direct-Access ANSI SCSI revision: 03 scsi0:0:0:0: Tagged Queuing enabled. Depth 253 scsi0:0:1:0: Tagged Queuing enabled. Depth 253 Vendor: YAMAHA Model: CRW4416S Rev: 1.0g Type: CD-ROM ANSI SCSI revision: 02 Vendor: NEC Model: CD-ROM DRIVE:466 Rev: 1.06 Type: CD-ROM ANSI SCSI revision: 02 Attached scsi disk sda at scsi0, channel 0, id 0, lun 0 Attached scsi disk sdb at scsi0, channel 0, id 1, lun 0 (scsi0:A:0): 80.000MB/s transfers (40.000MHz, offset 63, 16bit) SCSI device sda: 17916240 512-byte hdwr sectors (9173 MB) sda: sda1 sda2 sda3 (scsi0:A:1): 80.000MB/s transfers (40.000MHz, offset 63, 16bit) SCSI device sdb: 17916240 512-byte hdwr sectors (9173 MB) sdb: sdb1 sdb2 sdb3 md: raid1 personality registered as nr 3 md: md driver 0.90.0 MAX_MD_DEVS=256, MD_SB_DISKS=27 md: Autodetecting RAID arrays. (read) hda1's sb offset: 45030080 [events: 00000039] (read) hdc1's sb offset: 45030080 [events: 00000039] (read) sda1's sb offset: 2104384 [events: 0000003f] (read) sda2's sb offset: 5799360 [events: 0000003f] (read) sdb1's sb offset: 2104384 [events: 0000003f] (read) sdb2's sb offset: 5799360 [events: 0000003f] md: autorun ... md: considering sdb2 ... md: adding sdb2 ... md: adding sda2 ... md: created md1 md: bind<sda2,1> md: bind<sdb2,2> md: running: <sdb2><sda2> md: sdb2's event counter: 0000003f md: sda2's event counter: 0000003f md: RAID level 1 does not need chunksize! Continuing anyway. md1: max total readahead window set to 124k md1: 1 data-disks, max readahead per data-disk: 124k raid1: device sdb2 operational as mirror 0 raid1: device sda2 operational as mirror 1 raid1: raid set md1 active with 2 out of 2 mirrors md: updating md1 RAID superblock on device md: sdb2 [events: 00000040](write) sdb2's sb offset: 5799360 md: sda2 [events: 00000040](write) sda2's sb offset: 5799360 md: considering sdb1 ... md: adding sdb1 ... md: adding sda1 ... md: created md0 md: bind<sda1,1> md: bind<sdb1,2> md: running: <sdb1><sda1> md: sdb1's event counter: 0000003f md: sda1's event counter: 0000003f md: RAID level 1 does not need chunksize! Continuing anyway. md0: max total readahead window set to 124k md0: 1 data-disks, max readahead per data-disk: 124k raid1: device sdb1 operational as mirror 1 raid1: device sda1 operational as mirror 0 raid1: raid set md0 active with 2 out of 2 mirrors md: updating md0 RAID superblock on device md: sdb1 [events: 00000040](write) sdb1's sb offset: 2104384 md: sda1 [events: 00000040](write) sda1's sb offset: 2104384 md: considering hdc1 ... md: adding hdc1 ... md: adding hda1 ... md: created md2 md: bind<hda1,1> md: bind<hdc1,2> md: running: <hdc1><hda1> md: hdc1's event counter: 00000039 md: hda1's event counter: 00000039 md: RAID level 1 does not need chunksize! Continuing anyway. md2: max total readahead window set to 124k md2: 1 data-disks, max readahead per data-disk: 124k raid1: device hdc1 operational as mirror 1 raid1: device hda1 operational as mirror 0 raid1: raid set md2 active with 2 out of 2 mirrors md: updating md2 RAID superblock on device md: hdc1 [events: 0000003a](write) hdc1's sb offset: 45030080 md: hda1 [events: 0000003a](write) hda1's sb offset: 45030080 md: ... autorun DONE. NET4: Linux TCP/IP 1.0 for NET4.0 IP Protocols: ICMP, UDP, TCP IP: routing cache hash table of 8192 buckets, 64Kbytes TCP: Hash tables configured (established 262144 bind 65536) NET4: Unix domain sockets 1.0/SMP for Linux NET4.0. kjournald starting. Commit interval 5 seconds EXT3-fs: mounted filesystem with journal data mode. VFS: Mounted root (ext3 filesystem) readonly. Freeing unused kernel memory: 196k freed Adding Swap: 1052248k swap-space (priority 1) Adding Swap: 1052248k swap-space (priority 1) usb.c: registered new driver usbdevfs usb.c: registered new driver hub uhci.c: USB UHCI at I/O 0xdce0, IRQ 19 usb.c: new USB bus registered, assigned bus number 1 hub.c: USB hub found hub.c: 2 ports detected uhci.c: :USB Universal Host Controller Interface driver hub.c: USB new device connect on bus1/1, assigned device number 2 usb.c: USB device 2 (vend/prod 0x46d/0xc00c) is not claimed by any active driver. usb.c: registered new driver hid input0: USB HID v1.10 Mouse [Logitech USB Mouse] on usb1:2.0 hid-core.c: v1.8 Andreas Gal, Vojtech Pavlik <vojtech@suse.cz> hid-core.c: USB HID support drivers mice: PS/2 mouse device common for all mice EXT3 FS 2.4-0.9.10, 23 Sep 2001 on md(9,0), internal journal isapnp: Scanning for PnP cards... isapnp: Card 'CS4236B' isapnp: 1 Plug & Play card detected total ad1848/cs4248 codec driver Copyright (C) by Hannu Savolainen 1993-1996 ad1848: No ISAPnP cards found, trying standard ones... kjournald starting. Commit interval 5 seconds EXT3 FS 2.4-0.9.10, 23 Sep 2001 on md(9,1), internal journal EXT3-fs: mounted filesystem with journal data mode. kjournald starting. Commit interval 5 seconds EXT3 FS 2.4-0.9.10, 23 Sep 2001 on md(9,2), internal journal EXT3-fs: mounted filesystem with ordered data mode. 3c59x: Donald Becker and others. www.scyld.com/network/vortex.html 00:11.0: 3Com PCI 3c905B Cyclone 100baseTx at 0xdc00. Vers LK1.1.16 Serial driver version 5.05c (2001-07-08) with MANY_PORTS SHARE_IRQ SERIAL_PCI ISAPNP enabled ttyS00 at 0x03f8 (irq = 4) is a 16550A ttyS01 at 0x02f8 (irq = 3) is a 16550A (scsi0:A:1:0): Locking max tag count at 128 VFS: brelse: Trying to free free buffer If some fields are empty or look unusual you may have an old version. Compare to the current minimal requirements in Documentation/Changes.
Linux mackman.net 2.4.10 #1 SMP Mon Sep 24 02:37:41 PDT 2001 i686 unknown
Gnu C 2.96 Gnu make 3.79.1 binutils 2.10.91.0.2 util-linux 2.11f mount 2.11b modutils 2.4.2 e2fsprogs 1.23 Linux C Library 2.2.2 Dynamic linker (ldd) 2.2.2 Procps 2.0.7 Net-tools 1.57 Console-tools 0.3.3 Sh-utils 2.0 Modules Loaded 3c59x cs4232 uart401 ad1848 sound soundcore isa-pnp mousedev hid input uhci usbcore
|  |