ext3 and btrfs various Oops and kernel BUGs

ext3 and btrfs various Oops and kernel BUGs

[Norbert Preining]

Dear all,

(please Cc)

yesterday I had two bugs with btrfs and ext3 occurrences, always happening
when plugging in an external USB btrfs disk.

Today I had a BUG which is purely ext3 related.

The last bug was with yesterdays latest git checkout.

Here are the three bugs/oops:

that one was with 3.0.0-rc2 (exactely)

Jun 10 14:50:23 mithrandir kernel: [40871.704129] BUG: unable to handle kernel NULL pointer dereference at 0000000000000030
Jun 10 14:50:23 mithrandir kernel: [40871.704185] IP: [<ffffffffa0d7b6e5>] btrfs_print_leaf+0x17/0x75c [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.704241] PGD 12d5e9067 PUD 12d5e8067 PMD 0 
Jun 10 14:50:23 mithrandir kernel: [40871.704277] Oops: 0000 [#1] PREEMPT SMP 
Jun 10 14:50:23 mithrandir kernel: [40871.704311] CPU 1 
Jun 10 14:50:23 mithrandir kernel: [40871.704324] Modules linked in: usb_storage rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat hso fuse loop uinput snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi arc4 snd_rawmidi iwlagn mac80211 snd_seq_midi_event cfg80211 snd_seq snd_timer snd_seq_device snd btusb bluetooth sony_laptop tpm_infineon soundcore mxm_wmi joydev firewire_ohci crc16 firewire_core snd_page_alloc rfkill crc_itu_t
Jun 10 14:50:23 mithrandir kernel: [40871.704733] 
Jun 10 14:50:23 mithrandir kernel: [40871.704745] Pid: 18862, comm: btrfs-transacti Tainted: P            3.0.0-rc2+ #33 Sony Corporation VGN-Z11VN_B/VAIO
Jun 10 14:50:23 mithrandir kernel: [40871.704812] RIP: 0010:[<ffffffffa0d7b6e5>]  [<ffffffffa0d7b6e5>] btrfs_print_leaf+0x17/0x75c [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.704875] RSP: 0018:ffff880103eadb10  EFLAGS: 00010282
Jun 10 14:50:23 mithrandir kernel: [40871.704906] RAX: 00000000fffffffb RBX: 0000000000000000 RCX: ffff88012431a828
Jun 10 14:50:23 mithrandir kernel: [40871.704945] RDX: 00000044824bb000 RSI: 0000000000000000 RDI: ffff88012bd11800
Jun 10 14:50:23 mithrandir kernel: [40871.704985] RBP: ffff880103eadb90 R08: 0000000000000001 R09: 0000000000000000
Jun 10 14:50:23 mithrandir kernel: [40871.705024] R10: 0000000000000000 R11: 0000000000000001 R12: ffff88012bd11800
Jun 10 14:50:23 mithrandir kernel: [40871.705063] R13: 0000004482495000 R14: 0000000000000007 R15: 0000000000000000
Jun 10 14:50:23 mithrandir kernel: [40871.705103] FS:  0000000000000000(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
Jun 10 14:50:23 mithrandir kernel: [40871.705149] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jun 10 14:50:23 mithrandir kernel: [40871.705180] CR2: 0000000000000030 CR3: 00000001270df000 CR4: 00000000000006e0
Jun 10 14:50:23 mithrandir kernel: [40871.705220] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 10 14:50:23 mithrandir kernel: [40871.705259] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 10 14:50:23 mithrandir kernel: [40871.705299] Process btrfs-transacti (pid: 18862, threadinfo ffff880103eac000, task ffff88013f5e7750)
Jun 10 14:50:23 mithrandir kernel: [40871.705350] Stack:
Jun 10 14:50:23 mithrandir kernel: [40871.705364]  ffff880103eadb20 0000004482495000 ffff880103eadb50 ffff88012bd11800
Jun 10 14:50:23 mithrandir kernel: [40871.705415]  ffffffff00008050 0000000000000206 ffff88013f7a64c0 0000000000000000
Jun 10 14:50:23 mithrandir kernel: [40871.705465]  a800000044824950 0000000000001000 ffff8800374bf140 ffff880102dcbd00
Jun 10 14:50:23 mithrandir kernel: [40871.705516] Call Trace:
Jun 10 14:50:23 mithrandir kernel: [40871.705539]  [<ffffffffa0d7584e>] __btrfs_free_extent+0x24b/0x54f [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.705579]  [<ffffffff810781cc>] ? __delayacct_blkio_end+0x39/0x3b
Jun 10 14:50:23 mithrandir kernel: [40871.705616]  [<ffffffff81025a4e>] ? delayacct_blkio_end+0x1c/0x3c
Jun 10 14:50:23 mithrandir kernel: [40871.705652]  [<ffffffff810d2a0e>] ? wait_on_buffer+0x12/0x12
Jun 10 14:50:23 mithrandir kernel: [40871.705692]  [<ffffffffa0d77d22>] run_clustered_refs+0x616/0x660 [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.705733]  [<ffffffff81025042>] ? set_next_entity+0x3b/0x60
Jun 10 14:50:23 mithrandir kernel: [40871.705776]  [<ffffffffa0db768f>] ? btrfs_find_ref_cluster+0xf6/0x137 [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.705822]  [<ffffffffa0d77e38>] btrfs_run_delayed_refs+0xcc/0x17c [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.705871]  [<ffffffffa0d85775>] btrfs_commit_transaction+0x72/0x655 [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.705920]  [<ffffffffa0d84d89>] ? join_transaction.isra.23+0x203/0x20a [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.705964]  [<ffffffff810481d4>] ? add_wait_queue+0x44/0x44
Jun 10 14:50:23 mithrandir kernel: [40871.706006]  [<ffffffffa0d80180>] transaction_kthread+0x174/0x22b [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.706053]  [<ffffffffa0d8000c>] ? btrfs_congested_fn+0x8e/0x8e [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.706093]  [<ffffffff81047bb8>] kthread+0x7f/0x87
Jun 10 14:50:23 mithrandir kernel: [40871.706123]  [<ffffffff8139fd94>] kernel_thread_helper+0x4/0x10
Jun 10 14:50:23 mithrandir kernel: [40871.706157]  [<ffffffff81047b39>] ? kthread_worker_fn+0x141/0x141
Jun 10 14:50:23 mithrandir kernel: [40871.706192]  [<ffffffff8139fd90>] ? gs_change+0xb/0xb
Jun 10 14:50:23 mithrandir kernel: [40871.706220] Code: ff ff 48 8b 80 38 e0 ff ff a8 08 74 05 e8 ab 1c 62 e0 5d c3 55 48 89 e5 41 57 41 56 41 55 41 54 49 89 fc 53 48 89 f3 48 83 ec 58 
Jun 10 14:50:23 mithrandir kernel: [40871.706492] RIP  [<ffffffffa0d7b6e5>] btrfs_print_leaf+0x17/0x75c [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.706543]  RSP <ffff880103eadb10>
Jun 10 14:50:23 mithrandir kernel: [40871.706564] CR2: 0000000000000030
Jun 10 14:50:23 mithrandir kernel: [40871.729568] ---[ end trace 7ff9da295f74d790 ]---
Jun 10 14:50:23 mithrandir kernel: [40871.729588] BUG: unable to handle kernel paging request at 00000000000015bd
Jun 10 14:50:23 mithrandir kernel: [40871.729632] IP: [<ffffffff8111a0c2>] journal_start+0x2f/0xc1
Jun 10 14:50:23 mithrandir kernel: [40871.729668] PGD 1391ca067 PUD 1391c9067 PMD 0 
Jun 10 14:50:23 mithrandir kernel: [40871.729701] Oops: 0000 [#2] PREEMPT SMP 
Jun 10 14:50:23 mithrandir kernel: [40871.731455] CPU 0 
Jun 10 14:50:23 mithrandir kernel: [40871.731467] Modules linked in: usb_storage rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat hso fuse loop uinput snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi arc4 snd_rawmidi iwlagn mac80211 snd_seq_midi_event cfg80211 snd_seq snd_timer snd_seq_device snd btusb bluetooth sony_laptop tpm_infineon soundcore mxm_wmi joydev firewire_ohci crc16 firewire_core snd_page_alloc rfkill crc_itu_t
Jun 10 14:50:23 mithrandir kernel: [40871.732005] 
Jun 10 14:50:23 mithrandir kernel: [40871.732005] Pid: 18862, comm: btrfs-transacti Tainted: P      D     3.0.0-rc2+ #33 Sony Corporation VGN-Z11VN_B/VAIO
Jun 10 14:50:23 mithrandir kernel: [40871.732005] RIP: 0010:[<ffffffff8111a0c2>]  [<ffffffff8111a0c2>] journal_start+0x2f/0xc1
Jun 10 14:50:23 mithrandir kernel: [40871.732005] RSP: 0018:ffff880103ead3e8  EFLAGS: 00010282
Jun 10 14:50:23 mithrandir kernel: [40871.732005] RAX: 00000000000015bd RBX: ffff880124138780 RCX: 0000000000000000
Jun 10 14:50:23 mithrandir kernel: [40871.732005] RDX: ffff88013b362800 RSI: 0000000000000002 RDI: ffff88013b362400
Jun 10 14:50:23 mithrandir kernel: [40871.732005] RBP: ffff880103ead408 R08: 0000000000000040 R09: ffff880103ead778
Jun 10 14:50:23 mithrandir kernel: [40871.732005] R10: ffff880103eadfd8 R11: ffff880103ead6f8 R12: ffff88013f5e7750
Jun 10 14:50:23 mithrandir kernel: [40871.732005] R13: ffff88013b362400 R14: 0000000000000002 R15: 0000000000000000
Jun 10 14:50:23 mithrandir kernel: [40871.732005] FS:  0000000000000000(0000) GS:ffff88013fc00000(0000) knlGS:0000000000000000
Jun 10 14:50:23 mithrandir kernel: [40871.732005] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jun 10 14:50:23 mithrandir kernel: [40871.732005] CR2: 00000000000015bd CR3: 0000000127204000 CR4: 00000000000006f0
Jun 10 14:50:23 mithrandir kernel: [40871.732005] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 10 14:50:23 mithrandir kernel: [40871.732005] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 10 14:50:23 mithrandir kernel: [40871.732005] Process btrfs-transacti (pid: 18862, threadinfo ffff880103eac000, task ffff88013f5e7750)
Jun 10 14:50:23 mithrandir kernel: [40871.732005] Stack:
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  ffffffffffffffe2 ffff88004cf7dd60 ffff880124138780 0000000000000001
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  ffff880103ead428 ffffffff8110adf1 ffff88004cf7dd60 ffff88004cf7dd60
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  ffff880103ead458 ffffffff81105e8d ffffffff81105e62 ffff88004cf7dd60
Jun 10 14:50:23 mithrandir kernel: [40871.732005] Call Trace:
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8110adf1>] ext3_journal_start_sb+0x45/0x50
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81105e8d>] ext3_dirty_inode+0x2b/0x7d
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81105e62>] ? ext3_evict_inode+0x119/0x119
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff810cd805>] __mark_inode_dirty+0x2a/0x181
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff810c4813>] file_update_time+0xfd/0x121
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff810832a2>] __generic_file_aio_write+0x15b/0x270
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8139eb86>] ? retint_kernel+0x26/0x30
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81083413>] generic_file_aio_write+0x5c/0xb5
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff810b1e2a>] do_sync_write+0xba/0xfa
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8103c2c6>] ? mod_timer+0x87/0x8f
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8104e7c7>] ? timekeeping_get_ns+0x12/0x31
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8104ec3b>] ? ktime_get_ts+0x80/0x89
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81063001>] do_acct_process+0x28c/0x2bf
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81063625>] acct_process+0x73/0x92
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81035007>] do_exit+0x244/0x6da
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff810335c9>] ? kmsg_dump+0xcb/0xda
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8100492e>] oops_end+0x89/0x8e
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81396a4c>] no_context+0x1fe/0x20d
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d9397f>] ? lookup_extent_mapping+0xaf/0xc2 [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81396be9>] __bad_area_nosemaphore+0x18e/0x1b1
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81396c1a>] bad_area_nosemaphore+0xe/0x10
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8101d95b>] do_page_fault+0x143/0x308
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0da20c7>] ? free_extent_buffer+0x12/0x29 [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d6e660>] ? read_block_for_search.isra.35+0x356/0x36b [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d6b86d>] ? btrfs_header_nritems.isra.15+0x14/0x1b [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d6d7d9>] ? balance_level+0xd2/0x578 [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8139e3e0>] ? _raw_spin_lock+0x18/0x1a
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8139ec8f>] page_fault+0x1f/0x30
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d7b6e5>] ? btrfs_print_leaf+0x17/0x75c [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d7584e>] __btrfs_free_extent+0x24b/0x54f [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff810781cc>] ? __delayacct_blkio_end+0x39/0x3b
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81025a4e>] ? delayacct_blkio_end+0x1c/0x3c
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff810d2a0e>] ? wait_on_buffer+0x12/0x12
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d77d22>] run_clustered_refs+0x616/0x660 [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81025042>] ? set_next_entity+0x3b/0x60
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0db768f>] ? btrfs_find_ref_cluster+0xf6/0x137 [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d77e38>] btrfs_run_delayed_refs+0xcc/0x17c [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d85775>] btrfs_commit_transaction+0x72/0x655 [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d84d89>] ? join_transaction.isra.23+0x203/0x20a [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff810481d4>] ? add_wait_queue+0x44/0x44
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d80180>] transaction_kthread+0x174/0x22b [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffffa0d8000c>] ? btrfs_congested_fn+0x8e/0x8e [btrfs]
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81047bb8>] kthread+0x7f/0x87
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8139fd94>] kernel_thread_helper+0x4/0x10
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff81047b39>] ? kthread_worker_fn+0x141/0x141
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  [<ffffffff8139fd90>] ? gs_change+0xb/0xb
Jun 10 14:50:23 mithrandir kernel: [40871.732005] Code: 41 56 41 89 f6 41 55 49 89 fd 41 54 53 65 4c 8b 24 25 00 b6 00 00 48 85 ff 49 8b 9c 24 f8 04 00 00 74 7e 48 85 db 74 0f 48 8b 03 
Jun 10 14:50:23 mithrandir kernel: [40871.732005] RIP  [<ffffffff8111a0c2>] journal_start+0x2f/0xc1
Jun 10 14:50:23 mithrandir kernel: [40871.732005]  RSP <ffff880103ead3e8>
Jun 10 14:50:23 mithrandir kernel: [40871.732005] CR2: 00000000000015bd
Jun 10 14:50:23 mithrandir kernel: [40871.851016] ---[ end trace 7ff9da295f74d791 ]---
Jun 10 14:50:23 mithrandir kernel: [40871.851018] Fixing recursive fault but reboot is needed!
Jun 10 14:50:46 mithrandir console-kit-daemon[3064]: WARNING: The program /usr/lib/ConsoleKit/run-seat.d/udev-acl.ck didn't exit within 15 seconds; killing it
Jun 10 14:50:50 mithrandir kernel: [40898.096945] SysRq : Emergency Sync
Jun 10 14:50:50 mithrandir kernel: [40898.446155] SysRq : Emergency Sync
Jun 10 14:50:51 mithrandir kernel: [40899.399464] SysRq : Emergency Sync
Jun 10 14:50:51 mithrandir kernel: [40899.555006] SysRq : Emergency Sync
Jun 10 14:50:51 mithrandir kernel: [40900.037680] SysRq : Emergency Remount R/O



Reboot into git checkout last commit 7f45e5cd1718ed7692
(Thu Jun 9 16:33:01 2011 -0700)

--------- reboot and replug btrfs external HD

