kernel BUG at fs/btrfs/inode.c:4676!

kernel BUG at fs/btrfs/inode.c:4676!

[Marek Otahal]

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

Hello, 
the issue happens every time when i have to hard power-off my notebook (suspend problems). 
With kernel 2.6.39 the partition is unmountable, solution is to boot 2.6.38 kernel which 
1/ is able to mount the partition, 
2/ by doing that fixes the problem so later .39 (after clean shutdown) can mount it also. 

Attached dmesg follows. 

Thank you, 
Mark

mount options:
/dev/mapper/homeDevice /home btrfs defaults,relatime,nodev,nosuid,compress-force=lzo 0 2 # /dev/sda9 home

dmesg:
[   56.994241] loop: module loaded
[   57.172283] Btrfs loaded
[   57.191655] device label store devid 1 transid 26106 /dev/dm-3
[   57.218783] device label home devid 1 transid 450932 /dev/dm-2
[   57.459448] scsi 4:0:0:0: Direct-Access     Generic- Multi-Card       1.00 PQ: 0 ANSI: 0 CCS
[   57.460293] sd 4:0:0:0: Attached scsi generic sg1 type 0
[   57.467030] sd 4:0:0:0: [sdb] Attached SCSI removable disk
[   61.585618] EXT4-fs (sda4): warning: checktime reached, running e2fsck is recommended
[   61.671534] EXT4-fs (sda4): re-mounted. Opts: (null)
[   62.211037] device label home devid 1 transid 450932 /dev/mapper/homeDevice
[   62.212058] btrfs: force lzo compression
[   65.335194] ------------[ cut here ]------------
[   65.335308] kernel BUG at fs/btrfs/inode.c:4676!
[   65.335406] invalid opcode: 0000 [#1] PREEMPT SMP 
[   65.335532] last sysfs file: /sys/devices/virtual/bdi/btrfs-1/uevent
[   65.337833] Modules linked in: btrfs zlib_deflate crc32c libcrc32c loop uas ums_realtek uvcvideo usb_storage msr videodev media btusb bluetooth sbs sbshc arc4 ecb b43 mac80211 joydev cfg80211 ssb mmc_core pcmcia sg fuse tg3 uhci_hcd ideapad_laptop evdev sparse_keymap psmouse pcspkr snd_hda_codec_realtek iTCO_wdt iTCO_vendor_support rfkill serio_raw ehci_hcd snd_hda_intel pcmcia_core i2c_i801 libphy usbcore ac wmi battery thermal snd_hda_codec snd_hwdep snd_pcm snd_timer snd soundcore snd_page_alloc acpi_cpufreq freq_table processor mperf sha256_generic sha512_generic ext4 mbcache jbd2 crc16 cryptd aes_i586 aes_generic xts gf128mul dm_crypt dm_mod sd_mod ata_piix libata scsi_mod i915 drm_kms_helper drm i2c_algo_bit button i2c_core video intel_agp intel_gtt agpgart
[   65.337833] 
[   65.337833] Pid: 883, comm: mount Not tainted 2.6.39-ARCH #1 LENOVO                           41875QG         /Kuril                           
[   65.337833] EIP: 0060:[<f9604072>] EFLAGS: 00010282 CPU: 1
[   65.337833] EIP is at btrfs_add_link+0x172/0x200 [btrfs]
[   65.337833] EAX: ffffffef EBX: ef448908 ECX: 00000119 EDX: 00000111
[   65.337833] ESI: 004255d9 EDI: 00000020 EBP: eec77ba4 ESP: eec77b48
[   65.337833]  DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
[   65.337833] Process mount (pid: 883, ti=eec76000 task=f4c8c450 task.ti=eec76000)
[   65.337833] Stack:
[   65.396712]  00000020 004255d9 00000000 eec77b87 00000001 0000e55e 00000000 f960d6f8
[   65.396712]  eec77b88 eec77b8c eec77b90 eec77b94 ef472000 f5076800 ef448ba8 6f43c090
[   65.396712]  000046ab 01000000 00000000 00000000 00000000 0046ab6f 00000000 eec77c0c
[   65.396712] Call Trace:
[   65.396712]  [<f960d6f8>] ? btrfs_inode_ref_index+0xd8/0xe0 [btrfs]
[   65.396712]  [<f962cfcf>] add_inode_ref+0x28f/0x320 [btrfs]
[   65.396712]  [<f962de69>] replay_one_buffer+0x239/0x320 [btrfs]
[   65.396712]  [<f961cc97>] ? alloc_extent_buffer+0x77/0x3a0 [btrfs]
[   65.396712]  [<f962b7a9>] walk_down_log_tree+0x1d9/0x370 [btrfs]
[   65.396712]  [<f962b9d9>] walk_log_tree+0x99/0x1c0 [btrfs]
[   65.396712]  [<f962f2fa>] btrfs_recover_log_trees+0x1da/0x2a0 [btrfs]
[   65.396712]  [<f962dc30>] ? replay_one_dir_item+0xb0/0xb0 [btrfs]
[   65.396712]  [<f95f6749>] open_ctree+0x1129/0x1490 [btrfs]
[   65.396712]  [<c11ac7a9>] ? strlcpy+0x39/0x50
[   65.396712]  [<f95d756b>] btrfs_mount+0x4ab/0x5b0 [btrfs]
[   65.396712]  [<c1109d31>] mount_fs+0x31/0x170
[   65.396712]  [<c11207ac>] vfs_kern_mount+0x4c/0x90
[   65.396712]  [<c1120b49>] do_kern_mount+0x39/0xd0
[   65.396712]  [<c1121e31>] do_mount+0x161/0x700
[   65.396712]  [<c11226f6>] sys_mount+0x66/0xa0
[   65.396712]  [<c1330edf>] sysenter_do_call+0x12/0x28
[   65.396712] Code: 44 24 08 00 00 00 00 89 4c 24 0c 8b 4d 08 89 34 24 e8 73 cc fe ff 85 c0 0f 84 f0 fe ff ff 8b 5d f4 8b 75 f8 8b 7d fc 89 ec 5d c3 <0f> 0b 8b 81 d8 fe ff ff 8d 55 e3 b9 11 00 00 00 89 d7 05 03 01 
[   65.396712] EIP: [<f9604072>] btrfs_add_link+0x172/0x200 [btrfs] SS:ESP 0068:eec77b48
[   65.397464] ---[ end trace 5f278c10a67bc917 ]---
[   65.519660] Adding 2561304k swap on /dev/mapper/swapDevice.  Priority:-1 extents:1 across:2561304k 
[   67.243199] microcode: CPU0 sig=0x106c2, pf=0x4, revision=0x20a
[   67.292031] microcode: CPU1 sig=0x106c2, pf=0x4, revision=0x20a
[   67.298402] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[   67.305857] microcode: CPU0 updated to revision 0x218, date = 2009-04-10
[   67.315268] microcode: CPU1 updated to revision 0x218, date = 2009-04-10
[   70.985116] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   71.152025] Bluetooth: RFCOMM TTY layer initialized
[   71.156497] Bluetooth: RFCOMM socket layer initialized
[   71.160691] Bluetooth: RFCOMM ver 1.11
[  118.324105] ip_tables: (C) 2000-2006 Netfilter Core Team
[  118.574729] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)

-- 

Marek Otahal :o)

[-- Attachment #2: This is a digitally signed message part. --]
[-- Type: application/pgp-signature, Size: 836 bytes --]

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Marek Otahal]

Hi, sorry for repost, i'm not sure if my first mail was delivered. 

