Re: bitmap loading related reiserfs changes in 2.6.17-mm1 are broken

["Barry K. Nathan" <barryn@pobox.com>]

[-- Attachment #1: Type: text/plain, Size: 422 bytes --]

On 6/21/06, Barry K. Nathan <barryn@pobox.com> wrote:
> Unfortunately I still haven't gotten around to adding a serial console
> to this box *and* I do not have access to my digital camera for
> approx. 2 weeks. I guess I'll try to add a serial console ASAP. This
> will probably take me a day or two however.

Never mind, it took a lot less time than that. Here's the backtrace...
-- 
-Barry K. Nathan <barryn@pobox.com>

[-- Attachment #2: capture2.txt --]
[-- Type: text/plain, Size: 15219 bytes --]

[    0.000000] Linux version 2.6.17-mm1nolockdep (barryn@nserv) (gcc version 4.1.1) #1 Thu Jun 22 00:36:48 PDT 2006
[    0.000000] BIOS-provided physical RAM map:
[    0.000000] sanitize start
[    0.000000] sanitize end
[    0.000000] copy_e820_map() start: 0000000000000000 size: 00000000000a0000 end: 00000000000a0000 type: 1
[    0.000000] copy_e820_map() type is E820_RAM
[    0.000000] add_memory_region(0000000000000000, 00000000000a0000, 1)
[    0.000000] copy_e820_map() start: 00000000000f0000 size: 0000000000010000 end: 0000000000100000 type: 2
[    0.000000] add_memory_region(00000000000f0000, 0000000000010000, 2)
[    0.000000] copy_e820_map() start: 0000000000100000 size: 000000000eef0000 end: 000000000eff0000 type: 1
[    0.000000] copy_e820_map() type is E820_RAM
[    0.000000] add_memory_region(0000000000100000, 000000000eef0000, 1)
[    0.000000] copy_e820_map() start: 000000000eff0000 size: 0000000000003000 end: 000000000eff3000 type: 4
[    0.000000] add_memory_region(000000000eff0000, 0000000000003000, 4)
[    0.000000] copy_e820_map() start: 000000000eff3000 size: 000000000000d000 end: 000000000f000000 type: 3
[    0.000000] add_memory_region(000000000eff3000, 000000000000d000, 3)
[    0.000000] copy_e820_map() start: 00000000ffff0000 size: 0000000000010000 end: 0000000100000000 type: 2
[    0.000000] add_memory_region(00000000ffff0000, 0000000000010000, 2)
[    0.000000]  BIOS-e820: 0000000000000000 - 00000000000a0000 (usable)
[    0.000000]  BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000000eff0000 (usable)
[    0.000000]  BIOS-e820: 000000000eff0000 - 000000000eff3000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000000eff3000 - 000000000f000000 (ACPI data)
[    0.000000]  BIOS-e820: 00000000ffff0000 - 0000000100000000 (reserved)
[    0.000000] 239MB LOWMEM available.
[    0.000000] DMI 2.2 present.
[    0.000000] ACPI: PM-Timer IO Port: 0x408
[    0.000000] Allocating PCI resources starting at 10000000 (gap: 0f000000:f0ff0000)
[    0.000000] Detected 1399.443 MHz processor.
[   70.909638] Built 1 zonelists.  Total pages: 61424
[   70.909643] Kernel command line: BOOT_IMAGE=bzimage lapic root=/dev/sda1 vga=6 console=ttyS0,57600 console=tty0
[   70.910024] Local APIC disabled by BIOS -- reenabling.
[   70.910029] Found and enabled local APIC!
[   70.910038] Enabling fast FPU save and restore... done.
[   70.910041] Enabling unmasked SIMD FPU exception support... done.
[   70.910047] Initializing CPU#0
[   70.910114] CPU 0 irqstacks, hard=c03be000 soft=c03bd000
[   70.910120] PID hash table entries: 1024 (order: 10, 4096 bytes)
[   70.912324] Console: colour VGA+ 80x60
[   71.389932] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[   71.404154] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[   71.434455] Memory: 239984k/245696k available (1454k kernel code, 5160k reserved, 833k data, 156k init, 0k highmem)
[   71.455362] Checking if this processor honours the WP bit even in supervisor mode... Ok.
[   71.619372] Calibrating delay using timer specific routine.. 2800.22 BogoMIPS (lpj=14001119)
[   71.636399] Mount-cache hash table entries: 512
[   71.645671] CPU: L1 I cache: 16K, L1 D cache: 16K
[   71.655163] CPU: L2 cache: 512K
[   71.661498] Intel machine check architecture supported.
[   71.671980] Intel machine check reporting enabled on CPU#0.
[   71.683149] Compat vDSO mapped to ffffe000.
[   71.691551] CPU: Intel(R) Pentium(R) III CPU family      1400MHz stepping 01
[   71.705767] Checking 'hlt' instruction... OK.
[   71.750212] SMP alternatives: switching to UP code
[   71.759819] Freeing SMP alternatives: 0k freed
[   71.768730] ACPI: Core revision 20060608
[   71.782088] ACPI: setting ELCR to 0200 (from 1aa0)
[   71.981497] NET: Registered protocol family 16
[   71.990555] ACPI: bus type pci registered
[   72.001402] PCI: PCI BIOS revision 2.10 entry at 0xfb0c0, last bus=1
[   72.014133] Setting up standard PCI resources
[   72.033190] ACPI: Interpreter enabled
[   72.040558] ACPI: Using PIC for interrupt routing
[   72.050745] ACPI: PCI Root Bridge [PCI0] (0000:00)
[   72.069104] PCI quirk: region 0400-047f claimed by vt8235 PM
[   72.080471] PCI quirk: region 0500-050f claimed by vt8235 SMB
[   72.133255] ACPI: PCI Interrupt Link [LNKA] (IRQs 1 3 4 5 6 7 10 *11 12 14 15)
[   72.149049] ACPI: PCI Interrupt Link [LNKB] (IRQs 1 3 4 5 6 7 10 11 *12 14 15)
[   72.164810] ACPI: PCI Interrupt Link [LNKC] (IRQs 1 3 4 *5 6 7 10 11 12 14 15)
[   72.180582] ACPI: PCI Interrupt Link [LNKD] (IRQs 1 3 4 5 6 *7 10 11 12 14 15)
[   72.199156] Linux Plug and Play Support v0.97 (c) Adam Belay
[   72.210512] pnp: PnP ACPI init
[   72.221041] pnp: PnP ACPI: found 11 devices
[   72.229662] SCSI subsystem initialized
[   72.237245] PCI: Using ACPI for IRQ routing
[   72.245661] PCI: If a device doesn't work, try "pci=routeirq".  If it helps, post a report
[   72.311990] pnp: 00:02: ioport range 0x400-0x47f could not be reserved
[   72.325076] pnp: 00:02: ioport range 0x500-0x50f has been reserved
[   72.337833] PCI: Bridge: 0000:00:01.0
[   72.345189]   IO window: disabled.
[   72.352073]   MEM window: e4000000-e5ffffff
[   72.360505]   PREFETCH window: e0000000-e3ffffff
[   72.370031] NET: Registered protocol family 2
[   72.478540] IP route cache hash table entries: 2048 (order: 1, 8192 bytes)
[   72.492456] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[   72.506634] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[   72.519571] TCP: Hash tables configured (established 8192 bind 4096)
[   72.532299] TCP reno registered
[   72.539661] Initializing Cryptographic API
[   72.547891] io scheduler noop registered
[   72.555832] io scheduler cfq registered (default)
[   72.565728] ACPI: Power Button (FF) [PWRF]
[   72.573965] ACPI: Power Button (CM) [PWRB]
[   72.585364] Linux agpgart interface v0.101 (c) Dave Jones
[   72.596315] agpgart: Detected VIA CLE266 chipset
[   72.607045] agpgart: AGP aperture is 4M @ 0xe7000000
[   72.617162] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
[   72.633066] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[   72.645512] Floppy drive(s): fd0 is 1.44M, fd1 is 1.44M
[   72.670162] FDC 0 is a post-1991 82077
[   72.679948] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 11
[   72.691474] ACPI: PCI Interrupt 0000:00:08.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
[   72.709030] sata_sil 0000:00:08.0: Applying R_ERR on DMA activate FIS errata fix
[   72.723965] ata1: SATA max UDMA/100 cmd 0xCF804080 ctl 0xCF80408A bmdma 0xCF804000 irq 11
[   72.740392] ata2: SATA max UDMA/100 cmd 0xCF8040C0 ctl 0xCF8040CA bmdma 0xCF804008 irq 11
[   72.756827] ata3: SATA max UDMA/100 cmd 0xCF804280 ctl 0xCF80428A bmdma 0xCF804200 irq 11
[   72.773263] ata4: SATA max UDMA/100 cmd 0xCF8042C0 ctl 0xCF8042CA bmdma 0xCF804208 irq 11
[   72.789666] scsi0 : sata_sil
[   73.277654] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   73.290920] ata1.00: ATA-7, max UDMA/133, 585940320 sectors: LBA48 
[   73.303477] ata1.00: applying bridge limits
[   73.312878] ata1.00: configured for UDMA/100
[   73.321453] scsi1 : sata_sil
[   73.807094] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   73.820677] ata2.00: ATA-7, max UDMA/100, 781422768 sectors: LBA48 
[   73.833240] ata2.00: applying bridge limits
[   73.842975] ata2.00: configured for UDMA/100
[   73.851561] scsi2 : sata_sil
[   74.346527] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   74.359798] ata3.00: ATA-6, max UDMA/100, 625142448 sectors: LBA48 
[   74.372350] ata3.00: applying bridge limits
[   74.381753] ata3.00: configured for UDMA/100
[   74.390326] scsi3 : sata_sil
[   74.716118] ata4: SATA link down (SStatus 0 SControl 310)
[   74.727111]   Vendor: ATA       Model: Maxtor 4A300J0    Rev: RAMB
[   74.741105]   Type:   Direct-Access                      ANSI SCSI revision: 05
[   74.756053]   Vendor: ATA       Model: ST3400832A        Rev: 3.01
[   74.770033]   Type:   Direct-Access                      ANSI SCSI revision: 05
[   74.784952]   Vendor: ATA       Model: WDC WD3200JB-00K  Rev: 08.0
[   74.798927]   Type:   Direct-Access                      ANSI SCSI revision: 05
[   74.814010] ACPI: PCI Interrupt 0000:00:11.1[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
[   74.831559] PCI: VIA IRQ fixup for 0000:00:11.1, from 255 to 11
[   74.843537] ata5: PATA max UDMA/133 cmd 0x1F0 ctl 0x3F6 bmdma 0xE400 irq 14
[   74.857501] scsi4 : pata_via
[   75.016563] ata5.00: ATA-7, max UDMA/133, 490234752 sectors: LBA48 
[   75.030066] ata5.00: configured for UDMA/133
[   75.038732]   Vendor: ATA       Model: Maxtor 6Y250P0    Rev: YAR4
[   75.052702]   Type:   Direct-Access                      ANSI SCSI revision: 05
[   75.067593] ata6: PATA max UDMA/133 cmd 0x170 ctl 0x376 bmdma 0xE408 irq 15
[   75.081548] scsi5 : pata_via
[   75.246438] ata6.00: ATA-6, max UDMA/100, 625142448 sectors: LBA48 
[   75.260036] ata6.00: configured for UDMA/100
[   75.268710]   Vendor: ATA       Model: WDC WD3200JB-00K  Rev: 08.0
[   75.282674]   Type:   Direct-Access                      ANSI SCSI revision: 05
[   75.297678] SCSI device sda: 585940320 512-byte hdwr sectors (300001 MB)
[   75.311128] sda: Write Protect is off
[   75.318516] SCSI device sda: drive cache: write back
[   75.328545] SCSI device sda: 585940320 512-byte hdwr sectors (300001 MB)
[   75.341991] sda: Write Protect is off
[   75.349380] SCSI device sda: drive cache: write back
[   75.359340]  sda: sda1
[   75.367974] sd 0:0:0:0: Attached scsi disk sda
[   75.376982] SCSI device sdb: 781422768 512-byte hdwr sectors (400088 MB)
[   75.390413] sdb: Write Protect is off
[   75.397805] SCSI device sdb: drive cache: write back
[   75.407812] SCSI device sdb: 781422768 512-byte hdwr sectors (400088 MB)
[   75.421246] sdb: Write Protect is off
[   75.428635] SCSI device sdb: drive cache: write back
[   75.438594]  sdb: unknown partition table
[   75.464089] sd 1:0:0:0: Attached scsi disk sdb
[   75.473084] SCSI device sdc: 625142448 512-byte hdwr sectors (320073 MB)
[   75.486529] sdc: Write Protect is off
[   75.493910] SCSI device sdc: drive cache: write back
[   75.503925] SCSI device sdc: 625142448 512-byte hdwr sectors (320073 MB)
[   75.517360] sdc: Write Protect is off
[   75.524744] SCSI device sdc: drive cache: write back
[   75.534712]  sdc: sdc1 sdc2
[   75.561786] sd 2:0:0:0: Attached scsi disk sdc
[   75.570779] SCSI device sdd: 490234752 512-byte hdwr sectors (251000 MB)
[   75.584220] sdd: Write Protect is off
[   75.591614] SCSI device sdd: drive cache: write back
[   75.601616] SCSI device sdd: 490234752 512-byte hdwr sectors (251000 MB)
[   75.615108] sdd: Write Protect is off
[   75.622496] SCSI device sdd: drive cache: write back
[   75.632453]  sdd: unknown partition table
[   75.658873] sd 4:0:0:0: Attached scsi disk sdd
[   75.667861] SCSI device sde: 625142448 512-byte hdwr sectors (320073 MB)
[   75.681304] sde: Write Protect is off
[   75.688698] SCSI device sde: drive cache: write back
[   75.698713] SCSI device sde: 625142448 512-byte hdwr sectors (320073 MB)
[   75.712156] sde: Write Protect is off
[   75.719546] SCSI device sde: drive cache: write back
[   75.729504]  sde: unknown partition table
[   75.755625] sd 5:0:0:0: Attached scsi disk sde
[   75.764637] PNP: PS/2 Controller [PNP0303:PS2K] at 0x60,0x64 irq 1
[   75.777027] PNP: PS/2 controller doesn't have AUX irq; using default 12
[   75.790438] serio: i8042 AUX port at 0x60,0x64 irq 12
[   75.800581] serio: i8042 KBD port at 0x60,0x64 irq 1
[   75.810774] mice: PS/2 mouse device common for all mice
[   75.821331] NET: Registered protocol family 1
[   75.830101] Testing NMI watchdog ... OK.
[   75.938071] Using IPI Shortcut mode
[   75.945152] ACPI: (supports S0 S1 S3 S4 S5)
[   75.954083] Freeing unused kernel memory: 156k freed
[   75.964047] Time: tsc clocksource has been installed.
[   75.974213] Write protecting the kernel read-only data: 298k
[   75.992335] ReiserFS: sda1: found reiserfs format "3.6" with standard journal
[   76.006670] ReiserFS: sda1: using ordered data mode
[   76.018727] ReiserFS: sda1: journal params: device sda1, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
[   76.050463] ReiserFS: sda1: checking transaction log (sda1)
[   76.109979] input: AT Translated Set 2 keyboard as /class/input/input0
[   76.162999] ReiserFS: sda1: Using r5 hash to sort names
[   99.661389] ------------[ cut here ]------------
[   99.670669] kernel BUG at fs/reiserfs/bitmap.c:1307!
[   99.680622] invalid opcode: 0000 [#1]
[   99.687980] 4K_STACKS 
[   99.692793] last sysfs file: /block/sda/sda1/dev
[   99.702055] Modules linked in:
[   99.708239] CPU:    0
[   99.708241] EIP:    0060:[<c017ab78>]    Not tainted VLI
[   99.708243] EFLAGS: 00010202   (2.6.17-mm1nolockdep #1) 
[   99.734127] EIP is at reiserfs_cache_bitmap_metadata+0x78/0x85
[   99.745819] eax: 00000001   ebx: 00000000   ecx: c02ca4f4   edx: 00000001
[   99.759409] esi: cee10d24   edi: 00000000   ebp: ce808d70   esp: ce808d64
[   99.773000] ds: 007b   es: 007b   ss: 0068
[   99.781222] Process mount (pid: 338, ti=ce808000 task=cef9d560 task.ti=ce808000)
[   99.795677] Stack: cee10d24 cef6ea00 00000001 ce808d88 c017ac76 cf812000 cf812004 cef6ea00 
[   99.814530]        ceecf1c4 ce808d9c c017b211 00008180 fffffff4 ceecf210 ce808e84 c0185172 
[   99.831737]        ceecf210 ce808ea8 c017f465 cef6ea00 00c1ceff 00000001 00000000 00000000 
[   99.848912] Call Trace:
[   99.854631]  [<c017ac76>] reiserfs_read_bitmap_block+0xf1/0xfb
[   99.866663]  [<c017b211>] reiserfs_choose_packing+0x4d/0x7c
[   99.878177]  [<c0185172>] reiserfs_new_inode+0x90/0x609
[   99.889023]  [<c017ff3a>] reiserfs_create+0x71/0x12b
[   99.899334]  [<c01564ea>] vfs_create+0x67/0xad
[   99.908539]  [<c0158d86>] open_namei+0x181/0x636
[   99.918083]  [<c0149608>] do_filp_open+0x1f/0x35
[   99.927598]  [<c0149676>] do_sys_open+0x58/0xe3
[   99.936931]  [<c014972d>] sys_open+0x16/0x18
[   99.945741]  [<c026adf7>] syscall_call+0x7/0xb
[   99.954713] Code: 66 89 41 02 4b 83 fb ff 75 e2 8b 46 14 83 ea 04 39 c2 73 af 31 d2 b8 f4 a4 2c c0 66 83 39 00 0f 94 c2 e8 b4 62 03 00 85 c0 74 08 <0f> 0b 1b 05 f8 60 28 c0 5b 5e 5f 5d c3 55 89 e5 57 89 d7 56 89 
[   99.995769] EIP: [<c017ab78>] reiserfs_cache_bitmap_metadata+0x78/0x85 SS:ESP 0068:ce808d64
[  100.012596]  BUG: warning at kernel/exit.c:855/do_exit()
[  100.023494]  [<c0103079>] show_trace_log_lvl+0x54/0xfd
[  100.033885]  [<c010363d>] show_trace+0xd/0x10
[  100.042708]  [<c01036da>] dump_stack+0x19/0x1b
[  100.051694]  [<c01178a8>] do_exit+0x5f/0x8ab
[  100.060409]  [<c0103566>] die+0x218/0x220
[  100.068533]  [<c01035ea>] do_trap+0x7c/0x96
[  100.076997]  [<c0103cbb>] do_invalid_op+0x89/0x93
[  100.086501]  [<c0102c9d>] error_code+0x39/0x40