Jun 10 14:57:17 mithrandir kernel: [  266.180096] BUG: unable to handle kernel NULL pointer dereference at 0000000000000030
Jun 10 14:57:17 mithrandir kernel: [  266.180171] IP: [<ffffffffa02276f9>] btrfs_print_leaf+0x17/0x75c [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.180245] PGD 102a92067 PUD 102ba6067 PMD 0 
Jun 10 14:57:17 mithrandir kernel: [  266.180292] Oops: 0000 [#1] PREEMPT SMP 
Jun 10 14:57:17 mithrandir kernel: [  266.180334] CPU 1 
Jun 10 14:57:17 mithrandir kernel: [  266.180352] Modules linked in: usb_storage rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod hso isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek btusb bluetooth crc16 arc4 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm mxm_wmi snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq firewire_ohci firewire_core joydev crc_itu_t snd_timer snd_seq_device snd sony_laptop tpm_infineon iwlagn mac80211 cfg80211 rfkill soundcore snd_page_alloc
Jun 10 14:57:17 mithrandir kernel: [  266.180889] 
Jun 10 14:57:17 mithrandir kernel: [  266.180905] Pid: 4060, comm: sync Not tainted 3.0.0-rc2+ #34 Sony Corporation VGN-Z11VN_B/VAIO
Jun 10 14:57:17 mithrandir kernel: [  266.180980] RIP: 0010:[<ffffffffa02276f9>]  [<ffffffffa02276f9>] btrfs_print_leaf+0x17/0x75c [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.181067] RSP: 0018:ffff88010e543b78  EFLAGS: 00010296
Jun 10 14:57:17 mithrandir kernel: [  266.181110] RAX: 00000000fffffffb RBX: 0000000000000000 RCX: ffff880107c9fea8
Jun 10 14:57:17 mithrandir kernel: [  266.181165] RDX: 00000044823a9000 RSI: 0000000000000000 RDI: ffff88012de2d000
Jun 10 14:57:17 mithrandir kernel: [  266.181221] RBP: ffff88010e543bf8 R08: 0000000000000001 R09: 0000000000000000
Jun 10 14:57:17 mithrandir kernel: [  266.181276] R10: 0000000000000000 R11: 0000004c42ba9000 R12: ffff88012de2d000
Jun 10 14:57:17 mithrandir kernel: [  266.181332] R13: 0000004482378000 R14: 0000000000000005 R15: 0000000000000000
Jun 10 14:57:17 mithrandir kernel: [  266.181388] FS:  00007f2d337cd700(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
Jun 10 14:57:17 mithrandir kernel: [  266.181451] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jun 10 14:57:17 mithrandir kernel: [  266.181497] CR2: 0000000000000030 CR3: 000000010e7c2000 CR4: 00000000000006e0
Jun 10 14:57:17 mithrandir kernel: [  266.181553] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 10 14:57:17 mithrandir kernel: [  266.181608] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 10 14:57:17 mithrandir kernel: [  266.181664] Process sync (pid: 4060, threadinfo ffff88010e542000, task ffff8800bb554da0)
Jun 10 14:57:17 mithrandir kernel: [  266.181727] Stack:
Jun 10 14:57:17 mithrandir kernel: [  266.181745]  ffff88010e543ba8 0000004482378000 ffff88012cf10800 ffff88012de2d000
Jun 10 14:57:17 mithrandir kernel: [  266.181814]  ffffffff00008050 0000000000000206 ffff88013b0fe2c0 0000000000000000
Jun 10 14:57:17 mithrandir kernel: [  266.181883]  a800000044823780 0000000000001000 ffff8801161eb4c0 ffff8801062957f0
Jun 10 14:57:17 mithrandir kernel: [  266.181952] Call Trace:
Jun 10 14:57:17 mithrandir kernel: [  266.181986]  [<ffffffffa0221862>] __btrfs_free_extent+0x24b/0x54f [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.182042]  [<ffffffff8139e63a>] ? _raw_spin_unlock+0x1e/0x29
Jun 10 14:57:17 mithrandir kernel: [  266.182105]  [<ffffffffa024ec12>] ? map_private_extent_buffer+0xaa/0xcd [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.182174]  [<ffffffffa0223d36>] run_clustered_refs+0x616/0x660 [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.182228]  [<ffffffff8139e63a>] ? _raw_spin_unlock+0x1e/0x29
Jun 10 14:57:17 mithrandir kernel: [  266.182290]  [<ffffffffa02636a3>] ? btrfs_find_ref_cluster+0xf6/0x137 [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.182357]  [<ffffffffa0223e4c>] btrfs_run_delayed_refs+0xcc/0x17c [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.182427]  [<ffffffffa0231a2f>] btrfs_commit_transaction+0x318/0x655 [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.182487]  [<ffffffff810481cc>] ? add_wait_queue+0x44/0x44
Jun 10 14:57:17 mithrandir kernel: [  266.182534]  [<ffffffff810d13d6>] ? __sync_filesystem+0x72/0x72
Jun 10 14:57:17 mithrandir kernel: [  266.182589]  [<ffffffffa0216bd5>] btrfs_sync_fs+0x62/0x66 [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.182639]  [<ffffffff810d13c2>] __sync_filesystem+0x5e/0x72
Jun 10 14:57:17 mithrandir kernel: [  266.182686]  [<ffffffff810d13e7>] sync_one_sb+0x11/0x13
Jun 10 14:57:17 mithrandir kernel: [  266.182730]  [<ffffffff810b49ec>] iterate_supers+0x6a/0xbf
Jun 10 14:57:17 mithrandir kernel: [  266.182775]  [<ffffffff810d146e>] sys_sync+0x3d/0x4f
Jun 10 14:57:17 mithrandir kernel: [  266.182816]  [<ffffffff8139f03b>] system_call_fastpath+0x16/0x1b
Jun 10 14:57:17 mithrandir kernel: [  266.182864] Code: ff ff 48 8b 80 38 e0 ff ff a8 08 74 05 e8 5f 5c 17 e1 5d c3 55 48 89 e5 41 57 41 56 41 55 41 54 49 89 fc 53 48 89 f3 48 83 ec 58 
Jun 10 14:57:17 mithrandir kernel: [  266.183224] RIP  [<ffffffffa02276f9>] btrfs_print_leaf+0x17/0x75c [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.183293]  RSP <ffff88010e543b78>
Jun 10 14:57:17 mithrandir kernel: [  266.183321] CR2: 0000000000000030
Jun 10 14:57:17 mithrandir kernel: [  266.203699] ---[ end trace 757ecf6cb9e574c9 ]---
Jun 10 14:57:17 mithrandir kernel: [  266.203784] BUG: unable to handle kernel paging request at 00000000000015bd
Jun 10 14:57:17 mithrandir kernel: [  266.203788] IP: [<ffffffff8111a212>] journal_start+0x2f/0xc1
Jun 10 14:57:17 mithrandir kernel: [  266.203795] PGD 0 
Jun 10 14:57:17 mithrandir kernel: [  266.203797] Oops: 0000 [#2] PREEMPT SMP 
Jun 10 14:57:17 mithrandir kernel: [  266.203800] CPU 1 
Jun 10 14:57:17 mithrandir kernel: [  266.203801] Modules linked in: usb_storage rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod hso isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek btusb bluetooth crc16 arc4 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm mxm_wmi snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq firewire_ohci firewire_core joydev crc_itu_t snd_timer snd_seq_device snd sony_laptop tpm_infineon iwlagn mac80211 cfg80211 rfkill soundcore snd_page_alloc
Jun 10 14:57:17 mithrandir kernel: [  266.203834] 
Jun 10 14:57:17 mithrandir kernel: [  266.203837] Pid: 4060, comm: sync Tainted: G      D     3.0.0-rc2+ #34 Sony Corporation VGN-Z11VN_B/VAIO
Jun 10 14:57:17 mithrandir kernel: [  266.203842] RIP: 0010:[<ffffffff8111a212>]  [<ffffffff8111a212>] journal_start+0x2f/0xc1
Jun 10 14:57:17 mithrandir kernel: [  266.203847] RSP: 0018:ffff88010e543398  EFLAGS: 00010282
Jun 10 14:57:17 mithrandir kernel: [  266.203849] RAX: 00000000000015bd RBX: ffff880101e5e540 RCX: 0000000000000003
Jun 10 14:57:17 mithrandir kernel: [  266.203852] RDX: 0000000000000002 RSI: 000000000000000e RDI: ffff88013a502800
Jun 10 14:57:17 mithrandir kernel: [  266.203855] RBP: ffff88010e5433b8 R08: ffffea0003df9de8 R09: 0000000000000037
Jun 10 14:57:17 mithrandir kernel: [  266.203858] R10: ffffffff814182f0 R11: 00000000ffffffff R12: ffff8800bb554da0
Jun 10 14:57:17 mithrandir kernel: [  266.203860] R13: ffff88013a502800 R14: 000000000000000e R15: 0000000000000040
Jun 10 14:57:17 mithrandir kernel: [  266.203864] FS:  0000000000000000(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
Jun 10 14:57:17 mithrandir kernel: [  266.203867] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jun 10 14:57:17 mithrandir kernel: [  266.203869] CR2: 00000000000015bd CR3: 0000000001617000 CR4: 00000000000006e0
Jun 10 14:57:17 mithrandir kernel: [  266.203872] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 10 14:57:17 mithrandir kernel: [  266.203875] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 10 14:57:17 mithrandir kernel: [  266.203878] Process sync (pid: 4060, threadinfo ffff88010e542000, task ffff8800bb554da0)
Jun 10 14:57:17 mithrandir kernel: [  266.203880] Stack:
Jun 10 14:57:17 mithrandir kernel: [  266.203882]  ffffffffffffffe2 ffffea0003df9de8 0000000000000000 0000000000000e40
Jun 10 14:57:17 mithrandir kernel: [  266.203886]  ffff88010e5433d8 ffffffff8110af41 ffff88012e44fa50 ffff88012e44fa50
Jun 10 14:57:17 mithrandir kernel: [  266.203890]  ffff88010e543468 ffffffff81105a08 0000001f1e3d4508 ffff88010e5434f8
Jun 10 14:57:17 mithrandir kernel: [  266.203894] Call Trace:
Jun 10 14:57:17 mithrandir kernel: [  266.203900]  [<ffffffff8110af41>] ext3_journal_start_sb+0x45/0x50
Jun 10 14:57:17 mithrandir kernel: [  266.203904]  [<ffffffff81105a08>] ext3_write_begin+0x93/0x1a1
Jun 10 14:57:17 mithrandir kernel: [  266.203909]  [<ffffffff810867db>] ? get_pageblock_flags_group+0x3c/0x76
Jun 10 14:57:17 mithrandir kernel: [  266.203913]  [<ffffffff81082739>] generic_file_buffered_write+0xf9/0x236
Jun 10 14:57:17 mithrandir kernel: [  266.203918]  [<ffffffff810362ba>] ? current_fs_time+0x32/0x39
Jun 10 14:57:17 mithrandir kernel: [  266.203923]  [<ffffffff810834f2>] __generic_file_aio_write+0x240/0x270
Jun 10 14:57:17 mithrandir kernel: [  266.203927]  [<ffffffff8108357e>] generic_file_aio_write+0x5c/0xb5
Jun 10 14:57:17 mithrandir kernel: [  266.203931]  [<ffffffff810b1f96>] do_sync_write+0xba/0xfa
Jun 10 14:57:17 mithrandir kernel: [  266.203937]  [<ffffffff810c82c5>] ? vfsmount_lock_local_unlock+0x33/0x3e
Jun 10 14:57:17 mithrandir kernel: [  266.203942]  [<ffffffff8109b68d>] ? remove_vma+0x57/0x5f
Jun 10 14:57:17 mithrandir kernel: [  266.203947]  [<ffffffff8104e7bf>] ? timekeeping_get_ns+0x12/0x31
Jun 10 14:57:17 mithrandir kernel: [  266.203951]  [<ffffffff8104ec33>] ? ktime_get_ts+0x80/0x89
Jun 10 14:57:17 mithrandir kernel: [  266.203957]  [<ffffffff81063049>] do_acct_process+0x28c/0x2bf
Jun 10 14:57:17 mithrandir kernel: [  266.203962]  [<ffffffff8106366d>] acct_process+0x73/0x92
Jun 10 14:57:17 mithrandir kernel: [  266.203965]  [<ffffffff81034ffb>] do_exit+0x244/0x6da
Jun 10 14:57:17 mithrandir kernel: [  266.203969]  [<ffffffff810335bd>] ? kmsg_dump+0xcb/0xda
Jun 10 14:57:17 mithrandir kernel: [  266.203974]  [<ffffffff81004912>] oops_end+0x89/0x8e
Jun 10 14:57:17 mithrandir kernel: [  266.203978]  [<ffffffff81396abd>] no_context+0x1fe/0x20d
Jun 10 14:57:17 mithrandir kernel: [  266.203982]  [<ffffffff81396c5a>] __bad_area_nosemaphore+0x18e/0x1b1
Jun 10 14:57:17 mithrandir kernel: [  266.203985]  [<ffffffff8139e63a>] ? _raw_spin_unlock+0x1e/0x29
Jun 10 14:57:17 mithrandir kernel: [  266.203989]  [<ffffffff81396ccf>] bad_area+0x42/0x49
Jun 10 14:57:17 mithrandir kernel: [  266.203994]  [<ffffffff8101d9a3>] do_page_fault+0x1ab/0x308
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa024e0db>] ? free_extent_buffer+0x12/0x29 [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa021a674>] ? read_block_for_search.isra.35+0x356/0x36b [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa021792f>] ? unlock_up+0xa7/0xcb [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff8139ec4f>] page_fault+0x1f/0x30
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa02276f9>] ? btrfs_print_leaf+0x17/0x75c [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa0221862>] __btrfs_free_extent+0x24b/0x54f [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff8139e63a>] ? _raw_spin_unlock+0x1e/0x29
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa024ec12>] ? map_private_extent_buffer+0xaa/0xcd [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa0223d36>] run_clustered_refs+0x616/0x660 [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff8139e63a>] ? _raw_spin_unlock+0x1e/0x29
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa02636a3>] ? btrfs_find_ref_cluster+0xf6/0x137 [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa0223e4c>] btrfs_run_delayed_refs+0xcc/0x17c [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa0231a2f>] btrfs_commit_transaction+0x318/0x655 [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff810481cc>] ? add_wait_queue+0x44/0x44
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff810d13d6>] ? __sync_filesystem+0x72/0x72
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffffa0216bd5>] btrfs_sync_fs+0x62/0x66 [btrfs]
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff810d13c2>] __sync_filesystem+0x5e/0x72
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff810d13e7>] sync_one_sb+0x11/0x13
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff810b49ec>] iterate_supers+0x6a/0xbf
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff810d146e>] sys_sync+0x3d/0x4f
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  [<ffffffff8139f03b>] system_call_fastpath+0x16/0x1b
Jun 10 14:57:17 mithrandir kernel: [  266.204006] Code: 41 56 41 89 f6 41 55 49 89 fd 41 54 53 65 4c 8b 24 25 00 b6 00 00 48 85 ff 49 8b 9c 24 f8 04 00 00 74 7e 48 85 db 74 0f 48 8b 03 
Jun 10 14:57:17 mithrandir kernel: [  266.204006] RIP  [<ffffffff8111a212>] journal_start+0x2f/0xc1
Jun 10 14:57:17 mithrandir kernel: [  266.204006]  RSP <ffff88010e543398>
Jun 10 14:57:17 mithrandir kernel: [  266.204006] CR2: 00000000000015bd
Jun 10 14:57:17 mithrandir kernel: [  266.204503] ---[ end trace 757ecf6cb9e574ca ]---
Jun 10 14:57:17 mithrandir kernel: [  266.204506] Fixing recursive fault but reboot is needed!
Jun 10 14:57:18 mithrandir kernel: [  266.354243] btrfs no csum found for inode 321772 start 0
Jun 10 14:57:18 mithrandir kernel: [  266.354268] btrfs no csum found for inode 321772 start 4096
Jun 10 14:57:18 mithrandir kernel: [  266.354288] btrfs no csum found for inode 321772 start 8192
Jun 10 14:57:18 mithrandir kernel: [  266.354308] btrfs no csum found for inode 321772 start 12288
Jun 10 14:57:18 mithrandir kernel: [  266.354342] btrfs no csum found for inode 321772 start 0
Jun 10 14:57:18 mithrandir kernel: [  266.354372] btrfs no csum found for inode 321772 start 4096
Jun 10 14:57:18 mithrandir kernel: [  266.354401] btrfs no csum found for inode 321772 start 8192
Jun 10 14:57:18 mithrandir kernel: [  266.354430] btrfs no csum found for inode 321772 start 12288
Jun 10 14:57:18 mithrandir kernel: [  266.354463] btrfs no csum found for inode 321772 start 0
Jun 10 14:57:18 mithrandir kernel: [  266.354492] btrfs no csum found for inode 321772 start 0
Jun 10 14:58:05 mithrandir kernel: [  313.354163] SysRq : Emergency Sync
Jun 10 14:58:05 mithrandir kernel: [  313.354621] Emergency Sync complete
Jun 10 14:58:05 mithrandir kernel: [  313.558844] SysRq : Emergency Sync
Jun 10 14:58:05 mithrandir kernel: [  313.559388] Emergency Sync complete
Jun 10 14:58:05 mithrandir kernel: [  313.724442] SysRq : Emergency Sync
Jun 10 14:58:05 mithrandir kernel: [  313.724719] Emergency Sync complete
Jun 10 14:58:05 mithrandir kernel: [  313.968301] SysRq : Emergency Sync
Jun 10 14:58:05 mithrandir kernel: [  313.968769] Emergency Sync complete
Jun 10 14:58:06 mithrandir kernel: [  314.262330] SysRq : Emergency Sync
Jun 10 14:58:06 mithrandir kernel: [  314.262873] Emergency Sync complete
Jun 10 14:58:06 mithrandir kernel: [  314.432943] SysRq : Emergency Sync
Jun 10 14:58:06 mithrandir kernel: [  314.433519] Emergency Sync complete
Jun 10 14:58:06 mithrandir kernel: [  314.601535] SysRq : Emergency Remount R/O



And that was with the same kernel, without anything btrfs related,
just normal aptitude update run and browsing the web and reading
email with ssh/mutt.