On Monday 06 of June 2011 12:19:56 Marek Otahal wrote:
> Hello, 
> the issue happens every time when i have to hard power-off my notebook (suspend problems). 
> With kernel 2.6.39 the partition is unmountable, solution is to boot 2.6.38 kernel which 
> 1/ is able to mount the partition, 
> 2/ by doing that fixes the problem so later .39 (after clean shutdown) can mount it also. 
> 
> Attached dmesg follows. 
> 
> Thank you, 
> Mark
> 
> mount options:
> /dev/mapper/homeDevice /home btrfs defaults,relatime,nodev,nosuid,compress-force=lzo 0 2 # /dev/sda9 home
> 
> dmesg:
> [   56.994241] loop: module loaded
> [   57.172283] Btrfs loaded
> [   57.191655] device label store devid 1 transid 26106 /dev/dm-3
> [   57.218783] device label home devid 1 transid 450932 /dev/dm-2
> [   57.459448] scsi 4:0:0:0: Direct-Access     Generic- Multi-Card       1.00 PQ: 0 ANSI: 0 CCS
> [   57.460293] sd 4:0:0:0: Attached scsi generic sg1 type 0
> [   57.467030] sd 4:0:0:0: [sdb] Attached SCSI removable disk
> [   61.585618] EXT4-fs (sda4): warning: checktime reached, running e2fsck is recommended
> [   61.671534] EXT4-fs (sda4): re-mounted. Opts: (null)
> [   62.211037] device label home devid 1 transid 450932 /dev/mapper/homeDevice
> [   62.212058] btrfs: force lzo compression
> [   65.335194] ------------[ cut here ]------------
> [   65.335308] kernel BUG at fs/btrfs/inode.c:4676!
> [   65.335406] invalid opcode: 0000 [#1] PREEMPT SMP 
> [   65.335532] last sysfs file: /sys/devices/virtual/bdi/btrfs-1/uevent
> [   65.337833] Modules linked in: btrfs zlib_deflate crc32c libcrc32c loop uas ums_realtek uvcvideo usb_storage msr videodev media btusb bluetooth sbs sbshc arc4 ecb b43 mac80211 joydev cfg80211 ssb mmc_core pcmcia sg fuse tg3 uhci_hcd ideapad_laptop evdev sparse_keymap psmouse pcspkr snd_hda_codec_realtek iTCO_wdt iTCO_vendor_support rfkill serio_raw ehci_hcd snd_hda_intel pcmcia_core i2c_i801 libphy usbcore ac wmi battery thermal snd_hda_codec snd_hwdep snd_pcm snd_timer snd soundcore snd_page_alloc acpi_cpufreq freq_table processor mperf sha256_generic sha512_generic ext4 mbcache jbd2 crc16 cryptd aes_i586 aes_generic xts gf128mul dm_crypt dm_mod sd_mod ata_piix libata scsi_mod i915 drm_kms_helper drm i2c_algo_bit button i2c_core video intel_agp intel_gtt agpgart
> [   65.337833] 
> [   65.337833] Pid: 883, comm: mount Not tainted 2.6.39-ARCH #1 LENOVO                           41875QG         /Kuril                           
> [   65.337833] EIP: 0060:[<f9604072>] EFLAGS: 00010282 CPU: 1
> [   65.337833] EIP is at btrfs_add_link+0x172/0x200 [btrfs]
> [   65.337833] EAX: ffffffef EBX: ef448908 ECX: 00000119 EDX: 00000111
> [   65.337833] ESI: 004255d9 EDI: 00000020 EBP: eec77ba4 ESP: eec77b48
> [   65.337833]  DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
> [   65.337833] Process mount (pid: 883, ti=eec76000 task=f4c8c450 task.ti=eec76000)
> [   65.337833] Stack:
> [   65.396712]  00000020 004255d9 00000000 eec77b87 00000001 0000e55e 00000000 f960d6f8
> [   65.396712]  eec77b88 eec77b8c eec77b90 eec77b94 ef472000 f5076800 ef448ba8 6f43c090
> [   65.396712]  000046ab 01000000 00000000 00000000 00000000 0046ab6f 00000000 eec77c0c
> [   65.396712] Call Trace:
> [   65.396712]  [<f960d6f8>] ? btrfs_inode_ref_index+0xd8/0xe0 [btrfs]
> [   65.396712]  [<f962cfcf>] add_inode_ref+0x28f/0x320 [btrfs]
> [   65.396712]  [<f962de69>] replay_one_buffer+0x239/0x320 [btrfs]
> [   65.396712]  [<f961cc97>] ? alloc_extent_buffer+0x77/0x3a0 [btrfs]
> [   65.396712]  [<f962b7a9>] walk_down_log_tree+0x1d9/0x370 [btrfs]
> [   65.396712]  [<f962b9d9>] walk_log_tree+0x99/0x1c0 [btrfs]
> [   65.396712]  [<f962f2fa>] btrfs_recover_log_trees+0x1da/0x2a0 [btrfs]
> [   65.396712]  [<f962dc30>] ? replay_one_dir_item+0xb0/0xb0 [btrfs]
> [   65.396712]  [<f95f6749>] open_ctree+0x1129/0x1490 [btrfs]
> [   65.396712]  [<c11ac7a9>] ? strlcpy+0x39/0x50
> [   65.396712]  [<f95d756b>] btrfs_mount+0x4ab/0x5b0 [btrfs]
> [   65.396712]  [<c1109d31>] mount_fs+0x31/0x170
> [   65.396712]  [<c11207ac>] vfs_kern_mount+0x4c/0x90
> [   65.396712]  [<c1120b49>] do_kern_mount+0x39/0xd0
> [   65.396712]  [<c1121e31>] do_mount+0x161/0x700
> [   65.396712]  [<c11226f6>] sys_mount+0x66/0xa0
> [   65.396712]  [<c1330edf>] sysenter_do_call+0x12/0x28
> [   65.396712] Code: 44 24 08 00 00 00 00 89 4c 24 0c 8b 4d 08 89 34 24 e8 73 cc fe ff 85 c0 0f 84 f0 fe ff ff 8b 5d f4 8b 75 f8 8b 7d fc 89 ec 5d c3 <0f> 0b 8b 81 d8 fe ff ff 8d 55 e3 b9 11 00 00 00 89 d7 05 03 01 
> [   65.396712] EIP: [<f9604072>] btrfs_add_link+0x172/0x200 [btrfs] SS:ESP 0068:eec77b48
> [   65.397464] ---[ end trace 5f278c10a67bc917 ]---
> [   65.519660] Adding 2561304k swap on /dev/mapper/swapDevice.  Priority:-1 extents:1 across:2561304k 
> [   67.243199] microcode: CPU0 sig=0x106c2, pf=0x4, revision=0x20a
> [   67.292031] microcode: CPU1 sig=0x106c2, pf=0x4, revision=0x20a
> [   67.298402] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
> [   67.305857] microcode: CPU0 updated to revision 0x218, date = 2009-04-10
> [   67.315268] microcode: CPU1 updated to revision 0x218, date = 2009-04-10
> [   70.985116] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
> [   71.152025] Bluetooth: RFCOMM TTY layer initialized
> [   71.156497] Bluetooth: RFCOMM socket layer initialized
> [   71.160691] Bluetooth: RFCOMM ver 1.11
> [  118.324105] ip_tables: (C) 2000-2006 Netfilter Core Team
> [  118.574729] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
> 
> 

-- 

Marek Otahal :o)

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Jan Steffens]

