Bug 129623

Summary: Kernel Error in Reiser FS Code
Product: [openSUSE] SUSE LINUX 10.0 Reporter: Randall Schulz <rschulz>
Component: KernelAssignee: Chris L Mason <mason>
Status: RESOLVED INVALID QA Contact: E-mail List <qa-bugs>
Severity: Normal    
Priority: P5 - None CC: myinfo
Version: Final   
Target Milestone: ---   
Hardware: x86   
OS: SuSE Linux 10.0   
Whiteboard:
Found By: Customer Services Priority:
Business Priority: Blocker: ---
Marketing QA Status: --- IT Deployment: ---
Attachments: Excerpt of /var/log/messages

Description Randall Schulz 2005-10-20 03:26:46 UTC 
While using KAudioCreator to rip and encode CD audio to a file system formatted
for Reiser file system with default FS parameters the relevant processes hung.
Subsequent attempts to interact with that file system hung as well. Ps showed
all such processes in a 'D' sleep.

Kernel messages produced during this event:

Oct 13 09:36:45 twain kernel: ------------[ cut here ]------------
Oct 13 09:36:45 twain kernel: kernel BUG at fs/reiserfs/file.c:623!
Oct 13 09:36:45 twain kernel: invalid operand: 0000 [#1]
Oct 13 09:36:45 twain kernel: SMP
Oct 13 09:36:45 twain kernel: Modules linked in: iptable_filter ip_tables
hfsplus vfat fat udf nls_utf8 speedstep_lib freq_table ipv6 snd_pcm_oss
snd_mixer_oss snd_seq_midi snd_seq_midi_event snd_seq button battery ac usblp
usbhid usb_storage edd snd_cs46xx gameport snd_rawmidi snd_seq_device
snd_ac97_codec ohci1394 ieee1394 snd_ac97_bus snd_pcm snd_timer snd soundcore
snd_page_alloc e100 mii i2c_i801 i2c_core generic intel_agp agpgart shpchp
pci_hotplug ehci_hcd uhci_hcd usbcore parport_pc lp parport subfs reiserfs
dm_mod xfs exportfs fan thermal processor aic7xxx scsi_transport_spi sg ata_piix
libata ide_cd cdrom piix sd_mod scsi_mod ide_disk ide_core
Oct 13 09:36:45 twain kernel: CPU:    0
Oct 13 09:36:45 twain kernel: EIP:    0060:[<f9639013>]    Not tainted VLI
Oct 13 09:36:45 twain kernel: EFLAGS: 00210242   (2.6.13-15-smp)
Oct 13 09:36:45 twain kernel: EIP is at
reiserfs_allocate_blocks_for_region+0x13e3/0x13f0 [reiserfs]
Oct 13 09:36:45 twain kernel: eax: 4000081d   ebx: 00000000   ecx: d6141ea4  
edx: c1514f80
Oct 13 09:36:45 twain kernel: esi: 00000000   edi: 00000003   ebp: d92bb834  
esp: d6141d28
Oct 13 09:36:45 twain kernel: ds: 007b   es: 007b   ss: 0068
Oct 13 09:36:45 twain kernel: Process flac (pid: 8531, threadinfo=d6140000
task=f688f540)
Oct 13 09:36:45 twain kernel: Stack: f748f900 c82fec80 00000008 00000003
d92bb7cc c0173485 00000001 c11244e0
Oct 13 09:36:45 twain kernel:        f748f900 d6141f48 00f8b001 00000000
c9bf7870 c82fec80 00000000 00000000
Oct 13 09:36:45 twain kernel:        000005b0 000005b0 00f8b001 00000000
00000000 f748f8b0 c9227000 d6141ea4
Oct 13 09:36:45 twain kernel: Call Trace:
Oct 13 09:36:45 twain kernel:  [<c0173485>] alloc_page_buffers+0x75/0xc0
Oct 13 09:36:45 twain kernel:  [<c018c8bf>] inode_update_time+0x3f/0xc0
Oct 13 09:36:45 twain kernel:  [<f963a879>] reiserfs_file_write+0x749/0x760
[reiserfs]
Oct 13 09:36:45 twain kernel:  [<f96f6f03>] linvfs_aio_read+0x83/0xb0 [xfs]
Oct 13 09:36:45 twain kernel:  [<c011e8f5>] activate_task+0x95/0xb0
Oct 13 09:36:45 twain kernel:  [<c011efc6>] try_to_wake_up+0x76/0x340
Oct 13 09:36:45 twain kernel:  [<c03446b9>] schedule+0x5a9/0xc00
Oct 13 09:36:45 twain kernel:  [<c03446e7>] schedule+0x5d7/0xc00
Oct 13 09:36:45 twain kernel:  [<f963a130>] reiserfs_file_write+0x0/0x760 [reiserfs]
Oct 13 09:36:45 twain kernel:  [<c017126f>] vfs_write+0xaf/0x190
Oct 13 09:36:45 twain kernel:  [<c0171401>] sys_write+0x41/0x70
Oct 13 09:36:45 twain kernel:  [<c01042ab>] sysenter_past_esp+0x54/0x79
Oct 13 09:36:45 twain kernel: Code: 9d 00 00 0f 0b 6c 02 d1 9f 65 f9 e9 12 f7 ff
ff 8b 54 24 20 b9 b0 b4 65 f9 8b 82 a0 00 00 00 89 4c 24 04 89 04 24 e8 cd 9d 00
00 <0f> 0b 6f 02 d1 9f 65 f9 e9 f8 f6 ff ff 83 ec 10 85 d2 89 7c 24
Oct 13 09:36:45 twain kernel:  Badness in do_exit at kernel/exit.c:790
Oct 13 09:36:45 twain kernel:  [<c0128e08>] do_exit+0x3b8/0x3c0
Oct 13 09:36:45 twain kernel:  [<c0105ddf>] die+0x18f/0x190
Oct 13 09:36:45 twain kernel:  [<c0106110>] do_invalid_op+0x0/0xb0
Oct 13 09:36:45 twain kernel:  [<c01061b5>] do_invalid_op+0xa5/0xb0
Oct 13 09:36:45 twain kernel:  [<f9639013>]
reiserfs_allocate_blocks_for_region+0x13e3/0x13f0 [reiserfs]
Oct 13 09:36:45 twain kernel:  [<c010552f>] error_code+0x4f/0x60
Oct 13 09:36:45 twain kernel:  [<f9639013>]
reiserfs_allocate_blocks_for_region+0x13e3/0x13f0 [reiserfs]
Oct 13 09:36:45 twain kernel:  [<c0173485>] alloc_page_buffers+0x75/0xc0
Oct 13 09:36:45 twain kernel:  [<c018c8bf>] inode_update_time+0x3f/0xc0
Oct 13 09:36:45 twain kernel:  [<f963a879>] reiserfs_file_write+0x749/0x760
[reiserfs]
Oct 13 09:36:45 twain kernel:  [<f96f6f03>] linvfs_aio_read+0x83/0xb0 [xfs]
Oct 13 09:36:45 twain kernel:  [<c011e8f5>] activate_task+0x95/0xb0
Oct 13 09:36:45 twain kernel:  [<c011efc6>] try_to_wake_up+0x76/0x340
Oct 13 09:36:45 twain kernel:  [<c03446b9>] schedule+0x5a9/0xc00
Oct 13 09:36:45 twain kernel:  [<c03446e7>] schedule+0x5d7/0xc00
Oct 13 09:36:45 twain kernel:  [<f963a130>] reiserfs_file_write+0x0/0x760 [reiserfs]
Oct 13 09:36:45 twain kernel:  [<c017126f>] vfs_write+0xaf/0x190
Oct 13 09:36:45 twain kernel:  [<c0171401>] sys_write+0x41/0x70
Oct 13 09:36:45 twain kernel:  [<c01042ab>] sysenter_past_esp+0x54/0x79
Comment 1 Randall Schulz 2005-10-20 07:03:46 UTC 
Created attachment 54870 [details]
Excerpt of /var/log/messages

This is the same trace included in the body of the original bug report, but has not be subject to line wrapping.
Comment 2 Andreas Jaeger 2005-12-03 07:32:23 UTC 
Chris, any ideas?
Comment 3 R Santa 2006-01-18 20:24:25 UTC 
I'm having the same Problem with reiserfs cousing a kernel exception on
"Linux altis 2.6.13-15.7-default #1 Tue Nov 29 14:32:29 UTC 2005 i686 athlon i386 GNU/Linux"

After that exception the system cannot be brought down normally or recovered from this error. A hard reset with fsck operations is required.

These are the syslog messages:
Jan 18 00:35:52 altis kernel: ------------[ cut here ]------------
Jan 18 00:35:52 altis kernel: kernel BUG at fs/reiserfs/journal.c:666!
Jan 18 00:35:52 altis kernel: invalid operand: 0000 [#1]
Jan 18 00:35:52 altis kernel: Modules linked in: snd_seq_dummy nls_iso8859_1 nls_cp437 usb_storage nls_utf8 ipt_pkttype ipt_LOG ipt_limit hfsplus vfat fat subf
s cpufreq_ondemand cpufreq_userspace cpufreq_powersave powernow_k8 speedstep_lib freq_table af_packet fglrx w83627hf eeprom i2c_sensor i2c_isa snd_pcm_oss snd_
mixer_oss snd_seq button battery ac edd snd_via82xx gameport snd_ac97_codec snd_ac97_bus snd_pcm snd_timer snd_page_alloc snd_mpu401_uart ohci1394 ieee1394 snd
_rawmidi 3c59x sk98lin ehci_hcd snd_seq_device snd soundcore i2c_viapro i2c_core mii generic uhci_hcd usbcore amd64_agp agpgart shpchp pci_hotplug parport_pc l
p parport ip6t_REJECT ipt_REJECT ipt_state iptable_mangle iptable_nat iptable_filter ip6table_mangle ip_conntrack ip_tables ip6table_filter ip6_tables ipv6 rei
serfs dm_mod ide_cd cdrom fan thermal processor via82cxxx sg sata_via sata_promise libata sd_mod scsi_mod ide_disk ide_core
Jan 18 00:35:52 altis kernel: CPU:    0
Jan 18 00:35:52 altis kernel: EIP:    0060:[<f9173de6>]    Tainted: P    BU VLI
Jan 18 00:35:52 altis kernel: EFLAGS: 00010246   (2.6.13-15.7-default)
Jan 18 00:35:52 altis kernel: EIP is at submit_ordered_buffer+0x16/0x30 [reiserfs]
Jan 18 00:35:52 altis kernel: eax: 00000024   ebx: cc42bdf4   ecx: 00000000   edx: c7657a08
Jan 18 00:35:52 altis kernel: esi: 00000015   edi: cc42bda4   ebp: cc42be28   esp: cc42bd84
Jan 18 00:35:52 altis kernel: ds: 007b   es: 007b   ss: 0068
Jan 18 00:35:52 altis kernel: Process pdflush (pid: 27891, threadinfo=cc42a000 task=cc713a80)
Jan 18 00:35:52 altis kernel: Stack: f9173f0c ed74ec78 f910d0d4 d091c158 f9173f56 f91741d1 f9173ec0 00000000
Jan 18 00:35:52 altis kernel:        ec29655c ec296798 ec296730 ec296320 ec296ad8 ec296660 ec296458 ec2962ec
Jan 18 00:35:52 altis kernel:        ec296cac ec296118 edfd6cac ecd67d7c ecd678d0 ecd679d4 c5f7bfb8 c5f7b3f0
Jan 18 00:35:52 altis kernel: Call Trace:
Jan 18 00:35:52 altis kernel:  [<f9173f0c>] write_ordered_chunk+0x4c/0x60 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9173f56>] add_to_chunk+0x36/0x40 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f91741d1>] write_ordered_buffers+0xd1/0x1a0 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9173ec0>] write_ordered_chunk+0x0/0x60 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f917450e>] flush_commit_list+0x13e/0x490 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9178c0d>] do_journal_end+0x90d/0x9b0 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9177b1a>] journal_end_sync+0x5a/0x70 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9165420>] reiserfs_sync_fs+0x40/0x50 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<c015ef8d>] sync_supers+0x6d/0xc0
Jan 18 00:35:52 altis kernel:  [<c014240e>] wb_kupdate+0x2e/0x130
Jan 18 00:35:52 altis kernel:  [<c02f9be3>] schedule+0x343/0x610
Jan 18 00:35:52 altis kernel:  [<c0142c50>] pdflush+0x0/0x20
Jan 18 00:35:52 altis kernel:  [<c0142bb2>] __pdflush+0xb2/0x150
Jan 18 00:35:52 altis kernel:  [<c0142c6a>] pdflush+0x1a/0x20
Jan 18 00:35:52 altis kernel:  [<c01423e0>] wb_kupdate+0x0/0x130
Jan 18 00:35:52 altis kernel:  [<c012dc35>] kthread+0x85/0x90
Jan 18 00:35:52 altis kernel:  [<c012dbb0>] kthread+0x0/0x90
Jan 18 00:35:52 altis kernel:  [<c01012f1>] kernel_thread_helper+0x5/0x14
Jan 18 00:35:52 altis kernel: Code: 08 0f 0b 90 02 86 f0 17 f9 b8 01 00 00 00 e9 72 94 fe c6 89 f6 89 c2 ff 40 0c c7 40 24 70 3d 17 f9 0f ba 30 01 8b 00 a8 01
75 08 <0f> 0b 9a 02 86 f0 17 f9 b8 01 00 00 00 e9 48 94 fe c6 90 8d b4
Jan 18 00:35:52 altis kernel:  Badness in do_exit at kernel/exit.c:790
Jan 18 00:35:52 altis kernel:  [<c011e9f3>] do_exit+0x343/0x350
Jan 18 00:35:52 altis kernel:  [<c010468e>] die+0x13e/0x140
Jan 18 00:35:52 altis kernel:  [<c0104940>] do_invalid_op+0x0/0xa0
Jan 18 00:35:52 altis kernel:  [<c01049d1>] do_invalid_op+0x91/0xa0
Jan 18 00:35:52 altis kernel:  [<f9173de6>] submit_ordered_buffer+0x16/0x30 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<c0140000>] sys_fadvise64_64+0x10/0x1d0
Jan 18 00:35:52 altis kernel:  [<f90fe9bd>] dm_request+0x6d/0x80 [dm_mod]
Jan 18 00:35:52 altis kernel:  [<c025cc71>] generic_make_request+0x81/0x230
Jan 18 00:35:52 altis kernel:  [<c012df70>] autoremove_wake_function+0x0/0x30
Jan 18 00:35:52 altis kernel:  [<c0103f0f>] error_code+0x4f/0x60
Jan 18 00:35:52 altis kernel:  [<f9173de6>] submit_ordered_buffer+0x16/0x30 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9173f0c>] write_ordered_chunk+0x4c/0x60 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9173f56>] add_to_chunk+0x36/0x40 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f91741d1>] write_ordered_buffers+0xd1/0x1a0 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9173ec0>] write_ordered_chunk+0x0/0x60 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f917450e>] flush_commit_list+0x13e/0x490 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9178c0d>] do_journal_end+0x90d/0x9b0 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9177b1a>] journal_end_sync+0x5a/0x70 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<f9165420>] reiserfs_sync_fs+0x40/0x50 [reiserfs]
Jan 18 00:35:52 altis kernel:  [<c015ef8d>] sync_supers+0x6d/0xc0
Jan 18 00:35:52 altis kernel:  [<c014240e>] wb_kupdate+0x2e/0x130
Jan 18 00:35:52 altis kernel:  [<c02f9be3>] schedule+0x343/0x610
Jan 18 00:35:52 altis kernel:  [<c0142c50>] pdflush+0x0/0x20
Jan 18 00:35:52 altis kernel:  [<c0142bb2>] __pdflush+0xb2/0x150
Jan 18 00:35:52 altis kernel:  [<c0142c6a>] pdflush+0x1a/0x20
Jan 18 00:35:52 altis kernel:  [<c01423e0>] wb_kupdate+0x0/0x130
Jan 18 00:35:52 altis kernel:  [<c012dc35>] kthread+0x85/0x90
Jan 18 00:35:52 altis kernel:  [<c012dbb0>] kthread+0x0/0x90
Jan 18 00:35:52 altis kernel:  [<c01012f1>] kernel_thread_helper+0x5/0x14
------------[ cut here ]------------

Any idea yet?

Robert.
Comment 4 Greg Kroah-Hartman 2006-08-14 07:08:22 UTC 
Closed due to inactivity.