Jun 11 10:58:42 mithrandir kernel: [  564.924468] ------------[ cut here ]------------
Jun 11 10:58:42 mithrandir kernel: [  564.924504] kernel BUG at fs/inode.c:1368!
Jun 11 10:58:42 mithrandir kernel: [  564.924527] invalid opcode: 0000 [#1] PREEMPT SMP 
Jun 11 10:58:42 mithrandir kernel: [  564.924562] CPU 1 
Jun 11 10:58:42 mithrandir kernel: [  564.924576] Modules linked in: rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod hso isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek btusb bluetooth crc16 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss arc4 snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi mxm_wmi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device firewire_ohci joydev snd firewire_core crc_itu_t iwlagn mac80211 cfg80211 sony_laptop rfkill tpm_infineon soundcore snd_page_alloc
Jun 11 10:58:42 mithrandir kernel: [  564.924965] 
Jun 11 10:58:42 mithrandir kernel: [  564.924977] Pid: 7463, comm: rm Not tainted 3.0.0-rc2+ #34 Sony Corporation VGN-Z11VN_B/VAIO
Jun 11 10:58:42 mithrandir kernel: [  564.925030] RIP: 0010:[<ffffffff810c5354>]  [<ffffffff810c5354>] iput+0x1c/0x149
Jun 11 10:58:42 mithrandir kernel: [  564.925074] RSP: 0018:ffff8800adfcde48  EFLAGS: 00010202
Jun 11 10:58:42 mithrandir kernel: [  564.925104] RAX: 0000000000000000 RBX: ffff88009f58fa50 RCX: ffff880000000050
Jun 11 10:58:42 mithrandir kernel: [  564.925139] RDX: 0000000100010200 RSI: ffffffff8110a184 RDI: ffff88009f58fa50
Jun 11 10:58:42 mithrandir kernel: [  564.925175] RBP: ffff8800adfcde68 R08: 0000000000000000 R09: 0000000000000000
Jun 11 10:58:42 mithrandir kernel: [  564.925210] R10: ffff88012e8c6cf8 R11: ffff88013a46b600 R12: ffff880139abb6c0
Jun 11 10:58:42 mithrandir kernel: [  564.925245] R13: ffff88009f58fa50 R14: 0000000000000001 R15: ffff8800bb5bfe10
Jun 11 10:58:42 mithrandir kernel: [  564.925281] FS:  00007f9e67d0b700(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
Jun 11 10:58:42 mithrandir kernel: [  564.925321] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jun 11 10:58:42 mithrandir kernel: [  564.925350] CR2: 00000000013e2018 CR3: 00000000a5edd000 CR4: 00000000000006e0
Jun 11 10:58:42 mithrandir kernel: [  564.925386] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 11 10:58:42 mithrandir kernel: [  564.925421] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 11 10:58:42 mithrandir kernel: [  564.925457] Process rm (pid: 7463, threadinfo ffff8800adfcc000, task ffff88013f5f0b30)
Jun 11 10:58:42 mithrandir kernel: [  564.925497] Stack:
Jun 11 10:58:42 mithrandir kernel: [  564.925509]  ffffffff8110a184 ffff88009f8fb240 ffff880139abb6c0 ffff88009f58fa50
Jun 11 10:58:42 mithrandir kernel: [  564.925555]  ffff8800adfcde98 ffffffff810c26a7 ffff88009f8fb240 ffff88009f8fb29c
Jun 11 10:58:42 mithrandir kernel: [  564.925600]  ffff88009f58fa50 ffff88009f8fb240 ffff8800adfcdec8 ffffffff810c2bf5
Jun 11 10:58:42 mithrandir kernel: [  564.925646] Call Trace:
Jun 11 10:58:42 mithrandir kernel: [  564.925662]  [<ffffffff8110a184>] ? ext3_destroy_inode+0x8b/0x8b
Jun 11 10:58:42 mithrandir kernel: [  564.925695]  [<ffffffff810c26a7>] dentry_kill+0x122/0x144
Jun 11 10:58:42 mithrandir kernel: [  564.925723]  [<ffffffff810c2bf5>] dput+0xda/0xeb
Jun 11 10:58:42 mithrandir kernel: [  564.925749]  [<ffffffff810b3999>] fput+0x18d/0x1a5
Jun 11 10:58:42 mithrandir kernel: [  564.925775]  [<ffffffff810b0bfc>] filp_close+0x69/0x75
Jun 11 10:58:42 mithrandir kernel: [  564.925802]  [<ffffffff810b0cb0>] sys_close+0xa8/0xea
Jun 11 10:58:42 mithrandir kernel: [  564.925830]  [<ffffffff8139f03b>] system_call_fastpath+0x16/0x1b
Jun 11 10:58:42 mithrandir kernel: [  564.925861] Code: fe ff ff 48 8b 03 48 39 d8 75 da 58 5b 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 56 0f 84 2b 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 02 f6 08 00 85 c0 0f 
Jun 11 10:58:42 mithrandir kernel: [  564.926111] RIP  [<ffffffff810c5354>] iput+0x1c/0x149
Jun 11 10:58:42 mithrandir kernel: [  564.926142]  RSP <ffff8800adfcde48>
Jun 11 10:58:42 mithrandir kernel: [  564.945053] ---[ end trace 4a6eb3e9afabf726 ]---
Jun 11 10:58:43 mithrandir kernel: [  565.068687] ------------[ cut here ]------------
Jun 11 10:58:43 mithrandir kernel: [  565.068719] kernel BUG at fs/inode.c:1368!
Jun 11 10:58:43 mithrandir kernel: [  565.070455] invalid opcode: 0000 [#2] PREEMPT SMP 
Jun 11 10:58:43 mithrandir kernel: [  565.072005] CPU 1 
Jun 11 10:58:43 mithrandir kernel: [  565.072005] Modules linked in: rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod hso isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek btusb bluetooth crc16 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss arc4 snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi mxm_wmi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device firewire_ohci joydev snd firewire_core crc_itu_t iwlagn mac80211 cfg80211 sony_laptop rfkill tpm_infineon soundcore snd_page_alloc
Jun 11 10:58:43 mithrandir kernel: [  565.072005] 
Jun 11 10:58:43 mithrandir kernel: [  565.072005] Pid: 7466, comm: rm Tainted: G      D     3.0.0-rc2+ #34 Sony Corporation VGN-Z11VN_B/VAIO
Jun 11 10:58:43 mithrandir kernel: [  565.072005] RIP: 0010:[<ffffffff810c5354>]  [<ffffffff810c5354>] iput+0x1c/0x149
Jun 11 10:58:43 mithrandir kernel: [  565.072005] RSP: 0018:ffff8800a5cbfe48  EFLAGS: 00010202
Jun 11 10:58:43 mithrandir kernel: [  565.072005] RAX: 0000000000000000 RBX: ffff88009d584a50 RCX: ffff880000000050
Jun 11 10:58:43 mithrandir kernel: [  565.072005] RDX: 0000000100010200 RSI: ffffffff8110a184 RDI: ffff88009d584a50
Jun 11 10:58:43 mithrandir kernel: [  565.072005] RBP: ffff8800a5cbfe68 R08: 0000000000000000 R09: 0000000000000000
Jun 11 10:58:43 mithrandir kernel: [  565.072005] R10: ffff88012e8c6cf8 R11: ffff88013a46b600 R12: ffff8800bb002440
Jun 11 10:58:43 mithrandir kernel: [  565.072005] R13: ffff88009d584a50 R14: 0000000000000001 R15: ffff8800bb519250
Jun 11 10:58:43 mithrandir kernel: [  565.072005] FS:  00007fa577401700(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
Jun 11 10:58:43 mithrandir kernel: [  565.072005] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Jun 11 10:58:43 mithrandir kernel: [  565.072005] CR2: 00000000018dd078 CR3: 00000000bb71e000 CR4: 00000000000006e0
Jun 11 10:58:43 mithrandir kernel: [  565.072005] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 11 10:58:43 mithrandir kernel: [  565.072005] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 11 10:58:43 mithrandir kernel: [  565.072005] Process rm (pid: 7466, threadinfo ffff8800a5cbe000, task ffff88013f49a040)
Jun 11 10:58:43 mithrandir kernel: [  565.072005] Stack:
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  ffffffff8110a184 ffff88009d581ec0 ffff8800bb002440 ffff88009d584a50
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  ffff8800a5cbfe98 ffffffff810c26a7 ffff88009d581ec0 ffff88009d581f1c
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  ffff88009d584a50 ffff88009d581ec0 ffff8800a5cbfec8 ffffffff810c2bf5
Jun 11 10:58:43 mithrandir kernel: [  565.072005] Call Trace:
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  [<ffffffff8110a184>] ? ext3_destroy_inode+0x8b/0x8b
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  [<ffffffff810c26a7>] dentry_kill+0x122/0x144
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  [<ffffffff810c2bf5>] dput+0xda/0xeb
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  [<ffffffff810b3999>] fput+0x18d/0x1a5
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  [<ffffffff810b0bfc>] filp_close+0x69/0x75
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  [<ffffffff810b0cb0>] sys_close+0xa8/0xea
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  [<ffffffff8139f03b>] system_call_fastpath+0x16/0x1b
Jun 11 10:58:43 mithrandir kernel: [  565.072005] Code: fe ff ff 48 8b 03 48 39 d8 75 da 58 5b 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 56 0f 84 2b 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 02 f6 08 00 85 c0 0f 
Jun 11 10:58:43 mithrandir kernel: [  565.072005] RIP  [<ffffffff810c5354>] iput+0x1c/0x149
Jun 11 10:58:43 mithrandir kernel: [  565.072005]  RSP <ffff8800a5cbfe48>
Jun 11 10:58:43 mithrandir kernel: [  565.133844] ---[ end trace 4a6eb3e9afabf727 ]---
Jun 11 10:59:18 mithrandir dbus[2209]: [system] Failed to activate service 'org.freedesktop.Avahi': timed out
Jun 11 10:59:38 mithrandir kernel: [  620.107657] SysRq : Emergency Sync
Jun 11 10:59:38 mithrandir kernel: [  620.523102] SysRq : Emergency Sync
Jun 11 10:59:38 mithrandir kernel: [  620.646745] ------------[ cut here ]------------
Jun 11 10:59:38 mithrandir kernel: [  620.646753] WARNING: at fs/fs-writeback.c:356 writeback_single_inode+0x60/0x1c5()
Jun 11 10:59:38 mithrandir kernel: [  620.646755] Hardware name: VGN-Z11VN_B
Jun 11 10:59:38 mithrandir kernel: [  620.646756] Modules linked in: rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod hso isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek btusb bluetooth crc16 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss arc4 snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi mxm_wmi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device firewire_ohci joydev snd firewire_core crc_itu_t iwlagn mac80211 cfg80211 sony_laptop rfkill tpm_infineon soundcore snd_page_alloc
Jun 11 10:59:38 mithrandir kernel: [  620.646797] Pid: 2263, comm: flush-8:0 Tainted: G      D     3.0.0-rc2+ #34
Jun 11 10:59:38 mithrandir kernel: [  620.646799] Call Trace:
Jun 11 10:59:38 mithrandir kernel: [  620.646803]  [<ffffffff81032061>] warn_slowpath_common+0x7e/0x96
Jun 11 10:59:38 mithrandir kernel: [  620.646806]  [<ffffffff8103208e>] warn_slowpath_null+0x15/0x17
Jun 11 10:59:38 mithrandir kernel: [  620.646808]  [<ffffffff810cdd26>] writeback_single_inode+0x60/0x1c5
Jun 11 10:59:38 mithrandir kernel: [  620.646811]  [<ffffffff810cdfd8>] writeback_sb_inodes+0xc4/0x14c
Jun 11 10:59:38 mithrandir kernel: [  620.646814]  [<ffffffff810ce5a7>] wb_writeback+0x18b/0x253
Jun 11 10:59:38 mithrandir kernel: [  620.646818]  [<ffffffff81025b50>] ? finish_task_switch+0x7e/0xaa
Jun 11 10:59:38 mithrandir kernel: [  620.646820]  [<ffffffff810ce6bf>] wb_do_writeback+0x50/0x161
Jun 11 10:59:38 mithrandir kernel: [  620.646824]  [<ffffffff8139e665>] ? _raw_spin_unlock_irqrestore+0x20/0x2b
Jun 11 10:59:38 mithrandir kernel: [  620.646827]  [<ffffffff8103c036>] ? del_timer+0x6b/0x79
Jun 11 10:59:38 mithrandir kernel: [  620.646830]  [<ffffffff810ce83d>] bdi_writeback_thread+0x6d/0x151
Jun 11 10:59:38 mithrandir kernel: [  620.646833]  [<ffffffff810ce7d0>] ? wb_do_writeback+0x161/0x161
Jun 11 10:59:38 mithrandir kernel: [  620.646836]  [<ffffffff81047bb0>] kthread+0x7f/0x87
Jun 11 10:59:38 mithrandir kernel: [  620.646839]  [<ffffffff8139fd54>] kernel_thread_helper+0x4/0x10
Jun 11 10:59:38 mithrandir kernel: [  620.646842]  [<ffffffff81047b31>] ? kthread_worker_fn+0x141/0x141
Jun 11 10:59:38 mithrandir kernel: [  620.646844]  [<ffffffff8139fd50>] ? gs_change+0xb/0xb
Jun 11 10:59:38 mithrandir kernel: [  620.646846] ---[ end trace 4a6eb3e9afabf728 ]---


Let me know if you need more information.

Best wishes

Norbert
------------------------------------------------------------------------
Norbert Preining            preining@{jaist.ac.jp, logic.at, debian.org}
JAIST, Japan                                 TeX Live & Debian Developer
DSA: 0x09C5B094   fp: 14DF 2E6C 0307 BE6D AD76  A9C0 D2BF 4AA3 09C5 B094
------------------------------------------------------------------------
WILLIMANTIC (adj.)
Of a person whose hearth is in the wrong place (i.e. between their
legs).
			--- Douglas Adams, The Meaning of Liff

another ext3 kernel BUG

[Norbert Preining]

(please cc)

As already reported 2 times, here another BUG that occurs agian and
again when doing heavy IO stuff (aptitude update plus kernel compile eg).

Jun 14 09:52:59 mithrandir kernel: [14128.489923] ------------[ cut here ]------------
Jun 14 09:52:59 mithrandir kernel: [14128.489957] kernel BUG at fs/inode.c:1368!
Jun 14 09:52:59 mithrandir kernel: [14128.489980] invalid opcode: 0000 [#1] PREEMPT SMP 
Jun 14 09:52:59 mithrandir kernel: [14128.490012] CPU 0 
Jun 14 09:52:59 mithrandir kernel: [14128.490025] Modules linked in: rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek arc4 snd_hda_intel iwlagn snd_hda_codec mac80211 snd_hwdep cfg80211 snd_pcm_oss snd_mixer_oss snd_pcm hso snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device sony_laptop snd btusb bluetooth crc16 rfkill soundcore snd_page_alloc joydev firewire_ohci firewire_core mxm_wmi crc_itu_t tpm_infineon
Jun 14 09:52:59 mithrandir kernel: [14128.490389] 
Jun 14 09:52:59 mithrandir kernel: [14128.490401] Pid: 10687, comm: rm Not tainted 3.0.0-rc2+ #35 Sony Corporation VGN-Z11VN_B/VAIO
Jun 14 09:52:59 mithrandir kernel: [14128.490449] RIP: 0010:[<ffffffff810c5354>]  [<ffffffff810c5354>] iput+0x1c/0x149
Jun 14 09:52:59 mithrandir kernel: [14128.490493] RSP: 0018:ffff8801324cbe48  EFLAGS: 00010202
Jun 14 09:52:59 mithrandir kernel: [14128.490521] RAX: 0000000000000000 RBX: ffff88005f6eda50 RCX: ffff880100000050
Jun 14 09:52:59 mithrandir kernel: [14128.490556] RDX: 000000010034c000 RSI: ffffffff8110a184 RDI: ffff88005f6eda50
Jun 14 09:52:59 mithrandir kernel: [14128.490592] RBP: ffff8801324cbe68 R08: 000000000000000b R09: 0000000000000000
Jun 14 09:52:59 mithrandir kernel: [14128.490627] R10: ffff88010e9e4818 R11: ffff88013a4ffc00 R12: ffff88004fb03380
Jun 14 09:52:59 mithrandir kernel: [14128.490662] R13: ffff88005f6eda50 R14: 0000000000000001 R15: ffff88003d4e4510
Jun 14 09:52:59 mithrandir kernel: [14128.490698] FS:  00007fd842776700(0000) GS:ffff88013fc00000(0000) knlGS:0000000000000000
Jun 14 09:52:59 mithrandir kernel: [14128.490740] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Jun 14 09:52:59 mithrandir kernel: [14128.490769] CR2: 0000000000408080 CR3: 0000000132951000 CR4: 00000000000006f0
Jun 14 09:52:59 mithrandir kernel: [14128.490805] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 14 09:52:59 mithrandir kernel: [14128.490840] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 14 09:52:59 mithrandir kernel: [14128.490876] Process rm (pid: 10687, threadinfo ffff8801324ca000, task ffff8800229b89b0)
Jun 14 09:52:59 mithrandir kernel: [14128.490915] Stack:
Jun 14 09:52:59 mithrandir kernel: [14128.490927]  ffffffff8110a184 ffff880101d9bcc0 ffff88004fb03380 ffff88005f6eda50
Jun 14 09:52:59 mithrandir kernel: [14128.490973]  ffff8801324cbe98 ffffffff810c26a7 ffff880101d9bcc0 ffff880101d9bd1c
Jun 14 09:52:59 mithrandir kernel: [14128.491019]  ffff88005f6eda50 ffff880101d9bcc0 ffff8801324cbec8 ffffffff810c2bf5
Jun 14 09:52:59 mithrandir kernel: [14128.491064] Call Trace:
Jun 14 09:52:59 mithrandir kernel: [14128.491080]  [<ffffffff8110a184>] ? ext3_destroy_inode+0x8b/0x8b
Jun 14 09:52:59 mithrandir kernel: [14128.491114]  [<ffffffff810c26a7>] dentry_kill+0x122/0x144
Jun 14 09:52:59 mithrandir kernel: [14128.491143]  [<ffffffff810c2bf5>] dput+0xda/0xeb
Jun 14 09:52:59 mithrandir kernel: [14128.491169]  [<ffffffff810b3999>] fput+0x18d/0x1a5
Jun 14 09:52:59 mithrandir kernel: [14128.491195]  [<ffffffff810b0bfc>] filp_close+0x69/0x75
Jun 14 09:52:59 mithrandir kernel: [14128.491223]  [<ffffffff810b0cb0>] sys_close+0xa8/0xea
Jun 14 09:52:59 mithrandir kernel: [14128.491251]  [<ffffffff8139f07b>] system_call_fastpath+0x16/0x1b
Jun 14 09:52:59 mithrandir kernel: [14128.491282] Code: fe ff ff 48 8b 03 48 39 d8 75 da 58 5b 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 56 0f 84 2b 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 02 f6 08 00 85 c0 0f 
Jun 14 09:52:59 mithrandir kernel: [14128.491533] RIP  [<ffffffff810c5354>] iput+0x1c/0x149
Jun 14 09:52:59 mithrandir kernel: [14128.491563]  RSP <ffff8801324cbe48>
Jun 14 09:52:59 mithrandir kernel: [14128.513847] ---[ end trace ebb3cd31d1ade445 ]---
Jun 14 09:52:59 mithrandir kernel: [14128.696044] ------------[ cut here ]------------
Jun 14 09:52:59 mithrandir kernel: [14128.697889] kernel BUG at fs/inode.c:1368!
Jun 14 09:52:59 mithrandir kernel: [14128.699685] invalid opcode: 0000 [#2] PREEMPT SMP 
Jun 14 09:52:59 mithrandir kernel: [14128.700009] CPU 0 
Jun 14 09:52:59 mithrandir kernel: [14128.700009] Modules linked in: rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek arc4 snd_hda_intel iwlagn snd_hda_codec mac80211 snd_hwdep cfg80211 snd_pcm_oss snd_mixer_oss snd_pcm hso snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device sony_laptop snd btusb bluetooth crc16 rfkill soundcore snd_page_alloc joydev firewire_ohci firewire_core mxm_wmi crc_itu_t tpm_infineon
Jun 14 09:52:59 mithrandir kernel: [14128.700009] 
Jun 14 09:52:59 mithrandir kernel: [14128.700009] Pid: 10704, comm: rm Tainted: G      D     3.0.0-rc2+ #35 Sony Corporation VGN-Z11VN_B/VAIO
Jun 14 09:52:59 mithrandir kernel: [14128.700009] RIP: 0010:[<ffffffff810c5354>]  [<ffffffff810c5354>] iput+0x1c/0x149
Jun 14 09:52:59 mithrandir kernel: [14128.700009] RSP: 0000:ffff88011454fe48  EFLAGS: 00010202
Jun 14 09:52:59 mithrandir kernel: [14128.700009] RAX: 0000000000000000 RBX: ffff8800ab6e7740 RCX: ffff880100000050
Jun 14 09:52:59 mithrandir kernel: [14128.700009] RDX: 000000010034c000 RSI: ffffffff8110a184 RDI: ffff8800ab6e7740
Jun 14 09:52:59 mithrandir kernel: [14128.700009] RBP: ffff88011454fe68 R08: 0000000000000000 R09: 0000000000000000
Jun 14 09:52:59 mithrandir kernel: [14128.700009] R10: ffff88010e9e4818 R11: ffff88013a4ffc00 R12: ffff8800938e1200
Jun 14 09:52:59 mithrandir kernel: [14128.700009] R13: ffff8800ab6e7740 R14: 0000000000000001 R15: ffff880025fbf5d0
Jun 14 09:52:59 mithrandir kernel: [14128.700009] FS:  00007f16ab273700(0000) GS:ffff88013fc00000(0000) knlGS:0000000000000000
Jun 14 09:52:59 mithrandir kernel: [14128.700009] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jun 14 09:52:59 mithrandir kernel: [14128.700009] CR2: 00002adf57a41000 CR3: 00000001328dc000 CR4: 00000000000006f0
Jun 14 09:52:59 mithrandir kernel: [14128.700009] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 14 09:52:59 mithrandir kernel: [14128.700009] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 14 09:52:59 mithrandir kernel: [14128.700009] Process rm (pid: 10704, threadinfo ffff88011454e000, task ffff88000f4be970)
Jun 14 09:52:59 mithrandir kernel: [14128.700009] Stack:
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  ffffffff8110a184 ffff88004fb03140 ffff8800938e1200 ffff8800ab6e7740
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  ffff88011454fe98 ffffffff810c26a7 ffff88004fb03140 ffff88004fb0319c
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  ffff8800ab6e7740 ffff88004fb03140 ffff88011454fec8 ffffffff810c2bf5
Jun 14 09:52:59 mithrandir kernel: [14128.700009] Call Trace:
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff8110a184>] ? ext3_destroy_inode+0x8b/0x8b
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810c26a7>] dentry_kill+0x122/0x144
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810c2bf5>] dput+0xda/0xeb
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810b3999>] fput+0x18d/0x1a5
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810b0bfc>] filp_close+0x69/0x75
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810b0cb0>] sys_close+0xa8/0xea
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff8139f07b>] system_call_fastpath+0x16/0x1b
Jun 14 09:52:59 mithrandir kernel: [14128.700009] Code: fe ff ff 48 8b 03 48 39 d8 75 da 58 5b 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 56 0f 84 2b 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 02 f6 08 00 85 c0 0f 
Jun 14 09:52:59 mithrandir kernel: [14128.700009] RIP  [<ffffffff810c5354>] iput+0x1c/0x149
Jun 14 09:52:59 mithrandir kernel: [14128.700009]  RSP <ffff88011454fe48>
Jun 14 09:52:59 mithrandir kernel: [14128.768256] ---[ end trace ebb3cd31d1ade446 ]---