On Mon, Jun 6, 2011 at 12:19 PM, Marek Otahal <markotahal@gmail.com> wr=
ote:
> Hello,
> the issue happens every time when i have to hard power-off my noteboo=
k (suspend problems).
> With kernel 2.6.39 the partition is unmountable, solution is to boot =
2.6.38 kernel which
> 1/ is able to mount the partition,
> 2/ by doing that fixes the problem so later .39 (after clean shutdown=
) can mount it also.
>
> Attached dmesg follows.
>
> Thank you,
> Mark
>
> mount options:
> /dev/mapper/homeDevice /home btrfs defaults,relatime,nodev,nosuid,com=
press-force=3Dlzo 0 2 # /dev/sda9 home
>
> dmesg:
> [ =C2=A0 56.994241] loop: module loaded
> [ =C2=A0 57.172283] Btrfs loaded
> [ =C2=A0 57.191655] device label store devid 1 transid 26106 /dev/dm-=
3
> [ =C2=A0 57.218783] device label home devid 1 transid 450932 /dev/dm-=
2
> [ =C2=A0 57.459448] scsi 4:0:0:0: Direct-Access =C2=A0 =C2=A0 Generic=
- Multi-Card =C2=A0 =C2=A0 =C2=A0 1.00 PQ: 0 ANSI: 0 CCS
> [ =C2=A0 57.460293] sd 4:0:0:0: Attached scsi generic sg1 type 0
> [ =C2=A0 57.467030] sd 4:0:0:0: [sdb] Attached SCSI removable disk
> [ =C2=A0 61.585618] EXT4-fs (sda4): warning: checktime reached, runni=
ng e2fsck is recommended
> [ =C2=A0 61.671534] EXT4-fs (sda4): re-mounted. Opts: (null)
> [ =C2=A0 62.211037] device label home devid 1 transid 450932 /dev/map=
per/homeDevice
> [ =C2=A0 62.212058] btrfs: force lzo compression
> [ =C2=A0 65.335194] ------------[ cut here ]------------
> [ =C2=A0 65.335308] kernel BUG at fs/btrfs/inode.c:4676!
> [ =C2=A0 65.335406] invalid opcode: 0000 [#1] PREEMPT SMP
> [ =C2=A0 65.335532] last sysfs file: /sys/devices/virtual/bdi/btrfs-1=
/uevent
> [ =C2=A0 65.337833] Modules linked in: btrfs zlib_deflate crc32c libc=
rc32c loop uas ums_realtek uvcvideo usb_storage msr videodev media btus=
b bluetooth sbs sbshc arc4 ecb b43 mac80211 joydev cfg80211 ssb mmc_cor=
e pcmcia sg fuse tg3 uhci_hcd ideapad_laptop evdev sparse_keymap psmous=
e pcspkr snd_hda_codec_realtek iTCO_wdt iTCO_vendor_support rfkill seri=
o_raw ehci_hcd snd_hda_intel pcmcia_core i2c_i801 libphy usbcore ac wmi=
 battery thermal snd_hda_codec snd_hwdep snd_pcm snd_timer snd soundcor=
e snd_page_alloc acpi_cpufreq freq_table processor mperf sha256_generic=
 sha512_generic ext4 mbcache jbd2 crc16 cryptd aes_i586 aes_generic xts=
 gf128mul dm_crypt dm_mod sd_mod ata_piix libata scsi_mod i915 drm_kms_=
helper drm i2c_algo_bit button i2c_core video intel_agp intel_gtt agpga=
rt
> [ =C2=A0 65.337833]
> [ =C2=A0 65.337833] Pid: 883, comm: mount Not tainted 2.6.39-ARCH #1 =
LENOVO =C2=A0 =C2=A0 =C2=A0 =C2=A0 =C2=A0 =C2=A0 =C2=A0 =C2=A0 =C2=A0 =C2=
=A0 =C2=A0 =C2=A0 =C2=A0 41875QG =C2=A0 =C2=A0 =C2=A0 =C2=A0 /Kuril
> [ =C2=A0 65.337833] EIP: 0060:[<f9604072>] EFLAGS: 00010282 CPU: 1
> [ =C2=A0 65.337833] EIP is at btrfs_add_link+0x172/0x200 [btrfs]
> [ =C2=A0 65.337833] EAX: ffffffef EBX: ef448908 ECX: 00000119 EDX: 00=
000111
> [ =C2=A0 65.337833] ESI: 004255d9 EDI: 00000020 EBP: eec77ba4 ESP: ee=
c77b48
> [ =C2=A0 65.337833] =C2=A0DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 006=
8
> [ =C2=A0 65.337833] Process mount (pid: 883, ti=3Deec76000 task=3Df4c=
8c450 task.ti=3Deec76000)
> [ =C2=A0 65.337833] Stack:
> [ =C2=A0 65.396712] =C2=A000000020 004255d9 00000000 eec77b87 0000000=
1 0000e55e 00000000 f960d6f8
> [ =C2=A0 65.396712] =C2=A0eec77b88 eec77b8c eec77b90 eec77b94 ef47200=
0 f5076800 ef448ba8 6f43c090
> [ =C2=A0 65.396712] =C2=A0000046ab 01000000 00000000 00000000 0000000=
0 0046ab6f 00000000 eec77c0c
> [ =C2=A0 65.396712] Call Trace:
> [ =C2=A0 65.396712] =C2=A0[<f960d6f8>] ? btrfs_inode_ref_index+0xd8/0=
xe0 [btrfs]
> [ =C2=A0 65.396712] =C2=A0[<f962cfcf>] add_inode_ref+0x28f/0x320 [btr=
fs]
> [ =C2=A0 65.396712] =C2=A0[<f962de69>] replay_one_buffer+0x239/0x320 =
[btrfs]
> [ =C2=A0 65.396712] =C2=A0[<f961cc97>] ? alloc_extent_buffer+0x77/0x3=
a0 [btrfs]
> [ =C2=A0 65.396712] =C2=A0[<f962b7a9>] walk_down_log_tree+0x1d9/0x370=
 [btrfs]
> [ =C2=A0 65.396712] =C2=A0[<f962b9d9>] walk_log_tree+0x99/0x1c0 [btrf=
s]
> [ =C2=A0 65.396712] =C2=A0[<f962f2fa>] btrfs_recover_log_trees+0x1da/=
0x2a0 [btrfs]
> [ =C2=A0 65.396712] =C2=A0[<f962dc30>] ? replay_one_dir_item+0xb0/0xb=
0 [btrfs]
> [ =C2=A0 65.396712] =C2=A0[<f95f6749>] open_ctree+0x1129/0x1490 [btrf=
s]
> [ =C2=A0 65.396712] =C2=A0[<c11ac7a9>] ? strlcpy+0x39/0x50
> [ =C2=A0 65.396712] =C2=A0[<f95d756b>] btrfs_mount+0x4ab/0x5b0 [btrfs=
]
> [ =C2=A0 65.396712] =C2=A0[<c1109d31>] mount_fs+0x31/0x170
> [ =C2=A0 65.396712] =C2=A0[<c11207ac>] vfs_kern_mount+0x4c/0x90
> [ =C2=A0 65.396712] =C2=A0[<c1120b49>] do_kern_mount+0x39/0xd0
> [ =C2=A0 65.396712] =C2=A0[<c1121e31>] do_mount+0x161/0x700
> [ =C2=A0 65.396712] =C2=A0[<c11226f6>] sys_mount+0x66/0xa0
> [ =C2=A0 65.396712] =C2=A0[<c1330edf>] sysenter_do_call+0x12/0x28
> [ =C2=A0 65.396712] Code: 44 24 08 00 00 00 00 89 4c 24 0c 8b 4d 08 8=
9 34 24 e8 73 cc fe ff 85 c0 0f 84 f0 fe ff ff 8b 5d f4 8b 75 f8 8b 7d =
fc 89 ec 5d c3 <0f> 0b 8b 81 d8 fe ff ff 8d 55 e3 b9 11 00 00 00 89 d7 =
05 03 01
> [ =C2=A0 65.396712] EIP: [<f9604072>] btrfs_add_link+0x172/0x200 [btr=
fs] SS:ESP 0068:eec77b48
> [ =C2=A0 65.397464] ---[ end trace 5f278c10a67bc917 ]---
> [ =C2=A0 65.519660] Adding 2561304k swap on /dev/mapper/swapDevice. =C2=
=A0Priority:-1 extents:1 across:2561304k
> [ =C2=A0 67.243199] microcode: CPU0 sig=3D0x106c2, pf=3D0x4, revision=
=3D0x20a
> [ =C2=A0 67.292031] microcode: CPU1 sig=3D0x106c2, pf=3D0x4, revision=
=3D0x20a
> [ =C2=A0 67.298402] microcode: Microcode Update Driver: v2.00 <tigran=
@aivazian.fsnet.co.uk>, Peter Oruba
> [ =C2=A0 67.305857] microcode: CPU0 updated to revision 0x218, date =3D=
 2009-04-10
> [ =C2=A0 67.315268] microcode: CPU1 updated to revision 0x218, date =3D=
 2009-04-10
> [ =C2=A0 70.985116] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
> [ =C2=A0 71.152025] Bluetooth: RFCOMM TTY layer initialized
> [ =C2=A0 71.156497] Bluetooth: RFCOMM socket layer initialized
> [ =C2=A0 71.160691] Bluetooth: RFCOMM ver 1.11
> [ =C2=A0118.324105] ip_tables: (C) 2000-2006 Netfilter Core Team
> [ =C2=A0118.574729] nf_conntrack version 0.5.0 (16384 buckets, 65536 =
max)
>
> --
>
> Marek Otahal :o)
>