Best wishes

Norbert
------------------------------------------------------------------------
Norbert Preining            preining@{jaist.ac.jp, logic.at, debian.org}
JAIST, Japan                                 TeX Live & Debian Developer
DSA: 0x09C5B094   fp: 14DF 2E6C 0307 BE6D AD76  A9C0 D2BF 4AA3 09C5 B094
------------------------------------------------------------------------
FOVANT (n.)
A taxi driver's gesture, a raised hand pointed out of the window which
purports to mean 'thank you' and actually means 'fuck off out of the
way'.
			--- Douglas Adams, The Meaning of Liff

Re: another ext3 kernel BUG

[Eric Sandeen]

On 6/13/11 8:19 PM, Norbert Preining wrote:
> (please cc)
> 
> As already reported 2 times, here another BUG that occurs agian and
> again when doing heavy IO stuff (aptitude update plus kernel compile eg).
> 
> Jun 14 09:52:59 mithrandir kernel: [14128.489923] ------------[ cut here ]------------
> Jun 14 09:52:59 mithrandir kernel: [14128.489957] kernel BUG at fs/inode.c:1368!

BUG_ON(inode->i_state & I_CLEAR);

Disassembly of your code might show which register has i_state ... I wonder if this could possibly be corruption of some sort?  It'd be nice to know what was found in i_state.   Modifying iput() to print it out prior to the bug_on might also help.  Just a thought.

You seem uniquely able to hit it, and you do have a truckload of interesting modules loaded.  :)

-Eric

> Jun 14 09:52:59 mithrandir kernel: [14128.489980] invalid opcode: 0000 [#1] PREEMPT SMP 
> Jun 14 09:52:59 mithrandir kernel: [14128.490012] CPU 0 
> Jun 14 09:52:59 mithrandir kernel: [14128.490025] Modules linked in: rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek arc4 snd_hda_intel iwlagn snd_hda_codec mac80211 snd_hwdep cfg80211 snd_pcm_oss snd_mixer_oss snd_pcm hso snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device sony_laptop snd btusb bluetooth crc16 rfkill soundcore snd_page_alloc joydev firewire_ohci firewire_core mxm_wmi crc_itu_t tpm_infineon
> Jun 14 09:52:59 mithrandir kernel: [14128.490389] 
> Jun 14 09:52:59 mithrandir kernel: [14128.490401] Pid: 10687, comm: rm Not tainted 3.0.0-rc2+ #35 Sony Corporation VGN-Z11VN_B/VAIO
> Jun 14 09:52:59 mithrandir kernel: [14128.490449] RIP: 0010:[<ffffffff810c5354>]  [<ffffffff810c5354>] iput+0x1c/0x149
> Jun 14 09:52:59 mithrandir kernel: [14128.490493] RSP: 0018:ffff8801324cbe48  EFLAGS: 00010202
> Jun 14 09:52:59 mithrandir kernel: [14128.490521] RAX: 0000000000000000 RBX: ffff88005f6eda50 RCX: ffff880100000050
> Jun 14 09:52:59 mithrandir kernel: [14128.490556] RDX: 000000010034c000 RSI: ffffffff8110a184 RDI: ffff88005f6eda50
> Jun 14 09:52:59 mithrandir kernel: [14128.490592] RBP: ffff8801324cbe68 R08: 000000000000000b R09: 0000000000000000
> Jun 14 09:52:59 mithrandir kernel: [14128.490627] R10: ffff88010e9e4818 R11: ffff88013a4ffc00 R12: ffff88004fb03380
> Jun 14 09:52:59 mithrandir kernel: [14128.490662] R13: ffff88005f6eda50 R14: 0000000000000001 R15: ffff88003d4e4510
> Jun 14 09:52:59 mithrandir kernel: [14128.490698] FS:  00007fd842776700(0000) GS:ffff88013fc00000(0000) knlGS:0000000000000000
> Jun 14 09:52:59 mithrandir kernel: [14128.490740] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> Jun 14 09:52:59 mithrandir kernel: [14128.490769] CR2: 0000000000408080 CR3: 0000000132951000 CR4: 00000000000006f0
> Jun 14 09:52:59 mithrandir kernel: [14128.490805] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> Jun 14 09:52:59 mithrandir kernel: [14128.490840] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> Jun 14 09:52:59 mithrandir kernel: [14128.490876] Process rm (pid: 10687, threadinfo ffff8801324ca000, task ffff8800229b89b0)
> Jun 14 09:52:59 mithrandir kernel: [14128.490915] Stack:
> Jun 14 09:52:59 mithrandir kernel: [14128.490927]  ffffffff8110a184 ffff880101d9bcc0 ffff88004fb03380 ffff88005f6eda50
> Jun 14 09:52:59 mithrandir kernel: [14128.490973]  ffff8801324cbe98 ffffffff810c26a7 ffff880101d9bcc0 ffff880101d9bd1c
> Jun 14 09:52:59 mithrandir kernel: [14128.491019]  ffff88005f6eda50 ffff880101d9bcc0 ffff8801324cbec8 ffffffff810c2bf5
> Jun 14 09:52:59 mithrandir kernel: [14128.491064] Call Trace:
> Jun 14 09:52:59 mithrandir kernel: [14128.491080]  [<ffffffff8110a184>] ? ext3_destroy_inode+0x8b/0x8b
> Jun 14 09:52:59 mithrandir kernel: [14128.491114]  [<ffffffff810c26a7>] dentry_kill+0x122/0x144
> Jun 14 09:52:59 mithrandir kernel: [14128.491143]  [<ffffffff810c2bf5>] dput+0xda/0xeb
> Jun 14 09:52:59 mithrandir kernel: [14128.491169]  [<ffffffff810b3999>] fput+0x18d/0x1a5
> Jun 14 09:52:59 mithrandir kernel: [14128.491195]  [<ffffffff810b0bfc>] filp_close+0x69/0x75
> Jun 14 09:52:59 mithrandir kernel: [14128.491223]  [<ffffffff810b0cb0>] sys_close+0xa8/0xea
> Jun 14 09:52:59 mithrandir kernel: [14128.491251]  [<ffffffff8139f07b>] system_call_fastpath+0x16/0x1b
> Jun 14 09:52:59 mithrandir kernel: [14128.491282] Code: fe ff ff 48 8b 03 48 39 d8 75 da 58 5b 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 56 0f 84 2b 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 02 f6 08 00 85 c0 0f 
> Jun 14 09:52:59 mithrandir kernel: [14128.491533] RIP  [<ffffffff810c5354>] iput+0x1c/0x149
> Jun 14 09:52:59 mithrandir kernel: [14128.491563]  RSP <ffff8801324cbe48>
> Jun 14 09:52:59 mithrandir kernel: [14128.513847] ---[ end trace ebb3cd31d1ade445 ]---
> Jun 14 09:52:59 mithrandir kernel: [14128.696044] ------------[ cut here ]------------
> Jun 14 09:52:59 mithrandir kernel: [14128.697889] kernel BUG at fs/inode.c:1368!
> Jun 14 09:52:59 mithrandir kernel: [14128.699685] invalid opcode: 0000 [#2] PREEMPT SMP 
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] CPU 0 
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] Modules linked in: rfcomm bnep snd_hrtimer vboxnetadp vboxnetflt vboxdrv binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek arc4 snd_hda_intel iwlagn snd_hda_codec mac80211 snd_hwdep cfg80211 snd_pcm_oss snd_mixer_oss snd_pcm hso snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device sony_laptop snd btusb bluetooth crc16 rfkill soundcore snd_page_alloc joydev firewire_ohci firewire_core mxm_wmi crc_itu_t tpm_infineon
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] 
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] Pid: 10704, comm: rm Tainted: G      D     3.0.0-rc2+ #35 Sony Corporation VGN-Z11VN_B/VAIO
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] RIP: 0010:[<ffffffff810c5354>]  [<ffffffff810c5354>] iput+0x1c/0x149
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] RSP: 0000:ffff88011454fe48  EFLAGS: 00010202
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] RAX: 0000000000000000 RBX: ffff8800ab6e7740 RCX: ffff880100000050
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] RDX: 000000010034c000 RSI: ffffffff8110a184 RDI: ffff8800ab6e7740
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] RBP: ffff88011454fe68 R08: 0000000000000000 R09: 0000000000000000
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] R10: ffff88010e9e4818 R11: ffff88013a4ffc00 R12: ffff8800938e1200
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] R13: ffff8800ab6e7740 R14: 0000000000000001 R15: ffff880025fbf5d0
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] FS:  00007f16ab273700(0000) GS:ffff88013fc00000(0000) knlGS:0000000000000000
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] CR2: 00002adf57a41000 CR3: 00000001328dc000 CR4: 00000000000006f0
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] Process rm (pid: 10704, threadinfo ffff88011454e000, task ffff88000f4be970)
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] Stack:
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  ffffffff8110a184 ffff88004fb03140 ffff8800938e1200 ffff8800ab6e7740
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  ffff88011454fe98 ffffffff810c26a7 ffff88004fb03140 ffff88004fb0319c
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  ffff8800ab6e7740 ffff88004fb03140 ffff88011454fec8 ffffffff810c2bf5
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] Call Trace:
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff8110a184>] ? ext3_destroy_inode+0x8b/0x8b
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810c26a7>] dentry_kill+0x122/0x144
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810c2bf5>] dput+0xda/0xeb
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810b3999>] fput+0x18d/0x1a5
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810b0bfc>] filp_close+0x69/0x75
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff810b0cb0>] sys_close+0xa8/0xea
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  [<ffffffff8139f07b>] system_call_fastpath+0x16/0x1b
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] Code: fe ff ff 48 8b 03 48 39 d8 75 da 58 5b 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 56 0f 84 2b 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 02 f6 08 00 85 c0 0f 
> Jun 14 09:52:59 mithrandir kernel: [14128.700009] RIP  [<ffffffff810c5354>] iput+0x1c/0x149
> Jun 14 09:52:59 mithrandir kernel: [14128.700009]  RSP <ffff88011454fe48>
> Jun 14 09:52:59 mithrandir kernel: [14128.768256] ---[ end trace ebb3cd31d1ade446 ]---
> 
> 
> Best wishes
> 
> Norbert
> ------------------------------------------------------------------------
> Norbert Preining            preining@{jaist.ac.jp, logic.at, debian.org}
> JAIST, Japan                                 TeX Live & Debian Developer
> DSA: 0x09C5B094   fp: 14DF 2E6C 0307 BE6D AD76  A9C0 D2BF 4AA3 09C5 B094
> ------------------------------------------------------------------------
> FOVANT (n.)
> A taxi driver's gesture, a raised hand pointed out of the window which
> purports to mean 'thank you' and actually means 'fuck off out of the
> way'.
> 			--- Douglas Adams, The Meaning of Liff
> --
> To unsubscribe from this list: send the line "unsubscribe linux-ext4" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html

Re: another ext3 kernel BUG

[Norbert Preining]

Hi Eric,

thanks for the answer.

On Mo, 13 Jun 2011, Eric Sandeen wrote:
> Disassembly of your code might show which register has i_state ... I wonder if this could possibly be corruption of some sort?  It'd be nice to know what was found in i_state.   Modifying iput() to print it out prior to the bug_on might also help.  Just a thought.

Ok, I will try to patch something in and see what happens if I hit it
again.

> You seem uniquely able to hit it, and you do have a truckload of interesting modules loaded.  :)

Interesting modules?
alsa stuff, bluetooth, fuse/fat/vfat for ntfs/external drives, wlan
the only thing that stand out is the vbox* I guess, all the others are
pretty standard, aren't they?

Best wishes

Norbert
------------------------------------------------------------------------
Norbert Preining            preining@{jaist.ac.jp, logic.at, debian.org}
JAIST, Japan                                 TeX Live & Debian Developer
DSA: 0x09C5B094   fp: 14DF 2E6C 0307 BE6D AD76  A9C0 D2BF 4AA3 09C5 B094
------------------------------------------------------------------------
CONDOVER (n.)
One who is employed to stand about all day browsing through the
magazine racks in the newsagent.
			--- Douglas Adams, The Meaning of Liff

Re: another ext3 kernel BUG

[Dave Jones]

On Tue, Jun 14, 2011 at 11:43:07AM +0900, Norbert Preining wrote:
 
 > > You seem uniquely able to hit it, and you do have a truckload of interesting modules loaded.  :)
 > 
 > Interesting modules?
 > alsa stuff, bluetooth, fuse/fat/vfat for ntfs/external drives, wlan
 > the only thing that stand out is the vbox* I guess, all the others are
 > pretty standard, aren't they?