I've been running into the same problem, with the same workaround.
--
To unsubscribe from this list: send the line "unsubscribe linux-btrfs" =
in
the body of a message to majordomo@vger.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Andy Lutomirski]

On 06/06/2011 06:19 AM, Marek Otahal wrote:
> Hello,
> the issue happens every time when i have to hard power-off my notebook (suspend problems).
> With kernel 2.6.39 the partition is unmountable, solution is to boot 2.6.38 kernel which
> 1/ is able to mount the partition,
> 2/ by doing that fixes the problem so later .39 (after clean shutdown) can mount it also.

Same problem here.  Mounting with 2.6.38 says:

[   41.906259] Btrfs loaded
[   41.906747] device fsid e040a9d60da49596-66c0275e348878bf devid 1 
transid 69217 /dev/mapper/vg_midnight_ssd-home
[   41.908767] btrfs: disk space caching is enabled
[   42.232185] btrfs: unlinked 17 orphans
[   42.232189] btrfs: truncated 2 orphans

dmesg in 2.6.39.1 says:

[   14.956469] Btrfs loaded
[   14.963227] device fsid e040a9d60da49596-66c0275e348878bf devid 1 
transid 69217 /dev/mapper/vg_midnight_ssd-home
[   14.963680] btrfs: enabling disk space caching
[   14.963686] btrfs: use lzo compression
[   15.004196] ------------[ cut here ]------------
[   15.004255] kernel BUG at fs/btrfs/inode.c:4676!
[   15.004300] invalid opcode: 0000 [#1] SMP
[   15.004339] last sysfs file: /sys/devices/virtual/bdi/btrfs-1/uevent
[   15.004389] CPU 4
[   15.004408] Modules linked in: btrfs zlib_deflate lzo_compress 
libcrc32c snd_hda_codec_hdmi snd_hda_codec_realtek usb_storage uas vfat 
fat snd_hda_intel snd_hda_codec snd_hwdep snd_seq joydev snd_seq_device 
snd_pcm microcode snd_timer serio_raw iTCO_wdt pcspkr e1000e i2c_i801 
snd iTCO_vendor_support xhci_hcd soundcore snd_page_alloc virtio_net 
virtio virtio_ring kvm_intel kvm ipv6 xts gf128mul firewire_ohci 
firewire_core pata_acpi dm_crypt crc_itu_t ata_generic i915 
drm_kms_helper drm i2c_algo_bit i2c_core video [last unloaded: 
scsi_wait_scan]
[   15.004947]
[   15.004964] Pid: 859, comm: mount Not tainted 2.6.39.1+ #65 
         /DQ67SW
[   15.005031] RIP: 0010:[<ffffffffa03b0584>]  [<ffffffffa03b0584>] 
btrfs_add_link+0x109/0x162 [btrfs]
[   15.005122] RSP: 0018:ffff880230b79858  EFLAGS: 00010282
[   15.005165] RAX: 00000000ffffffef RBX: ffff88022fe41968 RCX: 
0000000000000057
[   15.005223] RDX: 0000000000000056 RSI: 000000000001af90 RDI: 
ffffea0007a79a10
[   15.005299] RBP: ffff880230b798c8 R08: ffffffffa038e301 R09: 
ffff880230b79760
[   15.005367] R10: 0000000000000aa3 R11: 0000000000000008 R12: 
ffff8802300ad800
[   15.005422] R13: ffff88022fe41d60 R14: 0000000000000009 R15: 
ffff880230ba7d50
[   15.005477] FS:  00007ff29d1b6820(0000) GS:ffff88023e300000(0000) 
knlGS:0000000000000000
[   15.005539] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[   15.005594] CR2: 00007f2fd5f5d00f CR3: 0000000230ed8000 CR4: 
00000000000406e0
[   15.005656] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 
0000000000000000
[   15.005711] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 
0000000000000400
[   15.005766] Process mount (pid: 859, threadinfo ffff880230b78000, 
task ffff88022c803260)
[   15.005827] Stack:
[   15.005846]  ffff880200000001 0000000000009153 ffff880230b79898 
ffff88022fe48080
[   15.005925]  0000000000000000 96ff88022f51a000 010000000000847e 
0000000000000000
[   15.005998]  0000000000001000 ffff88022fe2e120 ffff88022fe41d60 
ffff880230b799e7
[   15.006065] Call Trace:
[   15.006104]  [<ffffffffa03d14cd>] add_inode_ref+0x2bc/0x354 [btrfs]
[   15.006169]  [<ffffffffa03c4a53>] ? read_extent_buffer+0xc3/0xe3 [btrfs]
[   15.006253]  [<ffffffffa03d1e7e>] replay_one_buffer+0x197/0x212 [btrfs]
[   15.006338]  [<ffffffffa03a0f47>] ? 
btree_read_extent_buffer_pages+0xbc/0xbc [btrfs]
[   15.006411]  [<ffffffffa03cfcd8>] walk_up_log_tree+0xe4/0x1aa [btrfs]
[   15.006474]  [<ffffffffa03d1ce7>] ? replay_one_dir_item+0xbd/0xbd [btrfs]
[   15.006538]  [<ffffffffa03d00fd>] walk_log_tree+0x9e/0x19e [btrfs]
[   15.006589]  [<ffffffff8143ec48>] ? _cond_resched+0xe/0x22
[   15.006665]  [<ffffffffa03d306d>] btrfs_recover_log_trees+0x174/0x286 
[btrfs]
[   15.006733]  [<ffffffffa03d1ce7>] ? replay_one_dir_item+0xbd/0xbd [btrfs]
[   15.006798]  [<ffffffffa03a561f>] open_ctree+0xfb7/0x12a4 [btrfs]
[   15.006853]  [<ffffffffa038c7d3>] btrfs_mount+0x225/0x473 [btrfs]
[   15.006923]  [<ffffffff810c087d>] ? __free_pages+0x20/0x29
[   15.006975]  [<ffffffff8110626c>] mount_fs+0x69/0x155
[   15.007018]  [<ffffffff810d2d85>] ? __alloc_percpu+0x10/0x12
[   15.007066]  [<ffffffff8111a00d>] vfs_kern_mount+0x63/0x9d
[   15.007112]  [<ffffffff8111a996>] do_kern_mount+0x4d/0xdf
[   15.007157]  [<ffffffff8111c0a2>] do_mount+0x63c/0x69f
[   15.007198]  [<ffffffff8111c39a>] sys_mount+0x88/0xc2
[   15.007242]  [<ffffffff8144666b>] system_call_fastpath+0x16/0x1b
[   15.007308] Code: fa 4c 89 e6 48 8b 7d a8 48 89 44 24 08 41 8b 45 00 
66 c1 e8 0c 83 e0 0f 0f b6 80 48 5d 3e a0 89 04 24 e8 01 e7 fe ff 85 c0 
74 02 <0f> 0b 45 01 f6 4d 63 f6 4c 03 b3 c8 00 00 00 4c 89 b3 c8 00 00
[   15.007600] RIP  [<ffffffffa03b0584>] btrfs_add_link+0x109/0x162 [btrfs]
[   15.007686]  RSP <ffff880230b79858>
[   15.179663] ---[ end trace 614a0d527d4b2a82 ]---

--Andy

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Daniel J Blueman]