we've been getting a lot of bug reports in Fedora that have the vbox module
loaded that manifest in various ways that look like it's corrupting kernel data
structures. (Usually it scribbles over some linked list, and the CONFIG_LIST_DEBUG
stuff catches it).

I'd definitly try and reproduce it without that having been loaded if you can.

	Dave

Re: another ext3 kernel BUG

[Eric Sandeen]

On 6/13/11 10:21 PM, Dave Jones wrote:
> On Tue, Jun 14, 2011 at 11:43:07AM +0900, Norbert Preining wrote:
>  
>  > > You seem uniquely able to hit it, and you do have a truckload of interesting modules loaded.  :)
>  > 
>  > Interesting modules?
>  > alsa stuff, bluetooth, fuse/fat/vfat for ntfs/external drives, wlan
>  > the only thing that stand out is the vbox* I guess, all the others are
>  > pretty standard, aren't they?
> 
> we've been getting a lot of bug reports in Fedora that have the vbox module
> loaded that manifest in various ways that look like it's corrupting kernel data
> structures. (Usually it scribbles over some linked list, and the CONFIG_LIST_DEBUG
> stuff catches it).
> 
> I'd definitly try and reproduce it without that having been loaded if you can.
> 
> 	Dave

Yep, that's what I thought was "interesting" :)

-Eric

Re: another ext3 kernel BUG

[Norbert Preining]

Hi everyone,

On Mo, 13 Jun 2011, Dave Jones wrote:
> we've been getting a lot of bug reports in Fedora that have the vbox module
> loaded that manifest in various ways that look like it's corrupting kernel data
> structures. (Usually it scribbles over some linked list, and the CONFIG_LIST_DEBUG
> stuff catches it).

Ahh, ok. I thought that if the modules are loaded but NOT in use,
in the sense no vbox is running, then they cannot do much harm.
At the end it was always on heavy IO load without any vbox interaction.

But I will recompile with CONFIG_LIST_DEBUG and see what comes out.

Best wishes

Norbert
------------------------------------------------------------------------
Norbert Preining            preining@{jaist.ac.jp, logic.at, debian.org}
JAIST, Japan                                 TeX Live & Debian Developer
DSA: 0x09C5B094   fp: 14DF 2E6C 0307 BE6D AD76  A9C0 D2BF 4AA3 09C5 B094
------------------------------------------------------------------------
WHASSET (n.)
A business card in you wallet belonging to someone whom you have no
recollection of meeting.
			--- Douglas Adams, The Meaning of Liff

Re: another ext3 kernel BUG

[Norbert Preining]

Hi everyone,

here we go again.

On Di, 14 Jun 2011, Norbert Preining wrote:
> > we've been getting a lot of bug reports in Fedora that have the vbox module
> > loaded that manifest in various ways that look like it's corrupting kernel data
> > structures. (Usually it scribbles over some linked list, and the CONFIG_LIST_DEBUG
> > stuff catches it).
> 
> Ahh, ok. I thought that if the modules are loaded but NOT in use,
> in the sense no vbox is running, then they cannot do much harm.
> At the end it was always on heavy IO load without any vbox interaction.
> 
> But I will recompile with CONFIG_LIST_DEBUG and see what comes out.

CONFIG_LIST_DEBUG is compiled in.
vbox modules are *not* loaded
again BUG (there is a funny last line ...)
this is with rc3

Jun 15 10:12:16 mithrandir kernel: [24645.387482] ------------[ cut here ]------------
Jun 15 10:12:16 mithrandir kernel: [24645.387516] kernel BUG at fs/inode.c:1368!
Jun 15 10:12:16 mithrandir kernel: [24645.387539] invalid opcode: 0000 [#1] PREEMPT SMP 
Jun 15 10:12:16 mithrandir kernel: [24645.387572] CPU 0 
Jun 15 10:12:16 mithrandir kernel: [24645.387584] Modules linked in: rfcomm bnep snd_hrtimer binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss hso arc4 snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss iwlagn btusb snd_seq_midi bluetooth snd_rawmidi mac80211 crc16 snd_seq_midi_event snd_seq snd_timer snd_seq_device snd cfg80211 soundcore firewire_ohci firewire_core snd_page_alloc sony_laptop tpm_infineon rfkill crc_itu_t joydev mxm_wmi
Jun 15 10:12:16 mithrandir kernel: [24645.387925] 
Jun 15 10:12:16 mithrandir kernel: [24645.387936] Pid: 15175, comm: rm Not tainted 3.0.0-rc3+ #2 Sony Corporation VGN-Z11VN_B/VAIO
Jun 15 10:12:16 mithrandir kernel: [24645.387986] RIP: 0010:[<ffffffff810c43c5>]  [<ffffffff810c43c5>] iput+0x1c/0x13b
Jun 15 10:12:16 mithrandir kernel: [24645.388029] RSP: 0018:ffff88013a5abe48  EFLAGS: 00010202
Jun 15 10:12:16 mithrandir kernel: [24645.388057] RAX: 0000000000000000 RBX: ffff88010946ca50 RCX: 0000000000000036
Jun 15 10:12:16 mithrandir kernel: [24645.388093] RDX: 00000001005cde00 RSI: ffffffff81108be8 RDI: ffff88010946ca50
Jun 15 10:12:16 mithrandir kernel: [24645.388129] RBP: ffff88013a5abe68 R08: 0000000000000000 R09: ffff88012b8bca20
Jun 15 10:12:16 mithrandir kernel: [24645.388169] R10: ffff88012b8bca20 R11: ffff88013a4d9600 R12: ffff8800969349c0
Jun 15 10:12:16 mithrandir kernel: [24645.388203] R13: ffff88010946ca50 R14: ffff88011787c5f0 R15: ffff88009f122c10
Jun 15 10:12:16 mithrandir kernel: [24645.388237] FS:  00007f5fc4b74700(0000) GS:ffff88013fc00000(0000) knlGS:0000000000000000
Jun 15 10:12:16 mithrandir kernel: [24645.388276] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Jun 15 10:12:16 mithrandir kernel: [24645.388304] CR2: 000000000065b018 CR3: 0000000101e6d000 CR4: 00000000000006f0
Jun 15 10:12:16 mithrandir kernel: [24645.388338] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 15 10:12:16 mithrandir kernel: [24645.388373] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 15 10:12:16 mithrandir kernel: [24645.388407] Process rm (pid: 15175, threadinfo ffff88013a5aa000, task ffff880130d550e0)
Jun 15 10:12:16 mithrandir kernel: [24645.388444] Stack:
Jun 15 10:12:16 mithrandir kernel: [24645.388456]  0000000000000000 ffff88011787c540 ffff8800969349c0 ffff88010946ca50
Jun 15 10:12:16 mithrandir kernel: [24645.388501]  ffff88013a5abe98 ffffffff810c17cc ffff88011787c540 ffff88011787c59c
Jun 15 10:12:16 mithrandir kernel: [24645.388545]  ffff88010946ca50 ffff88011787c540 ffff88013a5abec8 ffffffff810c1ce1
Jun 15 10:12:16 mithrandir kernel: [24645.388589] Call Trace:
Jun 15 10:12:16 mithrandir kernel: [24645.388605]  [<ffffffff810c17cc>] dentry_kill+0x115/0x137
Jun 15 10:12:16 mithrandir kernel: [24645.388632]  [<ffffffff810c1ce1>] dput+0xbf/0xd0
Jun 15 10:12:16 mithrandir kernel: [24645.388658]  [<ffffffff810b2b99>] fput+0x18d/0x1a5
Jun 15 10:12:16 mithrandir kernel: [24645.388683]  [<ffffffff810afe04>] filp_close+0x69/0x75
Jun 15 10:12:16 mithrandir kernel: [24645.388710]  [<ffffffff810afeb8>] sys_close+0xa8/0xea
Jun 15 10:12:16 mithrandir kernel: [24645.388737]  [<ffffffff8139b53b>] system_call_fastpath+0x16/0x1b
Jun 15 10:12:16 mithrandir kernel: [24645.388767] Code: 1c 24 4c 39 e3 75 dc 58 5b 41 5c 41 5d 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 50 0f 84 1d 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 3d eb 08 00 85 c0 0f 
Jun 15 10:12:16 mithrandir kernel: [24645.389011] RIP  [<ffffffff810c43c5>] iput+0x1c/0x13b
Jun 15 10:12:16 mithrandir kernel: [24645.389040]  RSP <ffff88013a5abe48>
Jun 15 10:12:16 mithrandir kernel: [24645.410091] ---[ end trace 9353fc80151a84f4 ]---
Jun 15 10:12:16 mithrandir kernel: [24645.537171] ------------[ cut here ]------------
Jun 15 10:12:16 mithrandir kernel: [24645.537204] kernel BUG at fs/inode.c:1368!
Jun 15 10:12:16 mithrandir kernel: [24645.537227] invalid opcode: 0000 [#2] PREEMPT SMP 
Jun 15 10:12:16 mithrandir kernel: [24645.538996] CPU 1 
Jun 15 10:12:16 mithrandir kernel: [24645.539008] Modules linked in: rfcomm bnep snd_hrtimer binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss hso arc4 snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss iwlagn btusb snd_seq_midi bluetooth snd_rawmidi mac80211 crc16 snd_seq_midi_event snd_seq snd_timer snd_seq_device snd cfg80211 soundcore firewire_ohci firewire_core snd_page_alloc sony_laptop tpm_infineon rfkill crc_itu_t joydev mxm_wmi
Jun 15 10:12:16 mithrandir kernel: [24645.540007] 
Jun 15 10:12:16 mithrandir kernel: [24645.546893] Pid: 15178, comm: rm Tainted: G      D     3.0.0-rc3+ #2 Sony Corporation VGN-Z11VN_B/VAIO
Jun 15 10:12:16 mithrandir kernel: [24645.546893] RIP: 0010:[<ffffffff810c43c5>]  [<ffffffff810c43c5>] iput+0x1c/0x13b
Jun 15 10:12:16 mithrandir kernel: [24645.546893] RSP: 0018:ffff880130d7de48  EFLAGS: 00010202
Jun 15 10:12:16 mithrandir kernel: [24645.546893] RAX: 0000000000000000 RBX: ffff880094c12430 RCX: 0000000000000034
Jun 15 10:12:16 mithrandir kernel: [24645.546893] RDX: 00000001005cde00 RSI: ffffffff81108be8 RDI: ffff880094c12430
Jun 15 10:12:16 mithrandir kernel: [24645.546893] RBP: ffff880130d7de68 R08: 0000000000000000 R09: ffff88012b8bca20
Jun 15 10:12:16 mithrandir kernel: [24645.546893] R10: ffff88012b8bca20 R11: ffff88013a4d9600 R12: ffff88012b901480
Jun 15 10:12:16 mithrandir kernel: [24645.546893] R13: ffff880094c12430 R14: ffff88012cf62470 R15: ffff880130029550
Jun 15 10:12:16 mithrandir kernel: [24645.546893] FS:  00007f26d09bb700(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
Jun 15 10:12:16 mithrandir kernel: [24645.546893] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Jun 15 10:12:16 mithrandir kernel: [24645.546893] CR2: 0000000002112078 CR3: 000000009f3d6000 CR4: 00000000000006e0
Jun 15 10:12:16 mithrandir kernel: [24645.546893] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 15 10:12:16 mithrandir kernel: [24645.546893] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 15 10:12:16 mithrandir kernel: [24645.546893] Process rm (pid: 15178, threadinfo ffff880130d7c000, task ffff88009f24a140)
Jun 15 10:12:16 mithrandir kernel: [24645.546893] Stack:
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  0000000000000000 ffff88012cf623c0 ffff88012b901480 ffff880094c12430
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  ffff880130d7de98 ffffffff810c17cc ffff88012cf623c0 ffff88012cf6241c
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  ffff880094c12430 ffff88012cf623c0 ffff880130d7dec8 ffffffff810c1ce1
Jun 15 10:12:16 mithrandir kernel: [24645.546893] Call Trace:
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  [<ffffffff810c17cc>] dentry_kill+0x115/0x137
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  [<ffffffff810c1ce1>] dput+0xbf/0xd0
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  [<ffffffff810b2b99>] fput+0x18d/0x1a5
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  [<ffffffff810afe04>] filp_close+0x69/0x75
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  [<ffffffff810afeb8>] sys_close+0xa8/0xea
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  [<ffffffff8139b53b>] system_call_fastpath+0x16/0x1b
Jun 15 10:12:16 mithrandir kernel: [24645.546893] Code: 1c 24 4c 39 e3 75 dc 58 5b 41 5c 41 5d 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 50 0f 84 1d 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 3d eb 08 00 85 c0 0f 
Jun 15 10:12:16 mithrandir kernel: [24645.546893] RIP  [<ffffffff810c43c5>] iput+0x1c/0x13b
Jun 15 10:12:16 mithrandir kernel: [24645.546893]  RSP <ffff880130d7de48>
Jun 15 10:12:16 mithrandir kernel: [24645.600558] ---[ end trace 9353fc80151a84f5 ]---
Jun 15 10:12:41 mithrandir kernel: /0x2a

Best wishes

Norbert
------------------------------------------------------------------------
Norbert Preining            preining@{jaist.ac.jp, logic.at, debian.org}
JAIST, Japan                                 TeX Live & Debian Developer
DSA: 0x09C5B094   fp: 14DF 2E6C 0307 BE6D AD76  A9C0 D2BF 4AA3 09C5 B094
-----------------------------------------------------------------------

Re: another ext3 kernel BUG

[Török Edwin]

On 2011-06-15 04:23, Norbert Preining wrote:
> Hi everyone,
> 
> here we go again.
> 
> On Di, 14 Jun 2011, Norbert Preining wrote:
>>> we've been getting a lot of bug reports in Fedora that have the vbox module
>>> loaded that manifest in various ways that look like it's corrupting kernel data
>>> structures. (Usually it scribbles over some linked list, and the CONFIG_LIST_DEBUG
>>> stuff catches it).
>>
>> Ahh, ok. I thought that if the modules are loaded but NOT in use,
>> in the sense no vbox is running, then they cannot do much harm.
>> At the end it was always on heavy IO load without any vbox interaction.
>>
>> But I will recompile with CONFIG_LIST_DEBUG and see what comes out.
> 
> CONFIG_LIST_DEBUG is compiled in.
> vbox modules are *not* loaded
> again BUG (there is a funny last line ...)
> this is with rc3
> 
> Jun 15 10:12:16 mithrandir kernel: [24645.387482] ------------[ cut here ]------------
> Jun 15 10:12:16 mithrandir kernel: [24645.387516] kernel BUG at fs/inode.c:1368!

Hi,

I just started getting this error too today (I upgrade from 3.0-rc2 ->
3.0-rc3 yesterday).
I also get a warning at WARNING: at fs/fs-writeback.c:356
writeback_single_inode+0xad/0x240(), not just the BUG_ON.

I use ext4, xfs and tmpfs, these errors probably come from ext4 because
I was doing an apt-get install at a time and my root filesystem is ext4.

Apparently this reproduces with either of these, I'll probably try bisecting later today (3.0-rc2 was fine and I've run that for several days):
# apt-get install openjdk-6-jdk
# apt-get remove openjdk-6-jdk

FWIW the Xorg display gets corrupted too, I have to switch to a VT and back in order to get back my desktop (I use KMS).

Here is my mount list, note that /dev/mapper/vgssd-lvssd is a striped logical volume over 2 physical volumes that reside on SSD partitions:

/dev/mapper/vgssd-lvssd on / type ext4 (rw,noatime,nodiratime,discard,errors=remount-ro,commit=0)
tmpfs on /lib/init/rw type tmpfs (rw,nosuid,size=5242880,mode=755,size=5242880,mode=755)
tmpfs on /run type tmpfs (rw,noexec,nosuid,size=10%,mode=755,size=10%,mode=755)
tmpfs on /tmp type tmpfs (rw,noexec,nosuid,nodev,noatime,nodiratime,size=20%,mode=1777)
proc on /proc type proc (rw,noexec,nosuid,nodev)
sysfs on /sys type sysfs (rw,noexec,nosuid,nodev)
udev on /dev type tmpfs (rw,mode=0755)
tmpfs on /run/shm type tmpfs (rw,nosuid,nodev,size=20%,mode=1777,size=20%,mode=1777)
devpts on /dev/pts type devpts (rw,noexec,nosuid,gid=5,mode=620,gid=5,mode=620)
/dev/md0 on /boot type ext3 (rw,noexec,nosuid,nodev,noatime,commit=0)
none on /tmp type tmpfs (rw,noexec,nosuid,nodev,noatime,nodiratime)
/dev/md1p1 on /mnt/home_data type ext4 (rw,nodev,noatime,nodiratime)
/dev/md1p2 on /mnt/var_data type xfs (rw,noatime,nodiratime,logbufs=8,logbsize=256k,logdev=/dev/disk/by-id/scsi-SATA_WDC_WD740ADFD-0_WD-WMARF1007797-part5)
/dev/md1p3 on /mnt/samples type xfs (rw,noexec,nosuid,nodev,noatime,nodiratime,logbufs=8,logbsize=256k,logdev=/dev/disk/by-id/scsi-SATA_WDC_WD740ADFD-0_WD-WMARF1007797-part6)
/dev/md1p4 on /var/cache/rsnapshot type ext4 (rw,noexec,nosuid,noatime,nodiratime,commit=0)
/mnt/var_data/tmp on /var/tmp type none (rw,bind)
/mnt/var_data/cache_apt on /var/cache/apt type none (rw,bind)
/mnt/var_data/libvirt on /var/lib/libvirt type none (rw,bind)
/mnt/var_data/local on /var/local type none (rw,bind)
/mnt/var_data/log on /var/log type none (rw,bind)
/mnt/var_data/buildbot on /var/lib/buildbot type none (rw,bind)
/mnt/home_data on /home/edwin/HDD type none (rw,bind,commit=0)
/mnt/samples on /var/local/samples type none (rw,bind)
rpc_pipefs on /var/lib/nfs/rpc_pipefs type rpc_pipefs (rw)
fusectl on /sys/fs/fuse/connections type fusectl (rw)
/var/local/sid-ia32 on /var/lib/schroot/mount/sid-ia32-66022012-37fc-459c-af86-1c25d83a844d type none (rw,bind)
/proc on /var/lib/schroot/mount/sid-ia32-66022012-37fc-459c-af86-1c25d83a844d/proc type none (rw,bind)
/sys on /var/lib/schroot/mount/sid-ia32-66022012-37fc-459c-af86-1c25d83a844d/sys type none (rw,bind)
/dev on /var/lib/schroot/mount/sid-ia32-66022012-37fc-459c-af86-1c25d83a844d/dev type none (rw,bind)
/dev/pts on /var/lib/schroot/mount/sid-ia32-66022012-37fc-459c-af86-1c25d83a844d/dev/pts type none (rw,bind)
/home on /var/lib/schroot/mount/sid-ia32-66022012-37fc-459c-af86-1c25d83a844d/home type none (rw,bind,commit=0)
/tmp on /var/lib/schroot/mount/sid-ia32-66022012-37fc-459c-af86-1c25d83a844d/tmp type none (rw,bind)
/var/local/sid-ia32 on /var/lib/schroot/mount/sid-ia32-8815279a-04c8-4539-9354-e7a49c910490 type none (rw,bind)
/proc on /var/lib/schroot/mount/sid-ia32-8815279a-04c8-4539-9354-e7a49c910490/proc type none (rw,bind)
/sys on /var/lib/schroot/mount/sid-ia32-8815279a-04c8-4539-9354-e7a49c910490/sys type none (rw,bind)
/dev on /var/lib/schroot/mount/sid-ia32-8815279a-04c8-4539-9354-e7a49c910490/dev type none (rw,bind)
/dev/pts on /var/lib/schroot/mount/sid-ia32-8815279a-04c8-4539-9354-e7a49c910490/dev/pts type none (rw,bind)
/home on /var/lib/schroot/mount/sid-ia32-8815279a-04c8-4539-9354-e7a49c910490/home type none (rw,bind,commit=0)
/tmp on /var/lib/schroot/mount/sid-ia32-8815279a-04c8-4539-9354-e7a49c910490/tmp type none (rw,bind)
binfmt_misc on /proc/sys/fs/binfmt_misc type binfmt_misc (rw,noexec,nosuid,nodev)
nfsd on /proc/fs/nfsd type nfsd (rw)

And here is the error:
[ 2862.500029] ------------[ cut here ]------------
[ 2862.500126] kernel BUG at fs/inode.c:1368!
[ 2862.500199] invalid opcode: 0000 [#1] PREEMPT SMP
[ 2862.500295] CPU 0
[ 2862.500332] Modules linked in: xt_CHECKSUM iptable_mangle bridge stp llc powernow_k8 cpufreq_stats cpufreq_powersave cpufreq_ondemand rfcomm cpufreq_conservative bnep cpufreq_userspace binfmt_misc
fuse nfsd nfs lockd sunrpc ipv6 ipt_REJECT xt_state xt_tcpudp iptable_filter ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack nf_defrag_ipv4 ip_tables x_tables radeon ttm
drm_kms_helper drm i2c_algo_bit cfbcopyarea cfbimgblt cfbfillrect freq_table mperf it87 hwmon_vid loop firewire_sbp2 hid_a4tech snd_hda_codec_hdmi usbhid snd_hda_codec_realtek evdev snd_hda_intel
k10temp hwmon snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss btusb snd_pcm bluetooth i2c_piix4 snd_seq_oss snd_seq_midi_event snd_seq snd_timer snd_seq_device snd soundcore snd_page_alloc
parport_pc parport processor wmi button thermal_sys ohci_hcd sr_mod cdrom firewire_ohci firewire_core crc_itu_t ehci_hcd ssb xhci_hcd usbcore [last unloaded: scsi_wait_scan]
[ 2862.501007]
[ 2862.501007] Pid: 6595, comm: rm Not tainted 3.0.0-rc3 #131 Gigabyte Technology Co., Ltd. GA-870A-UD3/GA-870A-UD3
[ 2862.501007] RIP: 0010:[<ffffffff8116d1a6>]  [<ffffffff8116d1a6>] iput+0x176/0x1b0
[ 2862.501007] RSP: 0018:ffff880225a5de48  EFLAGS: 00010202
[ 2862.501007] RAX: 0000000000000000 RBX: ffff8800cb9ed6f0 RCX: 00000001002719c3
[ 2862.501007] RDX: ffff880225070cc0 RSI: ffffffff811d94f0 RDI: ffff8800cb9ed6f0
[ 2862.501007] RBP: ffff880225a5de68 R08: 00cb9ed7180c0000 R09: ffff8800cb9ed640
[ 2862.501007] R10: 0000000000000000 R11: 0000000000000000 R12: ffff8800cb9ed6f0
[ 2862.501007] R13: ffff88001238f6c0 R14: ffff880143f6af5c R15: ffff88022e993a00
[ 2862.501007] FS:  00007f4237387700(0000) GS:ffff88022fc00000(0000) knlGS:0000000000000000
[ 2862.501007] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[ 2862.501007] CR2: 00000000025210f8 CR3: 0000000225053000 CR4: 00000000000006f0
[ 2862.501007] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 2862.501007] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[ 2862.501007] Process rm (pid: 6595, threadinfo ffff880225a5c000, task ffff8802112fda40)
[ 2862.501007] Stack:
[ 2862.501007]  ffff880143f6af00 ffff8800cb9ed6f0 ffff88001238f6c0 ffff880143f6af5c
[ 2862.501007]  ffff880225a5de98 ffffffff811691d8 ffff880225a5de98 ffff880143f6af00
[ 2862.501007]  ffff8800cb9ed6f0 ffff88001238f6c0 ffff880225a5dec8 ffffffff81169c19
[ 2862.501007] Call Trace:
[ 2862.501007]  [<ffffffff811691d8>] d_kill+0xf8/0x140
[ 2862.501007]  [<ffffffff81169c19>] dput+0xc9/0x190
[ 2862.501007]  [<ffffffff8115577f>] fput+0x15f/0x210
[ 2862.501007]  [<ffffffff81152171>] filp_close+0x61/0x90
[ 2862.501007]  [<ffffffff81152251>] sys_close+0xb1/0x110
[ 2862.501007]  [<ffffffff814c14fb>] system_call_fastpath+0x16/0x1b
[ 2862.501007] Code: 8b 15 1f 23 8f 00 ff 05 3d 4b a5 00 48 89 05 12 23 8f 00 48 89 42 08 48 89 93 80 00 00 00 48 c7 83 88 00 00 00 a0 f4 a5 81 eb c6 <0f> 0b be 2d 05 00 00 48 c7 c7 fa 09 79 81 e8 17
3c f1 ff e9 dc
[ 2862.501007] RIP  [<ffffffff8116d1a6>] iput+0x176/0x1b0
[ 2862.501007]  RSP <ffff880225a5de48>
[ 2862.541441] ---[ end trace 3fc903d2307ef2ff ]---
[ 2889.624372] ------------[ cut here ]------------
[ 2889.624391] WARNING: at fs/fs-writeback.c:356 writeback_single_inode+0xad/0x240()
[ 2889.624397] Hardware name: GA-870A-UD3
[ 2889.624401] Modules linked in: xt_CHECKSUM iptable_mangle bridge stp llc powernow_k8 cpufreq_stats cpufreq_powersave cpufreq_ondemand rfcomm cpufreq_conservative bnep cpufreq_userspace binfmt_misc
fuse nfsd nfs lockd sunrpc ipv6 ipt_REJECT xt_state xt_tcpudp iptable_filter ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack nf_defrag_ipv4 ip_tables x_tables radeon ttm
drm_kms_helper drm i2c_algo_bit cfbcopyarea cfbimgblt cfbfillrect freq_table mperf it87 hwmon_vid loop firewire_sbp2 hid_a4tech snd_hda_codec_hdmi usbhid snd_hda_codec_realtek evdev snd_hda_intel
k10temp hwmon snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss btusb snd_pcm bluetooth i2c_piix4 snd_seq_oss snd_seq_midi_event snd_seq snd_timer snd_seq_device snd soundcore snd_page_alloc
parport_pc parport processor wmi button thermal_sys ohci_hcd sr_mod cdrom firewire_ohci firewire_core crc_itu_t ehci_hcd ssb xhci_hcd usbcore [last unloaded: scsi_wait_scan]
[ 2889.624546] Pid: 3606, comm: flush-253:0 Tainted: G      D     3.0.0-rc3 #131
[ 2889.624552] Call Trace:
[ 2889.624566]  [<ffffffff81080d9a>] warn_slowpath_common+0x7a/0xb0
[ 2889.624576]  [<ffffffff81080de5>] warn_slowpath_null+0x15/0x20
[ 2889.624584]  [<ffffffff81178a6d>] writeback_single_inode+0xad/0x240
[ 2889.624592]  [<ffffffff81178e6b>] writeback_sb_inodes+0xdb/0x1b0
[ 2889.624599]  [<ffffffff811799ab>] writeback_inodes_wb+0x7b/0x150
[ 2889.624607]  [<ffffffff81179f4b>] wb_writeback+0x4cb/0x520
[ 2889.624615]  [<ffffffff8117a068>] wb_check_old_data_flush+0xc8/0xd0
[ 2889.624623]  [<ffffffff8117a1fd>] wb_do_writeback+0x18d/0x270
[ 2889.624631]  [<ffffffff81090ad2>] ? del_timer_sync+0x32/0x50
[ 2889.624640]  [<ffffffff8108f510>] ? init_timer_deferrable_key+0x20/0x20
[ 2889.624648]  [<ffffffff8117a364>] bdi_writeback_thread+0x84/0x300
[ 2889.624656]  [<ffffffff8117a2e0>] ? wb_do_writeback+0x270/0x270
[ 2889.624664]  [<ffffffff810a1247>] kthread+0x87/0x90
[ 2889.624672]  [<ffffffff814c22d4>] kernel_thread_helper+0x4/0x10
[ 2889.624680]  [<ffffffff810a11c0>] ? kthread_worker_fn+0x190/0x190
[ 2889.624687]  [<ffffffff814c22d0>] ? gs_change+0xb/0xb
[ 2889.624692] ---[ end trace 3fc903d2307ef300 ]---
[ 2889.624698] ------------[ cut here ]------------
[ 2889.624706] WARNING: at fs/fs-writeback.c:356 writeback_single_inode+0xad/0x240()
[ 2889.624711] Hardware name: GA-870A-UD3
[ 2889.624714] Modules linked in: xt_CHECKSUM iptable_mangle bridge stp llc powernow_k8 cpufreq_stats cpufreq_powersave cpufreq_ondemand rfcomm cpufreq_conservative bnep cpufreq_userspace binfmt_misc
fuse nfsd nfs lockd sunrpc ipv6 ipt_REJECT xt_state xt_tcpudp iptable_filter ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack nf_defrag_ipv4 ip_tables x_tables radeon ttm
drm_kms_helper drm i2c_algo_bit cfbcopyarea cfbimgblt cfbfillrect freq_table mperf it87 hwmon_vid loop firewire_sbp2 hid_a4tech snd_hda_codec_hdmi usbhid snd_hda_codec_realtek evdev snd_hda_intel
k10temp hwmon snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss btusb snd_pcm bluetooth i2c_piix4 snd_seq_oss snd_seq_midi_event snd_seq snd_timer snd_seq_device snd soundcore snd_page_alloc
parport_pc parport processor wmi button thermal_sys ohci_hcd sr_mod cdrom firewire_ohci firewire_core crc_itu_t ehci_hcd ssb xhci_hcd usbcore [last unloaded: scsi_wait_scan]
[ 2889.624833] Pid: 3606, comm: flush-253:0 Tainted: G      D W   3.0.0-rc3 #131
[ 2889.624838] Call Trace:
[ 2889.624846]  [<ffffffff81080d9a>] warn_slowpath_common+0x7a/0xb0
[ 2889.624855]  [<ffffffff81080de5>] warn_slowpath_null+0x15/0x20
[ 2889.624862]  [<ffffffff81178a6d>] writeback_single_inode+0xad/0x240
[ 2889.624869]  [<ffffffff81178e6b>] writeback_sb_inodes+0xdb/0x1b0
[ 2889.624877]  [<ffffffff811799ab>] writeback_inodes_wb+0x7b/0x150
[ 2889.624885]  [<ffffffff81179f4b>] wb_writeback+0x4cb/0x520
[ 2889.624893]  [<ffffffff8117a068>] wb_check_old_data_flush+0xc8/0xd0
[ 2889.624901]  [<ffffffff8117a1fd>] wb_do_writeback+0x18d/0x270
[ 2889.624908]  [<ffffffff81090ad2>] ? del_timer_sync+0x32/0x50
[ 2889.624917]  [<ffffffff8108f510>] ? init_timer_deferrable_key+0x20/0x20
[ 2889.624925]  [<ffffffff8117a364>] bdi_writeback_thread+0x84/0x300
[ 2889.624932]  [<ffffffff8117a2e0>] ? wb_do_writeback+0x270/0x270
[ 2889.624940]  [<ffffffff810a1247>] kthread+0x87/0x90
[ 2889.624947]  [<ffffffff814c22d4>] kernel_thread_helper+0x4/0x10
[ 2889.624955]  [<ffffffff810a11c0>] ? kthread_worker_fn+0x190/0x190
[ 2889.624962]  [<ffffffff814c22d0>] ? gs_change+0xb/0xb
[ 2889.624967] ---[ end trace 3fc903d2307ef301 ]---

Best regards,
--Edwin

[PATCH] fix wrong iput on d_inode introduced by e6bc45d65d

[Török Edwin]

Git bisection shows that commit e6bc45d65df8599fdbae73be9cec4ceed274db53 causes
BUG_ONs under high I/O load:

kernel BUG at fs/inode.c:1368!
[ 2862.501007] Call Trace:
[ 2862.501007]  [<ffffffff811691d8>] d_kill+0xf8/0x140
[ 2862.501007]  [<ffffffff81169c19>] dput+0xc9/0x190
[ 2862.501007]  [<ffffffff8115577f>] fput+0x15f/0x210
[ 2862.501007]  [<ffffffff81152171>] filp_close+0x61/0x90
[ 2862.501007]  [<ffffffff81152251>] sys_close+0xb1/0x110
[ 2862.501007]  [<ffffffff814c14fb>] system_call_fastpath+0x16/0x1b

A reliable way to reproduce this bug is:
Login to KDE, run 'rsnapshot sync', and apt-get install openjdk-6-jdk,
and apt-get remove openjdk-6-jdk.

The buggy part of the patch is this:
	struct inode *inode = NULL;
.....
-               if (nd.last.name[nd.last.len])
-                       goto slashes;
                inode = dentry->d_inode;
-               if (inode)
-                       ihold(inode);
+               if (nd.last.name[nd.last.len] || !inode)
+                       goto slashes;
+               ihold(inode)
...
	if (inode)
		iput(inode);	/* truncate the inode here */

If nd.last.name[nd.last.len] is nonzero (and thus goto slashes branch is taken),
and dentry->d_inode is non-NULL, then this code now does an additional iput on
the inode, which is wrong.

Fix this by only setting the inode variable if nd.last.name[nd.last.len] is 0.

Reference: https://lkml.org/lkml/2011/6/15/50
Reported-by: Norbert Preining <preining@logic.at>
Reported-by: Török Edwin <edwintorok@gmail.com>
Cc: "Theodore Ts'o" <tytso@mit.edu>
Cc: Al Viro <viro@zeniv.linux.org.uk>
Signed-off-by: Török Edwin <edwintorok@gmail.com>
---
 fs/namei.c |    4 +++-
 1 files changed, 3 insertions(+), 1 deletions(-)

diff --git a/fs/namei.c b/fs/namei.c
index 9802345..6301963 100644
--- a/fs/namei.c
+++ b/fs/namei.c
@@ -2713,8 +2713,10 @@ static long do_unlinkat(int dfd, const char __user *pathname)
 	error = PTR_ERR(dentry);
 	if (!IS_ERR(dentry)) {
 		/* Why not before? Because we want correct error value */
+		if (nd.last.name[nd.last.len])
+			goto slashes;
 		inode = dentry->d_inode;
-		if (nd.last.name[nd.last.len] || !inode)
+		if (!inode)
 			goto slashes;
 		ihold(inode);
 		error = mnt_want_write(nd.path.mnt);
-- 
1.7.5.4

--
To unsubscribe from this list: send the line "unsubscribe linux-ext4" in
the body of a message to majordomo@vger.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

Re: another ext3 kernel BUG

[Norbert Preining]

On Mi, 15 Jun 2011, Norbert Preining wrote:
> CONFIG_LIST_DEBUG is compiled in.
> vbox modules are *not* loaded

new instance, this time again in fs/inode.c, but coming from fsnotify,
and one from ntfs3g.

When I go to 3.0-rc1 all is fine.

I will try to get a git bisect running, but that is a pain because
it is not easy to trigger.

Any suggestion what could be the culprit? 3cebde241 ?


Jun 16 14:30:21 mithrandir kernel: [19059.592663] ------------[ cut here ]------------
Jun 16 14:30:21 mithrandir kernel: [19059.592702] kernel BUG at fs/inode.c:1368!
Jun 16 14:30:21 mithrandir kernel: [19059.592732] invalid opcode: 0000 [#1] PREEMPT SMP 
Jun 16 14:30:21 mithrandir kernel: [19059.592771] CPU 1 
Jun 16 14:30:21 mithrandir kernel: [19059.592784] Modules linked in: usb_storage rfcomm bnep snd_hrtimer binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput hso arc4 snd_hda_codec_realtek btusb snd_hda_intel bluetooth snd_hda_codec iwlagn snd_hwdep snd_pcm_oss crc16 mac80211 snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi cfg80211 snd_seq_midi_event snd_seq snd_timer snd_seq_device snd firewire_ohci sony_laptop soundcore firewire_core snd_page_alloc crc_itu_t tpm_infineon joydev rfkill mxm_wmi
Jun 16 14:30:21 mithrandir kernel: [19059.593141] 
Jun 16 14:30:21 mithrandir kernel: [19059.593152] Pid: 544, comm: kswapd0 Not tainted 3.0.0-rc3+ #2 Sony Corporation VGN-Z11VN_B/VAIO
Jun 16 14:30:21 mithrandir kernel: [19059.593203] RIP: 0010:[<ffffffff810c43c5>]  [<ffffffff810c43c5>] iput+0x1c/0x13b
Jun 16 14:30:21 mithrandir kernel: [19059.593248] RSP: 0018:ffff88013b025b40  EFLAGS: 00010202
Jun 16 14:30:21 mithrandir kernel: [19059.593277] RAX: ffff88012a7e3bc0 RBX: ffff880120f68430 RCX: 0000000000000017
Jun 16 14:30:21 mithrandir kernel: [19059.593314] RDX: 0000000000000000 RSI: ffff880100000050 RDI: ffff880120f68430
Jun 16 14:30:21 mithrandir kernel: [19059.593350] RBP: ffff88013b025b60 R08: 0000000000000000 R09: 0000000000000017
Jun 16 14:30:21 mithrandir kernel: [19059.593387] R10: ffff88012801a780 R11: 0000000000013858 R12: ffff8801275960c0
Jun 16 14:30:21 mithrandir kernel: [19059.593424] R13: ffff880120f68430 R14: ffff8800bb4940c0 R15: ffff8800bb4940b0
Jun 16 14:30:21 mithrandir kernel: [19059.593460] FS:  0000000000000000(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
Jun 16 14:30:21 mithrandir kernel: [19059.593503] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jun 16 14:30:21 mithrandir kernel: [19059.593533] CR2: 00000000023deb81 CR3: 0000000119cfb000 CR4: 00000000000006e0
Jun 16 14:30:21 mithrandir kernel: [19059.593570] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 16 14:30:21 mithrandir kernel: [19059.593607] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 16 14:30:21 mithrandir kernel: [19059.593644] Process kswapd0 (pid: 544, threadinfo ffff88013b024000, task ffff88013f6386b0)
Jun 16 14:30:21 mithrandir kernel: [19059.593686] Stack:
Jun 16 14:30:21 mithrandir kernel: [19059.593699]  ffff88012a7e3bc0 ffff8800bb4940a0 ffff8801275960c0 ffff880120f68430
Jun 16 14:30:21 mithrandir kernel: [19059.593747]  ffff88013b025bb0 ffffffff810db540 ffff88013b025bc0 ffff880127596124
Jun 16 14:30:21 mithrandir kernel: [19059.593794]  ffff88013b025bb0 ffff8800bb4940a0 ffff88013b025b80 ffff88013b025bc0
Jun 16 14:30:21 mithrandir kernel: [19059.593841] Call Trace:
Jun 16 14:30:21 mithrandir kernel: [19059.593859]  [<ffffffff810db540>] fsnotify_destroy_mark+0x115/0x139
Jun 16 14:30:21 mithrandir kernel: [19059.593893]  [<ffffffff810daf23>] fsnotify_clear_marks_by_inode+0xa3/0xcf
Jun 16 14:30:21 mithrandir kernel: [19059.593928]  [<ffffffff810da0a1>] __fsnotify_inode_delete+0x9/0xb
Jun 16 14:30:21 mithrandir kernel: [19059.593961]  [<ffffffff810c4160>] __destroy_inode+0x1d/0x55
Jun 16 14:30:21 mithrandir kernel: [19059.593989]  [<ffffffff810c41b3>] destroy_inode+0x1b/0x4b
Jun 16 14:30:21 mithrandir kernel: [19059.594018]  [<ffffffff810c4365>] evict+0x110/0x119
Jun 16 14:30:21 mithrandir kernel: [19059.594044]  [<ffffffff810c4398>] dispose_list+0x2a/0x3b
Jun 16 14:30:21 mithrandir kernel: [19059.594072]  [<ffffffff810c47a0>] shrink_icache_memory+0x242/0x272
Jun 16 14:30:21 mithrandir kernel: [19059.594106]  [<ffffffff8108b8bb>] shrink_slab+0xed/0x15e
Jun 16 14:30:21 mithrandir kernel: [19059.594135]  [<ffffffff8108d3f0>] balance_pgdat+0x279/0x4ff
Jun 16 14:30:21 mithrandir kernel: [19059.594165]  [<ffffffff8108d874>] kswapd+0x1fe/0x217
Jun 16 14:30:21 mithrandir kernel: [19059.594194]  [<ffffffff81047faa>] ? remove_wait_queue+0x35/0x35
Jun 16 14:30:21 mithrandir kernel: [19059.594225]  [<ffffffff8108d676>] ? balance_pgdat+0x4ff/0x4ff
Jun 16 14:30:21 mithrandir kernel: [19059.594255]  [<ffffffff810479ce>] kthread+0x7f/0x87
Jun 16 14:30:21 mithrandir kernel: [19059.594283]  [<ffffffff8139c254>] kernel_thread_helper+0x4/0x10
Jun 16 14:30:21 mithrandir kernel: [19059.594314]  [<ffffffff8104794f>] ? kthread_worker_fn+0x143/0x143
Jun 16 14:30:21 mithrandir kernel: [19059.594346]  [<ffffffff8139c250>] ? gs_change+0xb/0xb
Jun 16 14:30:21 mithrandir kernel: [19059.594372] Code: 1c 24 4c 39 e3 75 dc 58 5b 41 5c 41 5d 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 50 0f 84 1d 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 3d eb 08 00 85 c0 0f 
Jun 16 14:30:21 mithrandir kernel: [19059.594621] RIP  [<ffffffff810c43c5>] iput+0x1c/0x13b
Jun 16 14:30:21 mithrandir kernel: [19059.594652]  RSP <ffff88013b025b40>
Jun 16 14:30:21 mithrandir kernel: [19059.624619] ---[ end trace abed2640ce3aad65 ]---
Jun 16 14:30:24 mithrandir kernel: [19062.204833] ------------[ cut here ]------------
Jun 16 14:30:24 mithrandir kernel: [19062.206546] kernel BUG at fs/inode.c:1368!
Jun 16 14:30:24 mithrandir kernel: [19062.208004] invalid opcode: 0000 [#2] PREEMPT SMP 
Jun 16 14:30:24 mithrandir kernel: [19062.208004] CPU 1 
Jun 16 14:30:24 mithrandir kernel: [19062.208004] Modules linked in: usb_storage rfcomm bnep snd_hrtimer binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput hso arc4 snd_hda_codec_realtek btusb snd_hda_intel bluetooth snd_hda_codec iwlagn snd_hwdep snd_pcm_oss crc16 mac80211 snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi cfg80211 snd_seq_midi_event snd_seq snd_timer snd_seq_device snd firewire_ohci sony_laptop soundcore firewire_core snd_page_alloc crc_itu_t tpm_infineon joydev rfkill mxm_wmi
Jun 16 14:30:24 mithrandir kernel: [19062.208004] 
Jun 16 14:30:24 mithrandir kernel: [19062.208004] Pid: 1731, comm: mount.ntfs-3g Tainted: G      D     3.0.0-rc3+ #2 Sony Corporation VGN-Z11VN_B/VAIO
Jun 16 14:30:24 mithrandir kernel: [19062.208004] RIP: 0010:[<ffffffff810c43c5>]  [<ffffffff810c43c5>] iput+0x1c/0x13b
Jun 16 14:30:24 mithrandir kernel: [19062.208004] RSP: 0018:ffff880128a87768  EFLAGS: 00010202
Jun 16 14:30:24 mithrandir kernel: [19062.208004] RAX: ffff88012a7e3bc0 RBX: ffff880120f64d60 RCX: 00000000ffffffff
Jun 16 14:30:24 mithrandir kernel: [19062.208004] RDX: ffff8801340dab00 RSI: ffff880100000050 RDI: ffff880120f64d60
Jun 16 14:30:24 mithrandir kernel: [19062.208004] RBP: ffff880128a87788 R08: 0000000000000000 R09: 0000000000000016
Jun 16 14:30:24 mithrandir kernel: [19062.208004] R10: ffff88012801a780 R11: ffff880120f64d60 R12: ffff8801275960c0
Jun 16 14:30:24 mithrandir kernel: [19062.208004] R13: ffff880120f64d60 R14: ffff8800bb494f18 R15: ffff8800bb494f08
Jun 16 14:30:24 mithrandir kernel: [19062.208004] FS:  00007f11d3b69700(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
Jun 16 14:30:24 mithrandir kernel: [19062.208004] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Jun 16 14:30:24 mithrandir kernel: [19062.208004] CR2: 00007f928f242000 CR3: 0000000128af1000 CR4: 00000000000006e0
Jun 16 14:30:24 mithrandir kernel: [19062.208004] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 16 14:30:24 mithrandir kernel: [19062.208004] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 16 14:30:24 mithrandir kernel: [19062.208004] Process mount.ntfs-3g (pid: 1731, threadinfo ffff880128a86000, task ffff88012a953610)
Jun 16 14:30:24 mithrandir kernel: [19062.208004] Stack:
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  ffff88012a7e3bc0 ffff8800bb494ef8 ffff8801275960c0 ffff880120f64d60
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  ffff880128a877d8 ffffffff810db540 ffff880128a877e8 ffff880127596124
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  ffff880128a877d8 ffff8800bb494ef8 ffff880128a877a8 ffff880128a877e8
Jun 16 14:30:24 mithrandir kernel: [19062.208004] Call Trace:
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810db540>] fsnotify_destroy_mark+0x115/0x139
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810daf23>] fsnotify_clear_marks_by_inode+0xa3/0xcf
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810da0a1>] __fsnotify_inode_delete+0x9/0xb
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810c4160>] __destroy_inode+0x1d/0x55
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810c41b3>] destroy_inode+0x1b/0x4b
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810c4365>] evict+0x110/0x119
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810c4398>] dispose_list+0x2a/0x3b
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810c47a0>] shrink_icache_memory+0x242/0x272
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff8108b8bb>] shrink_slab+0xed/0x15e
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff8108d9e1>] do_try_to_free_pages+0x154/0x294
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff8108dc3b>] try_to_free_pages+0x71/0x73
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff81087b22>] __alloc_pages_nodemask+0x449/0x64f
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810892aa>] __do_page_cache_readahead+0xd6/0x1d4
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff81020e80>] ? get_user_pages_fast+0xc0/0x15c
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810895de>] ra_submit+0x1c/0x20
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810897cb>] ondemand_readahead+0x1e9/0x1f8
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff81081aa1>] ? unlock_page+0x23/0x28
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff8108984e>] page_cache_async_readahead+0x74/0x7d
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff81081969>] ? find_get_page+0x5e/0x6a
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff8108307d>] generic_file_aio_read+0x2d5/0x640
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810b1298>] do_sync_read+0xba/0xfa
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810b102c>] ? fsnotify_access+0x5a/0x62
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810b18a4>] vfs_read+0xa4/0xeb
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810b286f>] ? fget_light+0x3b/0x9a
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff810b1a12>] sys_pread64+0x55/0x71
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  [<ffffffff8139b53b>] system_call_fastpath+0x16/0x1b
Jun 16 14:30:24 mithrandir kernel: [19062.208004] Code: 1c 24 4c 39 e3 75 dc 58 5b 41 5c 41 5d 5d c3 55 48 85 ff 48 89 e5 41 55 41 54 53 48 89 fb 50 0f 84 1d 01 00 00 f6 47 28 40 74 02 <0f> 0b 48 8d 77 20 48 8d bf b0 00 00 00 e8 3d eb 08 00 85 c0 0f 
Jun 16 14:30:24 mithrandir kernel: [19062.208004] RIP  [<ffffffff810c43c5>] iput+0x1c/0x13b
Jun 16 14:30:24 mithrandir kernel: [19062.208004]  RSP <ffff880128a87768>
Jun 16 14:30:24 mithrandir kernel: [19062.299782] ---[ end trace abed2640ce3aad66 ]---