On 10 June 2011 09:57, Andy Lutomirski <luto@mit.edu> wrote:
> On 06/06/2011 06:19 AM, Marek Otahal wrote:
>>
>> Hello,
>> the issue happens every time when i have to hard power-off my notebo=
ok
>> (suspend problems).
>> With kernel 2.6.39 the partition is unmountable, solution is to boot
>> 2.6.38 kernel which
>> 1/ is able to mount the partition,
>> 2/ by doing that fixes the problem so later .39 (after clean shutdow=
n) can
>> mount it also.
>
> Same problem here. =A0Mounting with 2.6.38 says:
>
> [ =A0 41.906259] Btrfs loaded
> [ =A0 41.906747] device fsid e040a9d60da49596-66c0275e348878bf devid =
1 transid
> 69217 /dev/mapper/vg_midnight_ssd-home
> [ =A0 41.908767] btrfs: disk space caching is enabled
> [ =A0 42.232185] btrfs: unlinked 17 orphans
> [ =A0 42.232189] btrfs: truncated 2 orphans
>
> dmesg in 2.6.39.1 says:
[]
> [ =A0 15.004255] kernel BUG at fs/btrfs/inode.c:4676!
[]

I've been experiencing the same issue also.

Josef/Chris, would an metadata snapshot or full block snapshot help
debug this regression? I can probably setup a small testcase to
trigger this.

Thanks,
  Daniel
--=20
Daniel J Blueman
--
To unsubscribe from this list: send the line "unsubscribe linux-btrfs" =
in
the body of a message to majordomo@vger.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Josef Bacik]

On 06/09/2011 10:06 PM, Daniel J Blueman wrote:
> On 10 June 2011 09:57, Andy Lutomirski <luto@mit.edu> wrote:
>> On 06/06/2011 06:19 AM, Marek Otahal wrote:
>>>
>>> Hello,
>>> the issue happens every time when i have to hard power-off my notebook
>>> (suspend problems).
>>> With kernel 2.6.39 the partition is unmountable, solution is to boot
>>> 2.6.38 kernel which
>>> 1/ is able to mount the partition,
>>> 2/ by doing that fixes the problem so later .39 (after clean shutdown) can
>>> mount it also.
>>
>> Same problem here.  Mounting with 2.6.38 says:
>>
>> [   41.906259] Btrfs loaded
>> [   41.906747] device fsid e040a9d60da49596-66c0275e348878bf devid 1 transid
>> 69217 /dev/mapper/vg_midnight_ssd-home
>> [   41.908767] btrfs: disk space caching is enabled
>> [   42.232185] btrfs: unlinked 17 orphans
>> [   42.232189] btrfs: truncated 2 orphans
>>
>> dmesg in 2.6.39.1 says:
> []
>> [   15.004255] kernel BUG at fs/btrfs/inode.c:4676!
> []
> 
> I've been experiencing the same issue also.
> 
> Josef/Chris, would an metadata snapshot or full block snapshot help
> debug this regression? I can probably setup a small testcase to
> trigger this.
> 

If you can come up with a testcase to reproduce I would love you forever
;).  If I get done what I wanted to do today I will try and reproduce.
Thanks,

Josef

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Andrew Lutomirski]

On Fri, Jun 10, 2011 at 2:43 PM, Marek Otahal <markotahal@gmail.com> wrote:
>
> The test-case is quite easy,
> 1. mount the FS, just with compress-force=lzo option // I didn't try without, but on my other btrfs partition that doesn't use compression the err never happened ...so, can the others who experience the bug confirm compress=lzo used?

Yes, I use compress=lzo.

--Andy

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Josef Bacik]

On 06/10/2011 02:43 PM, Marek Otahal wrote:
> On Friday 10 of June 2011 15:33:20 Josef Bacik wrote:
>> On 06/09/2011 10:06 PM, Daniel J Blueman wrote:
>>> On 10 June 2011 09:57, Andy Lutomirski <luto@mit.edu> wrote:
>>>> On 06/06/2011 06:19 AM, Marek Otahal wrote:
>>>>>
>>>>> Hello,
>>>>> the issue happens every time when i have to hard power-off my notebook
>>>>> (suspend problems).
>>>>> With kernel 2.6.39 the partition is unmountable, solution is to boot
>>>>> 2.6.38 kernel which
>>>>> 1/ is able to mount the partition,
>>>>> 2/ by doing that fixes the problem so later .39 (after clean shutdown) can
>>>>> mount it also.
>>>>
>>>> Same problem here.  Mounting with 2.6.38 says:
>>>>
>>>> [   41.906259] Btrfs loaded
>>>> [   41.906747] device fsid e040a9d60da49596-66c0275e348878bf devid 1 transid
>>>> 69217 /dev/mapper/vg_midnight_ssd-home
>>>> [   41.908767] btrfs: disk space caching is enabled
>>>> [   42.232185] btrfs: unlinked 17 orphans
>>>> [   42.232189] btrfs: truncated 2 orphans
>>>>
>>>> dmesg in 2.6.39.1 says:
>>> []
>>>> [   15.004255] kernel BUG at fs/btrfs/inode.c:4676!
>>> []
>>>
>>> I've been experiencing the same issue also.
>>>
>>> Josef/Chris, would an metadata snapshot or full block snapshot help
>>> debug this regression? I can probably setup a small testcase to
>>> trigger this.
>>>
>>
>> If you can come up with a testcase to reproduce I would love you forever
>> ;).  If I get done what I wanted to do today I will try and reproduce.
>> Thanks,
>>
>> Josef
>>
> ...I was getting ready for you eternal love, Josef :P...but I can't reproduce it 100%, like 70% success-rate. 
> 
> The test-case is quite easy, 
> 1. mount the FS, just with compress-force=lzo option // I didn't try without, but on my other btrfs partition that doesn't use compression the err never happened ...so, can the others who experience the bug confirm compress=lzo used?   
> 2. cd to it & create a file (not sure if needed)
> 3. hard power-off
> 
> To reproduce my tests: 
> dd /dev/zero /btrfstest bs=1M count=256 (min required for default mksf.btrfs)
> losetup /dev/loop0 /btrfstest
> mkfs.btrfs /dev/loop0
> mount -o compress-force=lzo /dev/loop0 /mnt/tmp
> vim /mnt/tmp/hello.txt
> ---power off!

How long do you wait between these two steps?  I've not been able to
reproduce this and I've done it maybe 5 times.  Either I've fixed it in
my tree (yay!) or I'm doing something wrong (boo!).  Thanks,

Josef

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Marek Otahal]

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

On Friday 10 of June 2011 16:52:36 Josef Bacik wrote:
> On 06/10/2011 02:43 PM, Marek Otahal wrote:
> > On Friday 10 of June 2011 15:33:20 Josef Bacik wrote:
> >> On 06/09/2011 10:06 PM, Daniel J Blueman wrote:
> >>> On 10 June 2011 09:57, Andy Lutomirski <luto@mit.edu> wrote:
> >>>> On 06/06/2011 06:19 AM, Marek Otahal wrote:
> >>>>>
> >>>>> Hello,
> >>>>> the issue happens every time when i have to hard power-off my notebook
> >>>>> (suspend problems).
> >>>>> With kernel 2.6.39 the partition is unmountable, solution is to boot
> >>>>> 2.6.38 kernel which
> >>>>> 1/ is able to mount the partition,
> >>>>> 2/ by doing that fixes the problem so later .39 (after clean shutdown) can
> >>>>> mount it also.
> >>>>
> >>>> Same problem here.  Mounting with 2.6.38 says:
> >>>>
> >>>> [   41.906259] Btrfs loaded
> >>>> [   41.906747] device fsid e040a9d60da49596-66c0275e348878bf devid 1 transid
> >>>> 69217 /dev/mapper/vg_midnight_ssd-home
> >>>> [   41.908767] btrfs: disk space caching is enabled
> >>>> [   42.232185] btrfs: unlinked 17 orphans
> >>>> [   42.232189] btrfs: truncated 2 orphans
> >>>>
> >>>> dmesg in 2.6.39.1 says:
> >>> []
> >>>> [   15.004255] kernel BUG at fs/btrfs/inode.c:4676!
> >>> []
> >>>
> >>> I've been experiencing the same issue also.
> >>>
> >>> Josef/Chris, would an metadata snapshot or full block snapshot help
> >>> debug this regression? I can probably setup a small testcase to
> >>> trigger this.
> >>>
> >>
> >> If you can come up with a testcase to reproduce I would love you forever
> >> ;).  If I get done what I wanted to do today I will try and reproduce.
> >> Thanks,
> >>
> >> Josef
> >>
> > ...I was getting ready for you eternal love, Josef :P...but I can't reproduce it 100%, like 70% 
success-rate. 
> > 
> > The test-case is quite easy, 
> > 1. mount the FS, just with compress-force=lzo option // I didn't try without, but on my other 
btrfs partition that doesn't use compression the err never happened ...so, can the others who 
experience the bug confirm compress=lzo used?   
> > 2. cd to it & create a file (not sure if needed)
> > 3. hard power-off
> > 
> > To reproduce my tests: 
> > dd /dev/zero /btrfstest bs=1M count=256 (min required for default mksf.btrfs)
> > losetup /dev/loop0 /btrfstest
> > mkfs.btrfs /dev/loop0
> > mount -o compress-force=lzo /dev/loop0 /mnt/tmp
> > vim /mnt/tmp/hello.txt
> > ---power off!
> 
> How long do you wait between these two steps?  I've not been able to
> reproduce this and I've done it maybe 5 times.  Either I've fixed it in
> my tree (yay!) or I'm doing something wrong (boo!).  Thanks,
> 
> Josef
Not much but not immediately too, I'd say like ~5s. Did ls, df and quit. 
Tomorrow I'll try if I can spot a difference. 
Btw, is there a way to simulate power-off on a loopback-fs? Like to kill the loopback device while 
fs is mounted or some way? So I don't have to stress the poor hw :) 
Thank you, Mark

-- 

Marek Otahal :o)

[-- Attachment #2: This is a digitally signed message part. --]
[-- Type: application/pgp-signature, Size: 836 bytes --]

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Josef Bacik]

On 06/10/2011 05:52 PM, Marek Otahal wrote:
> On Friday 10 of June 2011 16:52:36 Josef Bacik wrote:
>> On 06/10/2011 02:43 PM, Marek Otahal wrote:
>>> On Friday 10 of June 2011 15:33:20 Josef Bacik wrote:
>>>> On 06/09/2011 10:06 PM, Daniel J Blueman wrote:
>>>>> On 10 June 2011 09:57, Andy Lutomirski <luto@mit.edu> wrote:
>>>>>> On 06/06/2011 06:19 AM, Marek Otahal wrote:
>>>>>>>
>>>>>>> Hello,
>>>>>>> the issue happens every time when i have to hard power-off my notebook
>>>>>>> (suspend problems).
>>>>>>> With kernel 2.6.39 the partition is unmountable, solution is to boot
>>>>>>> 2.6.38 kernel which
>>>>>>> 1/ is able to mount the partition,
>>>>>>> 2/ by doing that fixes the problem so later .39 (after clean shutdown) can
>>>>>>> mount it also.
>>>>>>
>>>>>> Same problem here.  Mounting with 2.6.38 says:
>>>>>>
>>>>>> [   41.906259] Btrfs loaded
>>>>>> [   41.906747] device fsid e040a9d60da49596-66c0275e348878bf devid 1 transid
>>>>>> 69217 /dev/mapper/vg_midnight_ssd-home
>>>>>> [   41.908767] btrfs: disk space caching is enabled
>>>>>> [   42.232185] btrfs: unlinked 17 orphans
>>>>>> [   42.232189] btrfs: truncated 2 orphans
>>>>>>
>>>>>> dmesg in 2.6.39.1 says:
>>>>> []
>>>>>> [   15.004255] kernel BUG at fs/btrfs/inode.c:4676!
>>>>> []
>>>>>
>>>>> I've been experiencing the same issue also.
>>>>>
>>>>> Josef/Chris, would an metadata snapshot or full block snapshot help
>>>>> debug this regression? I can probably setup a small testcase to
>>>>> trigger this.
>>>>>
>>>>
>>>> If you can come up with a testcase to reproduce I would love you forever
>>>> ;).  If I get done what I wanted to do today I will try and reproduce.
>>>> Thanks,
>>>>
>>>> Josef
>>>>
>>> ...I was getting ready for you eternal love, Josef :P...but I can't reproduce it 100%, like 70% 
> success-rate. 
>>>
>>> The test-case is quite easy, 
>>> 1. mount the FS, just with compress-force=lzo option // I didn't try without, but on my other 
> btrfs partition that doesn't use compression the err never happened ...so, can the others who 
> experience the bug confirm compress=lzo used?   
>>> 2. cd to it & create a file (not sure if needed)
>>> 3. hard power-off
>>>
>>> To reproduce my tests: 
>>> dd /dev/zero /btrfstest bs=1M count=256 (min required for default mksf.btrfs)
>>> losetup /dev/loop0 /btrfstest
>>> mkfs.btrfs /dev/loop0
>>> mount -o compress-force=lzo /dev/loop0 /mnt/tmp
>>> vim /mnt/tmp/hello.txt
>>> ---power off!
>>
>> How long do you wait between these two steps?  I've not been able to
>> reproduce this and I've done it maybe 5 times.  Either I've fixed it in
>> my tree (yay!) or I'm doing something wrong (boo!).  Thanks,
>>
>> Josef
> Not much but not immediately too, I'd say like ~5s. Did ls, df and quit. 
> Tomorrow I'll try if I can spot a difference. 
> Btw, is there a way to simulate power-off on a loopback-fs? Like to kill the loopback device while 
> fs is mounted or some way? So I don't have to stress the poor hw :) 
> Thank you, Mark
> 

I've not been able to hit this at all.  Can you try on 3.0-rc4 and see
if you are still hitting it?  Maybe it accidently got fixed already :).
 Thanks,

Josef

Re: kernel BUG at fs/btrfs/inode.c:4676!

[David Woodhouse]