Best wishes

Norbert
------------------------------------------------------------------------
Norbert Preining            preining@{jaist.ac.jp, logic.at, debian.org}
JAIST, Japan                                 TeX Live & Debian Developer
DSA: 0x09C5B094   fp: 14DF 2E6C 0307 BE6D AD76  A9C0 D2BF 4AA3 09C5 B094
------------------------------------------------------------------------
HUMBY (n.)
An erection which won't go down when a gentleman has to go for a pee
in the middle of making love to someone.
			--- Douglas Adams, The Meaning of Liff

Re: another ext3 kernel BUG

[Török Edwin]

On 06/16/2011 08:51 AM, Norbert Preining wrote:
> On Mi, 15 Jun 2011, Norbert Preining wrote:
>> CONFIG_LIST_DEBUG is compiled in.
>> vbox modules are *not* loaded
> 
> new instance, this time again in fs/inode.c, but coming from fsnotify,
> and one from ntfs3g.
> 
> When I go to 3.0-rc1 all is fine.
> 
> I will try to get a git bisect running, but that is a pain because
> it is not easy to trigger.
> 
> Any suggestion what could be the culprit? 3cebde241 ?

e6bc45d65df8599fdbae73be9cec4ceed274db53

See here for a patch:
https://lkml.org/lkml/2011/6/15/341

Does that help?

Best regards,
--Edwin

Re: another ext3 kernel BUG

[Norbert Preining]

On Do, 16 Jun 2011, Török Edwin wrote:
> e6bc45d65df8599fdbae73be9cec4ceed274db53
> 
> See here for a patch:
> https://lkml.org/lkml/2011/6/15/341
> 
> Does that help?

Well, at least that is gone, but I am not sure. I am hit by
yet another bug (probably crappy hardware?): 

Thee external USB disk disconnects suddenly while saving big stuff
onto it (unison sync). Then the kernel hangs (that is something
that should NOT happen!), here is the log.

It is easy to see:-
- external disk plugged
- for some time working
- suddenly disconnected
- kernel goes into "I hang myself" mode


Jun 16 17:13:44 mithrandir kernel: [  171.640096] usb 2-3: new high speed USB device number 3 using ehci_hcd
Jun 16 17:13:44 mithrandir kernel: [  171.773558] usb 2-3: New USB device found, idVendor=0411, idProduct=0108
Jun 16 17:13:44 mithrandir kernel: [  171.773568] usb 2-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jun 16 17:13:44 mithrandir kernel: [  171.773575] usb 2-3: Product: USB-SATA Bridge
Jun 16 17:13:44 mithrandir kernel: [  171.773580] usb 2-3: Manufacturer: BUFFALO
Jun 16 17:13:44 mithrandir kernel: [  171.773586] usb 2-3: SerialNumber: 00000002022EAA7
Jun 16 17:13:44 mithrandir kernel: [  172.071498] Initializing USB Mass Storage driver...
Jun 16 17:13:44 mithrandir kernel: [  172.071707] scsi6 : usb-storage 2-3:1.0
Jun 16 17:13:44 mithrandir kernel: [  172.071827] usbcore: registered new interface driver usb-storage
Jun 16 17:13:44 mithrandir kernel: [  172.071829] USB Mass Storage support registered.
Jun 16 17:13:45 mithrandir kernel: [  173.068809] scsi 6:0:0:0: Direct-Access     BUFFALO  External HDD     0000 PQ: 0 ANSI: 3
Jun 16 17:13:45 mithrandir kernel: [  173.069248] sd 6:0:0:0: Attached scsi generic sg2 type 0
Jun 16 17:13:53 mithrandir kernel: [  180.691341] sd 6:0:0:0: [sdb] 2930277168 512-byte logical blocks: (1.50 TB/1.36 TiB)
Jun 16 17:13:53 mithrandir kernel: [  180.692296] sd 6:0:0:0: [sdb] Write Protect is off
Jun 16 17:13:53 mithrandir kernel: [  180.692306] sd 6:0:0:0: [sdb] Mode Sense: 3b 00 00 00
Jun 16 17:13:53 mithrandir kernel: [  180.693183] sd 6:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Jun 16 17:13:53 mithrandir kernel: [  180.716119]  sdb: sdb1
Jun 16 17:13:53 mithrandir kernel: [  180.718780] sd 6:0:0:0: [sdb] Attached SCSI disk
Jun 16 17:13:53 mithrandir ata_id[4035]: HDIO_GET_IDENTITY failed for '/dev/.tmp-block-8:16'
Jun 16 17:13:53 mithrandir kernel: [  181.011546] device fsid 70f41f7e-6f28-416e-a263-8c214138822a devid 1 transid 5690 /dev/sdb1
Jun 16 17:15:47 mithrandir kernel: [  295.086699] usb 2-3: USB disconnect, device number 3
Jun 16 17:15:47 mithrandir kernel: [  295.090546] sd 6:0:0:0: [sdb] Unhandled error code
Jun 16 17:15:47 mithrandir kernel: [  295.090550] sd 6:0:0:0: [sdb]  Result: hostbyte=0x01 driverbyte=0x00
Jun 16 17:15:47 mithrandir kernel: [  295.090553] sd 6:0:0:0: [sdb] CDB: cdb[0]=0x28: 28 00 7d c5 eb 0f 00 00 f0 00
Jun 16 17:15:47 mithrandir kernel: [  295.090561] end_request: I/O error, dev sdb, sector 2110122767
Jun 16 17:15:47 mithrandir kernel: [  295.090644] sd 6:0:0:0: [sdb] Unhandled error code
Jun 16 17:15:47 mithrandir kernel: [  295.090646] sd 6:0:0:0: [sdb]  Result: hostbyte=0x01 driverbyte=0x00
Jun 16 17:15:47 mithrandir kernel: [  295.090649] sd 6:0:0:0: [sdb] CDB: cdb[0]=0x28: 28 00 7d c5 eb ff 00 00 f0 00
Jun 16 17:15:47 mithrandir kernel: [  295.090656] end_request: I/O error, dev sdb, sector 2110123007
Jun 16 17:15:47 mithrandir kernel: [  295.092072] btrfs no csum found for inode 321896 start 164225024

32 of those btrfs no csum found ... messages

Jun 16 17:15:47 mithrandir kernel: [  295.096114] sd 6:0:0:0: [sdb] Synchronizing SCSI cache
Jun 16 17:15:47 mithrandir kernel: [  295.096146] sd 6:0:0:0: [sdb]  Result: hostbyte=0x01 driverbyte=0x00
Jun 16 17:15:47 mithrandir kernel: [  295.096336] scsi: killing requests for dead queue
Jun 16 17:15:47 mithrandir kernel: [  295.096341] btrfs no csum found for inode 321896 start 164360192

about 1000 more of these btrfs no csum found ... messages

Jun 16 17:15:47 mithrandir kernel: [  295.336045] usb 2-3: new high speed USB device number 4 using ehci_hcd
Jun 16 17:15:47 mithrandir mtp-probe: checking bus 2, device 4: "/sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3"
Jun 16 17:15:47 mithrandir kernel: [  295.469291] usb 2-3: New USB device found, idVendor=0411, idProduct=0108
Jun 16 17:15:47 mithrandir kernel: [  295.469294] usb 2-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jun 16 17:15:47 mithrandir kernel: [  295.469297] usb 2-3: Product: USB-SATA Bridge
Jun 16 17:15:47 mithrandir kernel: [  295.469298] usb 2-3: Manufacturer: BUFFALO
Jun 16 17:15:47 mithrandir kernel: [  295.469300] usb 2-3: SerialNumber: 00000002022EAA7
Jun 16 17:15:47 mithrandir kernel: [  295.469642] scsi7 : usb-storage 2-3:1.0
Jun 16 17:15:48 mithrandir mtp-probe: bus: 2, device: 4 was not an MTP device
Jun 16 17:15:48 mithrandir kernel: [  296.468835] scsi 7:0:0:0: Direct-Access     BUFFALO  External HDD     0000 PQ: 0 ANSI: 3
Jun 16 17:15:48 mithrandir kernel: [  296.469273] sd 7:0:0:0: Attached scsi generic sg2 type 0
Jun 16 17:15:48 mithrandir kernel: [  296.470286] sd 7:0:0:0: [sdc] 2930277168 512-byte logical blocks: (1.50 TB/1.36 TiB)
Jun 16 17:15:48 mithrandir kernel: [  296.471147] sd 7:0:0:0: [sdc] Write Protect is off
Jun 16 17:15:48 mithrandir kernel: [  296.471157] sd 7:0:0:0: [sdc] Mode Sense: 3b 00 00 00
Jun 16 17:15:48 mithrandir kernel: [  296.472002] sd 7:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Jun 16 17:15:48 mithrandir kernel: [  296.480261]  sdc: sdc1
Jun 16 17:15:48 mithrandir kernel: [  296.483125] sd 7:0:0:0: [sdc] Attached SCSI disk
Jun 16 17:15:48 mithrandir ata_id[4160]: HDIO_GET_IDENTITY failed for '/dev/.tmp-block-8:32'
Jun 16 17:15:49 mithrandir kernel: [  296.601221] lost page write due to I/O error on sdb1
Jun 16 17:15:49 mithrandir kernel: [  296.601245] lost page write due to I/O error on sdb1
Jun 16 17:15:49 mithrandir kernel: [  296.601260] lost page write due to I/O error on sdb1
Jun 16 17:15:49 mithrandir kernel: [  296.601263] btrfs: 1 errors while writing supers
Jun 16 17:15:49 mithrandir kernel: [  296.601283] ------------[ cut here ]------------
Jun 16 17:15:49 mithrandir kernel: [  296.601312] kernel BUG at fs/btrfs/disk-io.c:2360!
Jun 16 17:15:49 mithrandir kernel: [  296.601337] invalid opcode: 0000 [#1] PREEMPT SMP 
Jun 16 17:15:49 mithrandir kernel: [  296.601369] CPU 0 
Jun 16 17:15:49 mithrandir kernel: [  296.601381] Modules linked in: usb_storage rfcomm bnep snd_hrtimer binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput hso snd_hda_codec_realtek btusb bluetooth crc16 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm arc4 snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi mxm_wmi snd_seq_midi_event snd_seq snd_timer snd_seq_device joydev firewire_ohci firewire_core crc_itu_t snd tpm_infineon sony_laptop iwlagn mac80211 cfg80211 rfkill soundcore snd_page_alloc
Jun 16 17:15:49 mithrandir kernel: [  296.601724] 
Jun 16 17:15:49 mithrandir kernel: [  296.601734] Pid: 4169, comm: sync Not tainted 3.0.0-rc3+ #3 Sony Corporation VGN-Z11VN_B/VAIO
Jun 16 17:15:49 mithrandir kernel: [  296.601781] RIP: 0010:[<ffffffffa023ca5d>]  [<ffffffffa023ca5d>] write_all_supers+0x224/0x237 [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.601840] RSP: 0018:ffff88008e5c3dc8  EFLAGS: 00010296
Jun 16 17:15:49 mithrandir kernel: [  296.601867] RAX: 000000000000003a RBX: ffff8801202f7970 RCX: 0000000000000002
Jun 16 17:15:49 mithrandir kernel: [  296.601901] RDX: 000000000000e0e0 RSI: ffff88008e5c2000 RDI: ffff88008e5c2000
Jun 16 17:15:49 mithrandir kernel: [  296.601935] RBP: ffff88008e5c3e28 R08: 0000000000000002 R09: ffff88010e5c3ced
Jun 16 17:15:49 mithrandir kernel: [  296.601968] R10: ffff88010e5c3ced R11: 0000000000000000 R12: 0000000000000001
Jun 16 17:15:49 mithrandir kernel: [  296.602001] R13: ffff8801202f7900 R14: ffff88012c904000 R15: 0000000000000000
Jun 16 17:15:49 mithrandir kernel: [  296.602035] FS:  00007ffebdb25700(0000) GS:ffff88013fc00000(0000) knlGS:0000000000000000
Jun 16 17:15:49 mithrandir kernel: [  296.602074] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jun 16 17:15:49 mithrandir kernel: [  296.602102] CR2: 00007fecbc7cae02 CR3: 000000008e60d000 CR4: 00000000000006f0
Jun 16 17:15:49 mithrandir kernel: [  296.602136] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 16 17:15:49 mithrandir kernel: [  296.602169] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 16 17:15:49 mithrandir kernel: [  296.602203] Process sync (pid: 4169, threadinfo ffff88008e5c2000, task ffff88008e6467b0)
Jun 16 17:15:49 mithrandir kernel: [  296.602242] Stack:
Jun 16 17:15:49 mithrandir kernel: [  296.602254]  0000000011e70000 ffff8801202f7970 ffff880111e70e63 0000000000000001
Jun 16 17:15:49 mithrandir kernel: [  296.602298]  0000000000000000 ffff8801202f7970 ffff88008e5c3e28 ffff88012c904000
Jun 16 17:15:49 mithrandir kernel: [  296.602342]  ffff8800b51e8738 ffff880106377070 0000000000000000 0000000000000000
Jun 16 17:15:49 mithrandir kernel: [  296.602386] Call Trace:
Jun 16 17:15:49 mithrandir kernel: [  296.602409]  [<ffffffffa023ca7e>] write_ctree_super+0xe/0x10 [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.602449]  [<ffffffffa0240b93>] btrfs_commit_transaction+0x54e/0x64e [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.602488]  [<ffffffff81047fb2>] ? remove_wait_queue+0x35/0x35
Jun 16 17:15:49 mithrandir kernel: [  296.602518]  [<ffffffff810d02a6>] ? __sync_filesystem+0x72/0x72
Jun 16 17:15:49 mithrandir kernel: [  296.602551]  [<ffffffffa0225bd5>] btrfs_sync_fs+0x62/0x66 [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.602581]  [<ffffffff810d0292>] __sync_filesystem+0x5e/0x72
Jun 16 17:15:49 mithrandir kernel: [  296.602610]  [<ffffffff810d02b7>] sync_one_sb+0x11/0x13
Jun 16 17:15:49 mithrandir kernel: [  296.602637]  [<ffffffff810b3c39>] iterate_supers+0x6a/0xbf
Jun 16 17:15:49 mithrandir kernel: [  296.602665]  [<ffffffff810d033e>] sys_sync+0x3d/0x4f
Jun 16 17:15:49 mithrandir kernel: [  296.602692]  [<ffffffff8139b5fb>] system_call_fastpath+0x16/0x1b
Jun 16 17:15:49 mithrandir kernel: [  296.602721] Code: 20 01 00 00 48 8b b8 28 23 00 00 48 83 c7 50 e8 31 d3 15 e1 44 3b 65 bc 7e 13 44 89 e6 48 c7 c7 5c 00 28 a0 31 c0 e8 19 6e 15 e1 <0f> 0b 48 83 c4 38 31 c0 5b 41 5c 41 5d 41 5e 41 5f 5d c3 55 48 
Jun 16 17:15:49 mithrandir kernel: [  296.602969] RIP  [<ffffffffa023ca5d>] write_all_supers+0x224/0x237 [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.603014]  RSP <ffff88008e5c3dc8>
Jun 16 17:15:49 mithrandir kernel: [  296.616310] ---[ end trace 63d7613b609d9a58 ]---
Jun 16 17:15:49 mithrandir kernel: [  296.616361] BUG: unable to handle kernel paging request at 000000000000163e
Jun 16 17:15:49 mithrandir kernel: [  296.618131] IP: [<ffffffff81118ce2>] journal_start+0x2f/0xc1
Jun 16 17:15:49 mithrandir kernel: [  296.619909] PGD 0 
Jun 16 17:15:49 mithrandir kernel: [  296.620003] Oops: 0000 [#2] PREEMPT SMP 
Jun 16 17:15:49 mithrandir kernel: [  296.620003] CPU 0 
Jun 16 17:15:49 mithrandir kernel: [  296.620003] Modules linked in: usb_storage rfcomm bnep snd_hrtimer binfmt_misc dm_crypt dm_mod isofs btrfs zlib_deflate crc32c libcrc32c vfat fat fuse loop uinput hso snd_hda_codec_realtek btusb bluetooth crc16 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm arc4 snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi mxm_wmi snd_seq_midi_event snd_seq snd_timer snd_seq_device joydev firewire_ohci firewire_core crc_itu_t snd tpm_infineon sony_laptop iwlagn mac80211 cfg80211 rfkill soundcore snd_page_alloc
Jun 16 17:15:49 mithrandir kernel: [  296.620003] 
Jun 16 17:15:49 mithrandir kernel: [  296.620003] Pid: 4169, comm: sync Tainted: G      D     3.0.0-rc3+ #3 Sony Corporation VGN-Z11VN_B/VAIO
Jun 16 17:15:49 mithrandir kernel: [  296.620003] RIP: 0010:[<ffffffff81118ce2>]  [<ffffffff81118ce2>] journal_start+0x2f/0xc1
Jun 16 17:15:49 mithrandir kernel: [  296.620003] RSP: 0018:ffff88008e5c3698  EFLAGS: 00010282
Jun 16 17:15:49 mithrandir kernel: [  296.620003] RAX: 000000000000163e RBX: ffff8800b51e8738 RCX: 0000000000000003
Jun 16 17:15:49 mithrandir kernel: [  296.620003] RDX: 0000000000000002 RSI: 000000000000000e RDI: ffff88013b377000
Jun 16 17:15:49 mithrandir kernel: [  296.620003] RBP: ffff88008e5c36b8 R08: ffffea00015b3168 R09: 000000000000000d
Jun 16 17:15:49 mithrandir kernel: [  296.620003] R10: ffffffff814182f0 R11: 00000000ffffffff R12: ffff88008e6467b0
Jun 16 17:15:49 mithrandir kernel: [  296.620003] R13: ffff88013b377000 R14: 000000000000000e R15: 0000000000000040
Jun 16 17:15:49 mithrandir kernel: [  296.620003] FS:  0000000000000000(0000) GS:ffff88013fc00000(0000) knlGS:0000000000000000
Jun 16 17:15:49 mithrandir kernel: [  296.620003] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jun 16 17:15:49 mithrandir kernel: [  296.620003] CR2: 000000000000163e CR3: 0000000001617000 CR4: 00000000000006f0
Jun 16 17:15:49 mithrandir kernel: [  296.620003] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jun 16 17:15:49 mithrandir kernel: [  296.620003] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 16 17:15:49 mithrandir kernel: [  296.620003] Process sync (pid: 4169, threadinfo ffff88008e5c2000, task ffff88008e6467b0)
Jun 16 17:15:49 mithrandir kernel: [  296.620003] Stack:
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  ffffffffffffffe2 ffffea00015b3168 0000000000000000 0000000000000480
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  ffff88008e5c36d8 ffffffff81109a1d ffff8801397ef740 ffff8801397ef740
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  ffff88008e5c3768 ffffffff811044e4 0000000000000246 ffff88008e5c37f8
Jun 16 17:15:49 mithrandir kernel: [  296.620003] Call Trace:
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81109a1d>] ext3_journal_start_sb+0x45/0x50
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff811044e4>] ext3_write_begin+0x93/0x1a1
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81085f9f>] ? get_pageblock_flags_group+0x3c/0x76
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81081ece>] generic_file_buffered_write+0xf9/0x236
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81036172>] ? current_fs_time+0x32/0x39
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81082c87>] __generic_file_aio_write+0x240/0x270
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81082d13>] generic_file_aio_write+0x5c/0xb5
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff810b120e>] do_sync_write+0xba/0xfa
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff810c7325>] ? vfsmount_lock_local_unlock+0x33/0x3e
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff8109ac87>] ? remove_vma+0x57/0x5f
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff8104e577>] ? timekeeping_get_ns+0x12/0x31
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81062bc5>] do_acct_process+0x28c/0x2bf
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff810631bc>] acct_process+0x73/0x8f
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81034f0a>] do_exit+0x244/0x698
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff810334d1>] ? kmsg_dump+0xcb/0xda
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81004912>] oops_end+0x89/0x8e
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81004a4c>] die+0x55/0x61
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81001dfc>] do_trap+0x11c/0x12b
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff8100204d>] do_invalid_op+0x93/0x9c
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffffa023ca5d>] ? write_all_supers+0x224/0x237 [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff8139c195>] invalid_op+0x15/0x20
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffffa023ca5d>] ? write_all_supers+0x224/0x237 [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffffa023ca5d>] ? write_all_supers+0x224/0x237 [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffffa023ca7e>] write_ctree_super+0xe/0x10 [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffffa0240b93>] btrfs_commit_transaction+0x54e/0x64e [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff81047fb2>] ? remove_wait_queue+0x35/0x35
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff810d02a6>] ? __sync_filesystem+0x72/0x72
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffffa0225bd5>] btrfs_sync_fs+0x62/0x66 [btrfs]
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff810d0292>] __sync_filesystem+0x5e/0x72
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff810d02b7>] sync_one_sb+0x11/0x13
Jun 16 17:15:49 mithrandir kernel: [  296.620003]  [<ffffffff810b3c39>] iterate_supers+0x6a/0xbf
Jun 16 17:15:49 mithrandir kernel: [  296.713156]  [<ffffffff810d033e>] sys_sync+0x3d/0x4f
Jun 16 17:15:49 mithrandir kernel: [  296.713561]  [<ffffffff8139b5fb>] system_call_fastpath+0x16/0x1b
Jun 16 17:15:49 mithrandir kernel: [  296.713561] Code: 41 56 41 89 f6 41 55 49 89 fd 41 54 53 65 4c 8b 24 25 00 b6 00 00 48 85 ff 49 8b 9c 24 f8 04 00 00 74 7e 48 85 db 74 0f 48 8b 03 
Jun 16 17:15:49 mithrandir kernel: [  296.713561] RIP  [<ffffffff81118ce2>] journal_start+0x2f/0xc1
Jun 16 17:15:49 mithrandir kernel: [  296.713561]  RSP <ffff88008e5c3698>
Jun 16 17:15:49 mithrandir kernel: [  296.713561] CR2: 000000000000163e
Jun 16 17:15:49 mithrandir kernel: [  296.722164] ---[ end trace 63d7613b609d9a59 ]---
Jun 16 17:15:49 mithrandir kernel: [  296.722167] Fixing recursive fault but reboot is needed!


Best wishes

Norbert
------------------------------------------------------------------------
Norbert Preining            preining@{jaist.ac.jp, logic.at, debian.org}
JAIST, Japan                                 TeX Live & Debian Developer
DSA: 0x09C5B094   fp: 14DF 2E6C 0307 BE6D AD76  A9C0 D2BF 4AA3 09C5 B094
-----------------------------------------------------------------------