On Wed, 2011-06-22 at 12:09 -0400, Josef Bacik wrote:
> On 06/10/2011 05:52 PM, Marek Otahal wrote:
> > On Friday 10 of June 2011 16:52:36 Josef Bacik wrote:
> >> On 06/10/2011 02:43 PM, Marek Otahal wrote:
> >>> On Friday 10 of June 2011 15:33:20 Josef Bacik wrote:
> >>>> On 06/09/2011 10:06 PM, Daniel J Blueman wrote:
> >>>>> On 10 June 2011 09:57, Andy Lutomirski <luto@mit.edu> wrote:
> >>>>>> On 06/06/2011 06:19 AM, Marek Otahal wrote:
> >>>>>>>
> >>>>>>> Hello,
> >>>>>>> the issue happens every time when i have to hard power-off my notebook
> >>>>>>> (suspend problems).
> >>>>>>> With kernel 2.6.39 the partition is unmountable, solution is to boot
> >>>>>>> 2.6.38 kernel which
> >>>>>>> 1/ is able to mount the partition,
> >>>>>>> 2/ by doing that fixes the problem so later .39 (after clean shutdown) can
> >>>>>>> mount it also.
> >>>>>>
> >>>>>> Same problem here.  Mounting with 2.6.38 says:
> >>>>>>
> >>>>>> [   41.906259] Btrfs loaded
> >>>>>> [   41.906747] device fsid e040a9d60da49596-66c0275e348878bf devid 1 transid
> >>>>>> 69217 /dev/mapper/vg_midnight_ssd-home
> >>>>>> [   41.908767] btrfs: disk space caching is enabled
> >>>>>> [   42.232185] btrfs: unlinked 17 orphans
> >>>>>> [   42.232189] btrfs: truncated 2 orphans
> >>>>>>
> >>>>>> dmesg in 2.6.39.1 says:
> >>>>> []
> >>>>>> [   15.004255] kernel BUG at fs/btrfs/inode.c:4676!
> >>>>> []
> >>>>>
> >>>>> I've been experiencing the same issue also.
> >>>>>
> >>>>> Josef/Chris, would an metadata snapshot or full block snapshot help
> >>>>> debug this regression? I can probably setup a small testcase to
> >>>>> trigger this.
> >>>>>
> >>>>
> >>>> If you can come up with a testcase to reproduce I would love you forever
> >>>> ;).  If I get done what I wanted to do today I will try and reproduce.
> >>>> Thanks,
> >>>>
> >>>> Josef
> >>>>
> >>> ...I was getting ready for you eternal love, Josef :P...but I can't reproduce it 100%, like 70% 
> > success-rate. 
> >>>
> >>> The test-case is quite easy, 
> >>> 1. mount the FS, just with compress-force=lzo option // I didn't try without, but on my other 
> > btrfs partition that doesn't use compression the err never happened ...so, can the others who 
> > experience the bug confirm compress=lzo used?   
> >>> 2. cd to it & create a file (not sure if needed)
> >>> 3. hard power-off
> >>>
> >>> To reproduce my tests: 
> >>> dd /dev/zero /btrfstest bs=1M count=256 (min required for default mksf.btrfs)
> >>> losetup /dev/loop0 /btrfstest
> >>> mkfs.btrfs /dev/loop0
> >>> mount -o compress-force=lzo /dev/loop0 /mnt/tmp
> >>> vim /mnt/tmp/hello.txt
> >>> ---power off!
> >>
> >> How long do you wait between these two steps?  I've not been able to
> >> reproduce this and I've done it maybe 5 times.  Either I've fixed it in
> >> my tree (yay!) or I'm doing something wrong (boo!).  Thanks,
> >>
> >> Josef
> > Not much but not immediately too, I'd say like ~5s. Did ls, df and quit. 
> > Tomorrow I'll try if I can spot a difference. 
> > Btw, is there a way to simulate power-off on a loopback-fs? Like to kill the loopback device while 
> > fs is mounted or some way? So I don't have to stress the poor hw :) 
> > Thank you, Mark
> > 
> 
> I've not been able to hit this at all.  Can you try on 3.0-rc4 and see
> if you are still hitting it?  Maybe it accidently got fixed already :).
>  Thanks,

While repeatedly crashing 3.0-rc7 with attempts to make Broadcom
wireless work, I've seen something very similar to this. Like Marek, I
have to boot 2.6.38 to recover, and then I can boot 3.0 again. I've been
seeing it for a while, but upon looking in to the mailing list I saw it
was already being discussed and even had a test case more useful than
"sometimes when I crash my kernel...", so I figured it was already in
hand.

I'll try to crash it tonight so I can hand it to Chris in the morning.
Obviously, my attempts to reproduce it on demand so far have failed :)

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Chris Mason]

Excerpts from David Woodhouse's message of 2011-07-20 02:05:02 -0400:
> On Wed, 2011-06-22 at 12:09 -0400, Josef Bacik wrote:
> > >>>>>>>
> > >>>>>>> Hello,
> > >>>>>>> the issue happens every time when i have to hard power-off my notebook
> > >>>>>>> (suspend problems).
> > >>>>>>> With kernel 2.6.39 the partition is unmountable, solution is to boot
> > >>>>>>> 2.6.38 kernel which
> > >>>>>>> 1/ is able to mount the partition,
> > >>>>>>> 2/ by doing that fixes the problem so later .39 (after clean shutdown) can
> > >>>>>>> mount it also.
> > >>>>>>
> > >>>>>> Same problem here.  Mounting with 2.6.38 says:
> > >>>>>>
> > >>>>>> [   41.906259] Btrfs loaded
> > >>>>>> [   41.906747] device fsid e040a9d60da49596-66c0275e348878bf devid 1 transid
> > >>>>>> 69217 /dev/mapper/vg_midnight_ssd-home
> > >>>>>> [   41.908767] btrfs: disk space caching is enabled
> > >>>>>> [   42.232185] btrfs: unlinked 17 orphans
> > >>>>>> [   42.232189] btrfs: truncated 2 orphans
> > >>>>>>
> > >>>>>> dmesg in 2.6.39.1 says:
> > >>>>> []
> > >>>>>> [   15.004255] kernel BUG at fs/btrfs/inode.c:4676!
> > >>>>> []
> > >>>>>
> > >>>>> I've been experiencing the same issue also.
> > >>>>>
> > >>>>> Josef/Chris, would an metadata snapshot or full block snapshot help
> > >>>>> debug this regression? I can probably setup a small testcase to
> > >>>>> trigger this.
> > >>>>>
> > >>>>
> > >>>> If you can come up with a testcase to reproduce I would love you forever
> > >>>> ;).  If I get done what I wanted to do today I will try and reproduce.
> > >>>> Thanks,
> > >>>>
> > >>>> Josef
> > >>>>
> > >>> ...I was getting ready for you eternal love, Josef :P...but I can't reproduce it 100%, like 70% 
> > > success-rate. 
> > >>>
> > >>> The test-case is quite easy, 
> > >>> 1. mount the FS, just with compress-force=lzo option // I didn't try without, but on my other 
> > > btrfs partition that doesn't use compression the err never happened ...so, can the others who 
> > > experience the bug confirm compress=lzo used?   
> > >>> 2. cd to it & create a file (not sure if needed)
> > >>> 3. hard power-off
> > >>>
> > >>> To reproduce my tests: 
> > >>> dd /dev/zero /btrfstest bs=1M count=256 (min required for default mksf.btrfs)
> > >>> losetup /dev/loop0 /btrfstest
> > >>> mkfs.btrfs /dev/loop0
> > >>> mount -o compress-force=lzo /dev/loop0 /mnt/tmp
> > >>> vim /mnt/tmp/hello.txt
> > >>> ---power off!

Oh, the dirty little secret of loop devices is they don't actually write
things to disk properly.   They are not power off safe.  But you can
trigger this without a loop device, correct?

> 
> While repeatedly crashing 3.0-rc7 with attempts to make Broadcom
> wireless work, I've seen something very similar to this. Like Marek, I
> have to boot 2.6.38 to recover, and then I can boot 3.0 again. I've been
> seeing it for a while, but upon looking in to the mailing list I saw it
> was already being discussed and even had a test case more useful than
> "sometimes when I crash my kernel...", so I figured it was already in
> hand.
> 
> I'll try to crash it tonight so I can hand it to Chris in the morning.
> Obviously, my attempts to reproduce it on demand so far have failed :)
> 

The oops were hitting is a -EEXIST on trying to insert the directory
entry for the inode back ref, but the tree-logging stuff is already
trying to check for dups.

I'll take a look.

-chris

Re: kernel BUG at fs/btrfs/inode.c:4676!

[David Woodhouse]

On Wed, 2011-07-20 at 04:44 -0400, Chris Mason wrote:
> Oh, the dirty little secret of loop devices is they don't actually write
> things to disk properly.   They are not power off safe.  But you can
> trigger this without a loop device, correct?

Yes. I would have liked to reproduce it last night and show it to you
this morning, and that way I'd double-check that it really is the *same*
BUG(). But certainly I thought it was a few weeks ago when I looked, and
the bit about having to reboot into 2.6.38 before I can boot 3.0 is
*definitely* the same.

> The oops were hitting is a -EEXIST on trying to insert the directory
> entry for the inode back ref, but the tree-logging stuff is already
> trying to check for dups.
> 
> I'll take a look.

Thanks. I'll *try* to make it happen again, but I haven't managed it so
far... and no, I haven't updated my kernel; this is the *same* kernel
that was doing it before.

Re: kernel BUG at fs/btrfs/inode.c:4676!

[Stephane Chazelas]

2011-06-06 12:19:56 +0200, Marek Otahal:
> Hello, 
> the issue happens every time when i have to hard power-off my notebook (suspend problems). 
> With kernel 2.6.39 the partition is unmountable, solution is to boot 2.6.38 kernel which 
> 1/ is able to mount the partition, 
> 2/ by doing that fixes the problem so later .39 (after clean shutdown) can mount it also. 
[...]

I've just been hit by this (3.0). I dug up a 2.6.38 kernel and got
it back running just the same. Has any progress made on this?

[39564.802905] device fsid 01b919f7-32cd-4d09-be1c-1810249001b2 devid 1 transid 21097 /dev/mapper/VG_USB_debian-root
[39565.555655] ------------[ cut here ]------------
[39565.555662] kernel BUG at /build/buildd-linux-2.6_3.0.0-3-amd64-9ClimQ/linux-2.6-3.0.0/debian/build/source_amd64_none/fs/btrfs/inode.c:4586!
[39565.555668] invalid opcode: 0000 [#1] SMP
[39565.555672] CPU 1
[39565.555674] Modules linked in: ext2 hfsplus nls_utf8 nls_cp437 vfat fat ip6table_filter ip6_tables ebtable_nat ebtables vboxnetadp(O) vboxnetflt(O) ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT vboxdrv(O) xt_CHECKSUM iptable_mangle xt_tcpudp bridge stp parport_pc ppdev lp parport rfcomm bnep bluetooth rfkill xt_multiport iptable_filter ip_tables x_tables snd_hrtimer acpi_cpufreq mperf cpufreq_conservative cpufreq_powersave cpufreq_userspace cpufreq_stats binfmt_misc fuse nfsd nfs lockd fscache auth_rpcgss nfs_acl sunrpc ext3 jbd loop dm_crypt kvm_intel kvm uvcvideo videodev media v4l2_compat_ioctl32 nvidia(P) snd_hda_codec_via snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_seq snd_timer snd_seq_device evdev i7core_edac snd i2c_i80
 1 edac_core pcspkr soundcore i2c_core asus_atk0110 snd_page_alloc button processor thermal_sys ext4 mbcache jbd2 crc16 btrfs zlib_deflate crc32c libcrc32c dm_mod raid10 raid456 async_raid6_recov async_pq raid6_pq async_xor xor async_memcpy async_tx raid1 raid0 multipath linear md_mod nbd sg sd_mod sr_mod crc_t10dif cdrom ata_generic usb_storage usbhid hid uas pata_jmicron firewire_ohci firewire_core crc_itu_t ahci libahci ehci_hcd libata scsi_mod r8169 mii usbcore [last unloaded: scsi_wait_scan]
[39565.555806]
[39565.555810] Pid: 18729, comm: mount Tainted: P          IO 3.0.0-1-amd64 #1 System manufacturer System Product Name/P7P55D
[39565.555817] RIP: 0010:[<ffffffffa01fe008>]  [<ffffffffa01fe008>] btrfs_add_link+0x120/0x178 [btrfs]
[39565.555850] RSP: 0018:ffff8801c5273858  EFLAGS: 00010282
[39565.555854] RAX: 00000000ffffffef RBX: ffff8801caf96d90 RCX: ffff8802124d01d8
[39565.555858] RDX: 000000000000000e RSI: ffff8801948da880 RDI: 0000000000000292
[39565.555862] RBP: ffff880162b12800 R08: 0000000000000050 R09: 000000000000000d
[39565.555866] R10: 000000000000000c R11: 0000000000015670 R12: ffff8801caf7d1d8
[39565.555870] R13: 000000000000000b R14: ffff88017b3c0600 R15: ffff8801cecfb540
[39565.555875] FS:  00007f1062f7b7e0(0000) GS:ffff88023fc20000(0000) knlGS:0000000000000000
[39565.555879] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[39565.555883] CR2: 00007f84b7689000 CR3: 000000017b112000 CR4: 00000000000006e0
[39565.555887] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[39565.555891] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[39565.555896] Process mount (pid: 18729, threadinfo ffff8801c5272000, task ffff880236e947f0)
[39565.555899] Stack:
[39565.555901]  0000000000000001 00000000000001c9 ffff880100000000 00000000000592ad
[39565.555908]  00000000000592ad 0000000000000001 ffff880203d96e00 0000000c00000000
[39565.555915]  0000000000001000 ffff8801cec8b7f0 ffff8801c52739e8 ffff8801caf7d1d8
[39565.555922] Call Trace:
[39565.555948]  [<ffffffffa021ddf5>] ? add_inode_ref+0x2f3/0x385 [btrfs]
[39565.555974]  [<ffffffffa0220063>] ? replay_one_buffer+0x181/0x1fb [btrfs]
[39565.556000]  [<ffffffffa0210c4e>] ? alloc_extent_buffer+0x6f/0x295 [btrfs]
[39565.556025]  [<ffffffffa021f7e8>] ? walk_down_log_tree+0x153/0x29c [btrfs]
[39565.556050]  [<ffffffffa021f9b2>] ? walk_log_tree+0x81/0x196 [btrfs]
[39565.556074]  [<ffffffffa01f0b4e>] ? btrfs_read_fs_root_no_radix+0x166/0x1a5 [btrfs]
[39565.556099]  [<ffffffffa0221177>] ? btrfs_recover_log_trees+0x192/0x297 [btrfs]
[39565.556125]  [<ffffffffa021fee2>] ? replay_one_dir_item+0xb3/0xb3 [btrfs]
[39565.556148]  [<ffffffffa01ef722>] ? btree_read_extent_buffer_pages.clone.63+0x6f/0xb2 [btrfs]
[39565.556173]  [<ffffffffa01f2f47>] ? open_ctree+0x10f5/0x140e [btrfs]
[39565.556180]  [<ffffffff811aa488>] ? string.clone.2+0x39/0x9f
[39565.556187]  [<ffffffff810fdc92>] ? sget+0x363/0x381
[39565.556207]  [<ffffffffa01d9743>] ? btrfs_mount+0x228/0x470 [btrfs]
[39565.556213]  [<ffffffff810cdcd6>] ? pcpu_next_pop+0x37/0x45
[39565.556219]  [<ffffffff810cda22>] ? cpumask_next+0x18/0x1d
[39565.556224]  [<ffffffff810ceb4c>] ? pcpu_alloc+0x7b4/0x7cc
[39565.556232]  [<ffffffff810fe52b>] ? mount_fs+0x67/0x150
[39565.556241]  [<ffffffff81111c4c>] ? vfs_kern_mount+0x58/0x97
[39565.556249]  [<ffffffff81111f10>] ? do_kern_mount+0x49/0xd8
[39565.556255]  [<ffffffff81113645>] ? do_mount+0x690/0x6f6
[39565.556262]  [<ffffffff8103840a>] ? should_resched+0x5/0x24
[39565.556269]  [<ffffffff813358d7>] ? _cond_resched+0x9/0x20
[39565.556275]  [<ffffffff810caf25>] ? memdup_user+0x36/0x5b
[39565.556280]  [<ffffffff81113939>] ? sys_mount+0x88/0xc3
[39565.556287]  [<ffffffff8133bd12>] ? system_call_fastpath+0x16/0x1b
[39565.556291] Code: 89 f2 48 89 ee 4c 89 ff 48 89 44 24 08 41 8b 04 24 66 c1 e8 0c 83 e0 0f 0f b6 80 70 6c 23 a0 89 04 24 e8 f4 da fe ff 85 c0 74 02 <0f> 0b 45 01 ed 4d 63 ed 4c 03 ab d0 00 00 00 4c 89 ab d0 00 00
[39565.556334] RIP  [<ffffffffa01fe008>] btrfs_add_link+0x120/0x178 [btrfs]
[39565.556360]  RSP <ffff8801c5273858>
[39565.556434] ---[ end trace a7919e7f17c0a727 ]---

-- 
Stephane