I receive the bug when I try to snapshot from fcron: 2011-08-29T02:00:46.529238+01:00 picard kernel: [ 4155.444476] ------------[ cut here ]------------ 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.444490] kernel BUG at fs/btrfs/inode.c:2299! 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.444498] invalid opcode: 0000 [#1] PREEMPT SMP 2011-08-29T02:00:46.529259+01:00 picard kernel: [ 4155.444506] CPU 1 2011-08-29T02:00:46.529264+01:00 picard kernel: [ 4155.444508] Modules linked in: microcode reiserfs btrfs zlib_deflate libcrc32c arc4 snd_hda_codec_conexant iwlagn mac80211 uvcvideo videodev v4l2_compat_ioctl32 cfg80211 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd thinkpad_acpi soundcore snd_page_alloc hwmon rfkill nvram e1000e zram(C) rtc_cmos tp_smapi thinkpad_ec psmouse fscache kvm_intel kvm configs ipv6 autofs4 usbhid uhci_hcd firewire_ohci firewire_core sdhci_pci ehci_hcd crc_itu_t sdhci mmc_core sd_mod usbcore sr_mod cdrom 2011-08-29T02:00:46.529267+01:00 picard kernel: [ 4155.444569] 2011-08-29T02:00:46.529270+01:00 picard kernel: [ 4155.444576] Pid: 3167, comm: btrfs Tainted: P C 3.0.3-gentoo #1 LENOVO 2242CTO/2242CTO 2011-08-29T02:00:46.529274+01:00 picard kernel: [ 4155.444586] RIP: 0010:[<ffffffffa031339b>] [<ffffffffa031339b>] btrfs_orphan_del +0x8d/0xa9 [btrfs] 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: 0000:ffff880111c21b58 EFLAGS: 00010282 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: 00000000fffffffe RBX: ffff88011141bd60 RCX: 0000000000001000 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: 000000000000000f RSI: ffff880110c8d250 RDI: ffff880134092d80 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: 0000000000000000 R08: ffff880111c21a30 R09: 0000000000000011 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: 00000000000001dc R11: 0000000000000286 R12: ffff880136866300 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: ffff880130cac800 R14: 0000000000000001 R15: ffff880130cacb88 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: 00007f126715a740(0000) GS:ffff88013bc80000(0000) knlGS:0000000000000000 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: 00007f5149eb7008 CR3: 000000009caca000 CR4: 00000000000406e0 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process btrfs (pid: 3167, threadinfo ffff880111c20000, task ffff88010b35a140) 2011-08-29T02:00:46.529316+01:00 picard kernel: [ 4155.444705] Stack: 2011-08-29T02:00:46.529318+01:00 picard kernel: [ 4155.444711] 0000000000c369c5 ffff880130cac800 ffff880110c8dbe0 0000000000c369c5 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] 0000000000000000 ffff880130cacb88 ffff880130cacb90 ffffffffa03173c4 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] ffff880136866300 ffff88011141bd60 ffff880110c8dbe0 fffb000000000000 2011-08-29T02:00:46.562959+01:00 picard kernel: [ 4155.444739] Call Trace: 2011-08-29T02:00:46.562964+01:00 picard kernel: [ 4155.444756] [<ffffffffa03173c4>] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] [<ffffffffa0317862>] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] [<ffffffffa03178a2>] ? btrfs_lookup+0x9/0x1f [btrfs] 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] [<ffffffff810ccf63>] ? d_alloc_and_lookup+0x3a/0x60 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] [<ffffffff810ce356>] ? walk_component+0x210/0x3b7 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] [<ffffffff810cea4a>] ? path_lookupat+0x7c/0x29e 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] [<ffffffff810cfc34>] ? do_path_lookup+0x1c/0x87 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] [<ffffffff810cffe2>] ? user_path_at+0x49/0x7b 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] [<ffffffff810c8781>] ? vfs_fstatat+0x3c/0x6a 2011-08-29T02:00:46.562986+01:00 picard kernel: [ 4155.444853] [<ffffffff8102d38e>] ? sub_preempt_count+0x83/0x94 2011-08-29T02:00:46.562988+01:00 picard kernel: [ 4155.444861] [<ffffffff810c88ab>] ? sys_newstat+0x12/0x2b 2011-08-29T02:00:46.562990+01:00 picard kernel: [ 4155.444870] [<ffffffff81346d4f>] ? page_fault+0x1f/0x30 2011-08-29T02:00:46.562992+01:00 picard kernel: [ 4155.444878] [<ffffffff8134713b>] ? system_call_fastpath+0x16/0x1b 2011-08-29T02:00:46.562995+01:00 picard kernel: [ 4155.444885] Code: 4d 85 e4 74 28 48 8b 93 70 fe ff ff 48 81 fa 00 01 00 00 77 07 48 8b 93 b0 00 00 00 4c 89 ee 4c 89 e7 e8 af 28 02 00 85 c0 74 02 <0f> 0b 85 ed 74 08 48 89 df e8 e0 ca fe ff 5d 31 c0 5b 5d 41 5c 2011-08-29T02:00:46.563001+01:00 picard kernel: [ 4155.444926] RIP [<ffffffffa031339b>] btrfs_orphan_del+0x8d/0xa9 [btrfs] 2011-08-29T02:00:46.563004+01:00 picard kernel: [ 4155.444943] RSP <ffff880111c21b58> 2011-08-29T02:00:46.563014+01:00 picard kernel: [ 4155.464057] ---[ end trace 276655044a13c7af ]--- Unfortunately it results in freeze of system and I cannot give more details. Sometimes it happens not from fcron but then it does not result in freeze (???). Regards
On mon, 29 Aug 2011 02:45:07 +0100, Maciej Marcin Piechotka wrote:> I receive the bug when I try to snapshot from fcron: > > 2011-08-29T02:00:46.529238+01:00 picard kernel: [ 4155.444476] > ------------[ cut here ]------------ > 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.444490] kernel > BUG at fs/btrfs/inode.c:2299! > 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.444498] invalid > opcode: 0000 [#1] PREEMPT SMP > 2011-08-29T02:00:46.529259+01:00 picard kernel: [ 4155.444506] CPU 1 > 2011-08-29T02:00:46.529264+01:00 picard kernel: [ 4155.444508] Modules > linked in: microcode reiserfs btrfs zlib_deflate libcrc32c arc4 > snd_hda_codec_conexant iwlagn mac80211 uvcvideo videodev > v4l2_compat_ioctl32 cfg80211 snd_hda_intel snd_hda_codec snd_hwdep > snd_pcm snd_timer snd thinkpad_acpi soundcore snd_page_alloc hwmon > rfkill nvram e1000e zram(C) rtc_cmos tp_smapi thinkpad_ec psmouse > fscache kvm_intel kvm configs ipv6 autofs4 usbhid uhci_hcd firewire_ohci > firewire_core sdhci_pci ehci_hcd crc_itu_t sdhci mmc_core sd_mod usbcore > sr_mod cdrom > 2011-08-29T02:00:46.529267+01:00 picard kernel: [ 4155.444569] > 2011-08-29T02:00:46.529270+01:00 picard kernel: [ 4155.444576] Pid: > 3167, comm: btrfs Tainted: P C 3.0.3-gentoo #1 LENOVO > 2242CTO/2242CTO > 2011-08-29T02:00:46.529274+01:00 picard kernel: [ 4155.444586] RIP: > 0010:[<ffffffffa031339b>] [<ffffffffa031339b>] btrfs_orphan_del > +0x8d/0xa9 [btrfs] > 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: > 0000:ffff880111c21b58 EFLAGS: 00010282 > 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: > 00000000fffffffe RBX: ffff88011141bd60 RCX: 0000000000001000 > 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: > 000000000000000f RSI: ffff880110c8d250 RDI: ffff880134092d80 > 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: > 0000000000000000 R08: ffff880111c21a30 R09: 0000000000000011 > 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: > 00000000000001dc R11: 0000000000000286 R12: ffff880136866300 > 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: > ffff880130cac800 R14: 0000000000000001 R15: ffff880130cacb88 > 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: > 00007f126715a740(0000) GS:ffff88013bc80000(0000) knlGS:0000000000000000 > 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 > DS: 0000 ES: 0000 CR0: 000000008005003b > 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: > 00007f5149eb7008 CR3: 000000009caca000 CR4: 00000000000406e0 > 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: > 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 > 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: > 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 > 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process > btrfs (pid: 3167, threadinfo ffff880111c20000, task ffff88010b35a140) > 2011-08-29T02:00:46.529316+01:00 picard kernel: [ 4155.444705] Stack: > 2011-08-29T02:00:46.529318+01:00 picard kernel: [ 4155.444711] > 0000000000c369c5 ffff880130cac800 ffff880110c8dbe0 0000000000c369c5 > 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] > 0000000000000000 ffff880130cacb88 ffff880130cacb90 ffffffffa03173c4 > 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] > ffff880136866300 ffff88011141bd60 ffff880110c8dbe0 fffb000000000000 > 2011-08-29T02:00:46.562959+01:00 picard kernel: [ 4155.444739] Call > Trace: > 2011-08-29T02:00:46.562964+01:00 picard kernel: [ 4155.444756] > [<ffffffffa03173c4>] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] > 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] > [<ffffffffa0317862>] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] > 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] > [<ffffffffa03178a2>] ? btrfs_lookup+0x9/0x1f [btrfs] > 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] > [<ffffffff810ccf63>] ? d_alloc_and_lookup+0x3a/0x60 > 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] > [<ffffffff810ce356>] ? walk_component+0x210/0x3b7 > 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] > [<ffffffff810cea4a>] ? path_lookupat+0x7c/0x29e > 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] > [<ffffffff810cfc34>] ? do_path_lookup+0x1c/0x87 > 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] > [<ffffffff810cffe2>] ? user_path_at+0x49/0x7b > 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] > [<ffffffff810c8781>] ? vfs_fstatat+0x3c/0x6a > 2011-08-29T02:00:46.562986+01:00 picard kernel: [ 4155.444853] > [<ffffffff8102d38e>] ? sub_preempt_count+0x83/0x94 > 2011-08-29T02:00:46.562988+01:00 picard kernel: [ 4155.444861] > [<ffffffff810c88ab>] ? sys_newstat+0x12/0x2b > 2011-08-29T02:00:46.562990+01:00 picard kernel: [ 4155.444870] > [<ffffffff81346d4f>] ? page_fault+0x1f/0x30 > 2011-08-29T02:00:46.562992+01:00 picard kernel: [ 4155.444878] > [<ffffffff8134713b>] ? system_call_fastpath+0x16/0x1b > 2011-08-29T02:00:46.562995+01:00 picard kernel: [ 4155.444885] Code: 4d > 85 e4 74 28 48 8b 93 70 fe ff ff 48 81 fa 00 01 00 00 77 07 48 8b 93 b0 > 00 00 00 4c 89 ee 4c 89 e7 e8 af 28 02 00 85 c0 74 02 <0f> 0b 85 ed 74 > 08 48 89 df e8 e0 ca fe ff 5d 31 c0 5b 5d 41 5c > 2011-08-29T02:00:46.563001+01:00 picard kernel: [ 4155.444926] RIP > [<ffffffffa031339b>] btrfs_orphan_del+0x8d/0xa9 [btrfs] > 2011-08-29T02:00:46.563004+01:00 picard kernel: [ 4155.444943] RSP > <ffff880111c21b58> > 2011-08-29T02:00:46.563014+01:00 picard kernel: [ 4155.464057] ---[ end > trace 276655044a13c7af ]--- > > Unfortunately it results in freeze of system and I cannot give more > details. Sometimes it happens not from fcron but then it does not result > in freeze (???).Could you give me the method to reproduce it? Thanks Miao> > Regards-- 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
On Tue, 2011-08-30 at 14:27 +0800, Miao Xie wrote:> On mon, 29 Aug 2011 02:45:07 +0100, Maciej Marcin Piechotka wrote: > > I receive the bug when I try to snapshot from fcron: > > > > 2011-08-29T02:00:46.529238+01:00 picard kernel: [ 4155.444476] > > ------------[ cut here ]------------ > > 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.444490] kernel > > BUG at fs/btrfs/inode.c:2299! > > 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.444498] invalid > > opcode: 0000 [#1] PREEMPT SMP > > 2011-08-29T02:00:46.529259+01:00 picard kernel: [ 4155.444506] CPU 1 > > 2011-08-29T02:00:46.529264+01:00 picard kernel: [ 4155.444508] Modules > > linked in: microcode reiserfs btrfs zlib_deflate libcrc32c arc4 > > snd_hda_codec_conexant iwlagn mac80211 uvcvideo videodev > > v4l2_compat_ioctl32 cfg80211 snd_hda_intel snd_hda_codec snd_hwdep > > snd_pcm snd_timer snd thinkpad_acpi soundcore snd_page_alloc hwmon > > rfkill nvram e1000e zram(C) rtc_cmos tp_smapi thinkpad_ec psmouse > > fscache kvm_intel kvm configs ipv6 autofs4 usbhid uhci_hcd firewire_ohci > > firewire_core sdhci_pci ehci_hcd crc_itu_t sdhci mmc_core sd_mod usbcore > > sr_mod cdrom > > 2011-08-29T02:00:46.529267+01:00 picard kernel: [ 4155.444569] > > 2011-08-29T02:00:46.529270+01:00 picard kernel: [ 4155.444576] Pid: > > 3167, comm: btrfs Tainted: P C 3.0.3-gentoo #1 LENOVO > > 2242CTO/2242CTO > > 2011-08-29T02:00:46.529274+01:00 picard kernel: [ 4155.444586] RIP: > > 0010:[<ffffffffa031339b>] [<ffffffffa031339b>] btrfs_orphan_del > > +0x8d/0xa9 [btrfs] > > 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: > > 0000:ffff880111c21b58 EFLAGS: 00010282 > > 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: > > 00000000fffffffe RBX: ffff88011141bd60 RCX: 0000000000001000 > > 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: > > 000000000000000f RSI: ffff880110c8d250 RDI: ffff880134092d80 > > 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: > > 0000000000000000 R08: ffff880111c21a30 R09: 0000000000000011 > > 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: > > 00000000000001dc R11: 0000000000000286 R12: ffff880136866300 > > 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: > > ffff880130cac800 R14: 0000000000000001 R15: ffff880130cacb88 > > 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: > > 00007f126715a740(0000) GS:ffff88013bc80000(0000) knlGS:0000000000000000 > > 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 > > DS: 0000 ES: 0000 CR0: 000000008005003b > > 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: > > 00007f5149eb7008 CR3: 000000009caca000 CR4: 00000000000406e0 > > 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: > > 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 > > 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: > > 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 > > 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process > > btrfs (pid: 3167, threadinfo ffff880111c20000, task ffff88010b35a140) > > 2011-08-29T02:00:46.529316+01:00 picard kernel: [ 4155.444705] Stack: > > 2011-08-29T02:00:46.529318+01:00 picard kernel: [ 4155.444711] > > 0000000000c369c5 ffff880130cac800 ffff880110c8dbe0 0000000000c369c5 > > 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] > > 0000000000000000 ffff880130cacb88 ffff880130cacb90 ffffffffa03173c4 > > 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] > > ffff880136866300 ffff88011141bd60 ffff880110c8dbe0 fffb000000000000 > > 2011-08-29T02:00:46.562959+01:00 picard kernel: [ 4155.444739] Call > > Trace: > > 2011-08-29T02:00:46.562964+01:00 picard kernel: [ 4155.444756] > > [<ffffffffa03173c4>] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] > > 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] > > [<ffffffffa0317862>] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] > > 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] > > [<ffffffffa03178a2>] ? btrfs_lookup+0x9/0x1f [btrfs] > > 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] > > [<ffffffff810ccf63>] ? d_alloc_and_lookup+0x3a/0x60 > > 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] > > [<ffffffff810ce356>] ? walk_component+0x210/0x3b7 > > 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] > > [<ffffffff810cea4a>] ? path_lookupat+0x7c/0x29e > > 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] > > [<ffffffff810cfc34>] ? do_path_lookup+0x1c/0x87 > > 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] > > [<ffffffff810cffe2>] ? user_path_at+0x49/0x7b > > 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] > > [<ffffffff810c8781>] ? vfs_fstatat+0x3c/0x6a > > 2011-08-29T02:00:46.562986+01:00 picard kernel: [ 4155.444853] > > [<ffffffff8102d38e>] ? sub_preempt_count+0x83/0x94 > > 2011-08-29T02:00:46.562988+01:00 picard kernel: [ 4155.444861] > > [<ffffffff810c88ab>] ? sys_newstat+0x12/0x2b > > 2011-08-29T02:00:46.562990+01:00 picard kernel: [ 4155.444870] > > [<ffffffff81346d4f>] ? page_fault+0x1f/0x30 > > 2011-08-29T02:00:46.562992+01:00 picard kernel: [ 4155.444878] > > [<ffffffff8134713b>] ? system_call_fastpath+0x16/0x1b > > 2011-08-29T02:00:46.562995+01:00 picard kernel: [ 4155.444885] Code: 4d > > 85 e4 74 28 48 8b 93 70 fe ff ff 48 81 fa 00 01 00 00 77 07 48 8b 93 b0 > > 00 00 00 4c 89 ee 4c 89 e7 e8 af 28 02 00 85 c0 74 02 <0f> 0b 85 ed 74 > > 08 48 89 df e8 e0 ca fe ff 5d 31 c0 5b 5d 41 5c > > 2011-08-29T02:00:46.563001+01:00 picard kernel: [ 4155.444926] RIP > > [<ffffffffa031339b>] btrfs_orphan_del+0x8d/0xa9 [btrfs] > > 2011-08-29T02:00:46.563004+01:00 picard kernel: [ 4155.444943] RSP > > <ffff880111c21b58> > > 2011-08-29T02:00:46.563014+01:00 picard kernel: [ 4155.464057] ---[ end > > trace 276655044a13c7af ]--- > > > > Unfortunately it results in freeze of system and I cannot give more > > details. Sometimes it happens not from fcron but then it does not result > > in freeze (???). > > Could you give me the method to reproduce it? > > Thanks > Miao >Unfortunatly no. Today, when I haven''t worked at 2 am, it happened at 5 am resulting in freeze of desktop except screensaver (which probably don''t issue I/O to $HOME). It happens at least once per day but I neither know how to trigger it nor what is the underlying issue. HOWEVER, I did tried in the past to: - Backup the image on external harddrive - Loop-mount it - mkfs.btrfs /dev/my/partition - rsync -aHAXP back the contents and it didn''t solved the problem. Additionally very often restarts result in known OOPS bug. I do have the old image but I''ve just tried 3.1 kernel when you sent this message.> > > > Regards >Regards -- 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
On Tue, 2011-08-30 at 09:47 +0100, Maciej Marcin Piechotka wrote:> On Tue, 2011-08-30 at 14:27 +0800, Miao Xie wrote: > > On mon, 29 Aug 2011 02:45:07 +0100, Maciej Marcin Piechotka wrote: > > > I receive the bug when I try to snapshot from fcron: > > > > > > 2011-08-29T02:00:46.529238+01:00 picard kernel: [ 4155.444476] > > > ------------[ cut here ]------------ > > > 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.444490] kernel > > > BUG at fs/btrfs/inode.c:2299! > > > 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.444498] invalid > > > opcode: 0000 [#1] PREEMPT SMP > > > 2011-08-29T02:00:46.529259+01:00 picard kernel: [ 4155.444506] CPU 1 > > > 2011-08-29T02:00:46.529264+01:00 picard kernel: [ 4155.444508] Modules > > > linked in: microcode reiserfs btrfs zlib_deflate libcrc32c arc4 > > > snd_hda_codec_conexant iwlagn mac80211 uvcvideo videodev > > > v4l2_compat_ioctl32 cfg80211 snd_hda_intel snd_hda_codec snd_hwdep > > > snd_pcm snd_timer snd thinkpad_acpi soundcore snd_page_alloc hwmon > > > rfkill nvram e1000e zram(C) rtc_cmos tp_smapi thinkpad_ec psmouse > > > fscache kvm_intel kvm configs ipv6 autofs4 usbhid uhci_hcd firewire_ohci > > > firewire_core sdhci_pci ehci_hcd crc_itu_t sdhci mmc_core sd_mod usbcore > > > sr_mod cdrom > > > 2011-08-29T02:00:46.529267+01:00 picard kernel: [ 4155.444569] > > > 2011-08-29T02:00:46.529270+01:00 picard kernel: [ 4155.444576] Pid: > > > 3167, comm: btrfs Tainted: P C 3.0.3-gentoo #1 LENOVO > > > 2242CTO/2242CTO > > > 2011-08-29T02:00:46.529274+01:00 picard kernel: [ 4155.444586] RIP: > > > 0010:[<ffffffffa031339b>] [<ffffffffa031339b>] btrfs_orphan_del > > > +0x8d/0xa9 [btrfs] > > > 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: > > > 0000:ffff880111c21b58 EFLAGS: 00010282 > > > 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: > > > 00000000fffffffe RBX: ffff88011141bd60 RCX: 0000000000001000 > > > 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: > > > 000000000000000f RSI: ffff880110c8d250 RDI: ffff880134092d80 > > > 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: > > > 0000000000000000 R08: ffff880111c21a30 R09: 0000000000000011 > > > 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: > > > 00000000000001dc R11: 0000000000000286 R12: ffff880136866300 > > > 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: > > > ffff880130cac800 R14: 0000000000000001 R15: ffff880130cacb88 > > > 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: > > > 00007f126715a740(0000) GS:ffff88013bc80000(0000) knlGS:0000000000000000 > > > 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 > > > DS: 0000 ES: 0000 CR0: 000000008005003b > > > 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: > > > 00007f5149eb7008 CR3: 000000009caca000 CR4: 00000000000406e0 > > > 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: > > > 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 > > > 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: > > > 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 > > > 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process > > > btrfs (pid: 3167, threadinfo ffff880111c20000, task ffff88010b35a140) > > > 2011-08-29T02:00:46.529316+01:00 picard kernel: [ 4155.444705] Stack: > > > 2011-08-29T02:00:46.529318+01:00 picard kernel: [ 4155.444711] > > > 0000000000c369c5 ffff880130cac800 ffff880110c8dbe0 0000000000c369c5 > > > 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] > > > 0000000000000000 ffff880130cacb88 ffff880130cacb90 ffffffffa03173c4 > > > 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] > > > ffff880136866300 ffff88011141bd60 ffff880110c8dbe0 fffb000000000000 > > > 2011-08-29T02:00:46.562959+01:00 picard kernel: [ 4155.444739] Call > > > Trace: > > > 2011-08-29T02:00:46.562964+01:00 picard kernel: [ 4155.444756] > > > [<ffffffffa03173c4>] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] > > > 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] > > > [<ffffffffa0317862>] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] > > > 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] > > > [<ffffffffa03178a2>] ? btrfs_lookup+0x9/0x1f [btrfs] > > > 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] > > > [<ffffffff810ccf63>] ? d_alloc_and_lookup+0x3a/0x60 > > > 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] > > > [<ffffffff810ce356>] ? walk_component+0x210/0x3b7 > > > 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] > > > [<ffffffff810cea4a>] ? path_lookupat+0x7c/0x29e > > > 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] > > > [<ffffffff810cfc34>] ? do_path_lookup+0x1c/0x87 > > > 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] > > > [<ffffffff810cffe2>] ? user_path_at+0x49/0x7b > > > 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] > > > [<ffffffff810c8781>] ? vfs_fstatat+0x3c/0x6a > > > 2011-08-29T02:00:46.562986+01:00 picard kernel: [ 4155.444853] > > > [<ffffffff8102d38e>] ? sub_preempt_count+0x83/0x94 > > > 2011-08-29T02:00:46.562988+01:00 picard kernel: [ 4155.444861] > > > [<ffffffff810c88ab>] ? sys_newstat+0x12/0x2b > > > 2011-08-29T02:00:46.562990+01:00 picard kernel: [ 4155.444870] > > > [<ffffffff81346d4f>] ? page_fault+0x1f/0x30 > > > 2011-08-29T02:00:46.562992+01:00 picard kernel: [ 4155.444878] > > > [<ffffffff8134713b>] ? system_call_fastpath+0x16/0x1b > > > 2011-08-29T02:00:46.562995+01:00 picard kernel: [ 4155.444885] Code: 4d > > > 85 e4 74 28 48 8b 93 70 fe ff ff 48 81 fa 00 01 00 00 77 07 48 8b 93 b0 > > > 00 00 00 4c 89 ee 4c 89 e7 e8 af 28 02 00 85 c0 74 02 <0f> 0b 85 ed 74 > > > 08 48 89 df e8 e0 ca fe ff 5d 31 c0 5b 5d 41 5c > > > 2011-08-29T02:00:46.563001+01:00 picard kernel: [ 4155.444926] RIP > > > [<ffffffffa031339b>] btrfs_orphan_del+0x8d/0xa9 [btrfs] > > > 2011-08-29T02:00:46.563004+01:00 picard kernel: [ 4155.444943] RSP > > > <ffff880111c21b58> > > > 2011-08-29T02:00:46.563014+01:00 picard kernel: [ 4155.464057] ---[ end > > > trace 276655044a13c7af ]--- > > > > > > Unfortunately it results in freeze of system and I cannot give more > > > details. Sometimes it happens not from fcron but then it does not result > > > in freeze (???). > > > > Could you give me the method to reproduce it? > > > > Thanks > > Miao > > > > Unfortunatly no. Today, when I haven''t worked at 2 am, it happened at 5 > am resulting in freeze of desktop except screensaver (which probably > don''t issue I/O to $HOME). It happens at least once per day but I > neither know how to trigger it nor what is the underlying issue. > > HOWEVER, I did tried in the past to: > - Backup the image on external harddrive > - Loop-mount it > - mkfs.btrfs /dev/my/partition > - rsync -aHAXP back the contents > > and it didn''t solved the problem. > > Additionally very often restarts result in known OOPS bug. > > I do have the old image but I''ve just tried 3.1 kernel when you sent > this message. > > > > > > > Regards > > > > RegardsI reproduced it on 3.1 rc4. The behaviour is similar except it is trigger each time I log into account (NOT mount /home - log into account). It might have something in common with suspend. Regards
On Fri, 2011-09-02 at 12:18 +0100, Maciej Marcin Piechotka wrote:> On Tue, 2011-08-30 at 09:47 +0100, Maciej Marcin Piechotka wrote: > > On Tue, 2011-08-30 at 14:27 +0800, Miao Xie wrote: > > > On mon, 29 Aug 2011 02:45:07 +0100, Maciej Marcin Piechotka wrote: > > > > I receive the bug when I try to snapshot from fcron: > > > > > > > > 2011-08-29T02:00:46.529238+01:00 picard kernel: [ 4155.444476] > > > > ------------[ cut here ]------------ > > > > 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.444490] kernel > > > > BUG at fs/btrfs/inode.c:2299! > > > > 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.444498] invalid > > > > opcode: 0000 [#1] PREEMPT SMP > > > > 2011-08-29T02:00:46.529259+01:00 picard kernel: [ 4155.444506] CPU 1 > > > > 2011-08-29T02:00:46.529264+01:00 picard kernel: [ 4155.444508] Modules > > > > linked in: microcode reiserfs btrfs zlib_deflate libcrc32c arc4 > > > > snd_hda_codec_conexant iwlagn mac80211 uvcvideo videodev > > > > v4l2_compat_ioctl32 cfg80211 snd_hda_intel snd_hda_codec snd_hwdep > > > > snd_pcm snd_timer snd thinkpad_acpi soundcore snd_page_alloc hwmon > > > > rfkill nvram e1000e zram(C) rtc_cmos tp_smapi thinkpad_ec psmouse > > > > fscache kvm_intel kvm configs ipv6 autofs4 usbhid uhci_hcd firewire_ohci > > > > firewire_core sdhci_pci ehci_hcd crc_itu_t sdhci mmc_core sd_mod usbcore > > > > sr_mod cdrom > > > > 2011-08-29T02:00:46.529267+01:00 picard kernel: [ 4155.444569] > > > > 2011-08-29T02:00:46.529270+01:00 picard kernel: [ 4155.444576] Pid: > > > > 3167, comm: btrfs Tainted: P C 3.0.3-gentoo #1 LENOVO > > > > 2242CTO/2242CTO > > > > 2011-08-29T02:00:46.529274+01:00 picard kernel: [ 4155.444586] RIP: > > > > 0010:[<ffffffffa031339b>] [<ffffffffa031339b>] btrfs_orphan_del > > > > +0x8d/0xa9 [btrfs] > > > > 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: > > > > 0000:ffff880111c21b58 EFLAGS: 00010282 > > > > 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: > > > > 00000000fffffffe RBX: ffff88011141bd60 RCX: 0000000000001000 > > > > 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: > > > > 000000000000000f RSI: ffff880110c8d250 RDI: ffff880134092d80 > > > > 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: > > > > 0000000000000000 R08: ffff880111c21a30 R09: 0000000000000011 > > > > 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: > > > > 00000000000001dc R11: 0000000000000286 R12: ffff880136866300 > > > > 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: > > > > ffff880130cac800 R14: 0000000000000001 R15: ffff880130cacb88 > > > > 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: > > > > 00007f126715a740(0000) GS:ffff88013bc80000(0000) knlGS:0000000000000000 > > > > 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 > > > > DS: 0000 ES: 0000 CR0: 000000008005003b > > > > 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: > > > > 00007f5149eb7008 CR3: 000000009caca000 CR4: 00000000000406e0 > > > > 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: > > > > 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 > > > > 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: > > > > 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 > > > > 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process > > > > btrfs (pid: 3167, threadinfo ffff880111c20000, task ffff88010b35a140) > > > > 2011-08-29T02:00:46.529316+01:00 picard kernel: [ 4155.444705] Stack: > > > > 2011-08-29T02:00:46.529318+01:00 picard kernel: [ 4155.444711] > > > > 0000000000c369c5 ffff880130cac800 ffff880110c8dbe0 0000000000c369c5 > > > > 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] > > > > 0000000000000000 ffff880130cacb88 ffff880130cacb90 ffffffffa03173c4 > > > > 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] > > > > ffff880136866300 ffff88011141bd60 ffff880110c8dbe0 fffb000000000000 > > > > 2011-08-29T02:00:46.562959+01:00 picard kernel: [ 4155.444739] Call > > > > Trace: > > > > 2011-08-29T02:00:46.562964+01:00 picard kernel: [ 4155.444756] > > > > [<ffffffffa03173c4>] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] > > > > 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] > > > > [<ffffffffa0317862>] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] > > > > 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] > > > > [<ffffffffa03178a2>] ? btrfs_lookup+0x9/0x1f [btrfs] > > > > 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] > > > > [<ffffffff810ccf63>] ? d_alloc_and_lookup+0x3a/0x60 > > > > 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] > > > > [<ffffffff810ce356>] ? walk_component+0x210/0x3b7 > > > > 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] > > > > [<ffffffff810cea4a>] ? path_lookupat+0x7c/0x29e > > > > 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] > > > > [<ffffffff810cfc34>] ? do_path_lookup+0x1c/0x87 > > > > 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] > > > > [<ffffffff810cffe2>] ? user_path_at+0x49/0x7b > > > > 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] > > > > [<ffffffff810c8781>] ? vfs_fstatat+0x3c/0x6a > > > > 2011-08-29T02:00:46.562986+01:00 picard kernel: [ 4155.444853] > > > > [<ffffffff8102d38e>] ? sub_preempt_count+0x83/0x94 > > > > 2011-08-29T02:00:46.562988+01:00 picard kernel: [ 4155.444861] > > > > [<ffffffff810c88ab>] ? sys_newstat+0x12/0x2b > > > > 2011-08-29T02:00:46.562990+01:00 picard kernel: [ 4155.444870] > > > > [<ffffffff81346d4f>] ? page_fault+0x1f/0x30 > > > > 2011-08-29T02:00:46.562992+01:00 picard kernel: [ 4155.444878] > > > > [<ffffffff8134713b>] ? system_call_fastpath+0x16/0x1b > > > > 2011-08-29T02:00:46.562995+01:00 picard kernel: [ 4155.444885] Code: 4d > > > > 85 e4 74 28 48 8b 93 70 fe ff ff 48 81 fa 00 01 00 00 77 07 48 8b 93 b0 > > > > 00 00 00 4c 89 ee 4c 89 e7 e8 af 28 02 00 85 c0 74 02 <0f> 0b 85 ed 74 > > > > 08 48 89 df e8 e0 ca fe ff 5d 31 c0 5b 5d 41 5c > > > > 2011-08-29T02:00:46.563001+01:00 picard kernel: [ 4155.444926] RIP > > > > [<ffffffffa031339b>] btrfs_orphan_del+0x8d/0xa9 [btrfs] > > > > 2011-08-29T02:00:46.563004+01:00 picard kernel: [ 4155.444943] RSP > > > > <ffff880111c21b58> > > > > 2011-08-29T02:00:46.563014+01:00 picard kernel: [ 4155.464057] ---[ end > > > > trace 276655044a13c7af ]--- > > > > > > > > Unfortunately it results in freeze of system and I cannot give more > > > > details. Sometimes it happens not from fcron but then it does not result > > > > in freeze (???). > > > > > > Could you give me the method to reproduce it? > > > > > > Thanks > > > Miao > > > > > > > Unfortunatly no. Today, when I haven''t worked at 2 am, it happened at 5 > > am resulting in freeze of desktop except screensaver (which probably > > don''t issue I/O to $HOME). It happens at least once per day but I > > neither know how to trigger it nor what is the underlying issue. > > > > HOWEVER, I did tried in the past to: > > - Backup the image on external harddrive > > - Loop-mount it > > - mkfs.btrfs /dev/my/partition > > - rsync -aHAXP back the contents > > > > and it didn''t solved the problem. > > > > Additionally very often restarts result in known OOPS bug. > > > > I do have the old image but I''ve just tried 3.1 kernel when you sent > > this message. > > > > > > > > > > Regards > > > > > > > Regards > > I reproduced it on 3.1 rc4. The behaviour is similar except it is > trigger each time I log into account (NOT mount /home - log into > account). > > It might have something in common with suspend. > > Regards >To keep up-to-date: - It happens at 2 am it results in freeze. To be more precise the snapshot is scheduled at 2 am and the lock was 2:07 am. The after each odd reboot the bug is triggered on boot and on each even boot the computer hangs shortly after logging in. I can still rsync to separate partition w/out problems in case of even boot. - If it happens at other time (for certain reasons I haven''t reproduced it with 3.1 - long story why not but I suspect it might correlate to uptime) the bug may or may not freeze computer. Regards
On Tue, 2011-08-30 at 14:27 +0800, Miao Xie wrote:> On mon, 29 Aug 2011 02:45:07 +0100, Maciej Marcin Piechotka wrote: > > I receive the bug when I try to snapshot from fcron: > > > > 2011-08-29T02:00:46.529238+01:00 picard kernel: [ 4155.444476] > > ------------[ cut here ]------------ > > 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.444490] kernel > > BUG at fs/btrfs/inode.c:2299! > > 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.444498] invalid > > opcode: 0000 [#1] PREEMPT SMP > > 2011-08-29T02:00:46.529259+01:00 picard kernel: [ 4155.444506] CPU 1 > > 2011-08-29T02:00:46.529264+01:00 picard kernel: [ 4155.444508] Modules > > linked in: microcode reiserfs btrfs zlib_deflate libcrc32c arc4 > > snd_hda_codec_conexant iwlagn mac80211 uvcvideo videodev > > v4l2_compat_ioctl32 cfg80211 snd_hda_intel snd_hda_codec snd_hwdep > > snd_pcm snd_timer snd thinkpad_acpi soundcore snd_page_alloc hwmon > > rfkill nvram e1000e zram(C) rtc_cmos tp_smapi thinkpad_ec psmouse > > fscache kvm_intel kvm configs ipv6 autofs4 usbhid uhci_hcd firewire_ohci > > firewire_core sdhci_pci ehci_hcd crc_itu_t sdhci mmc_core sd_mod usbcore > > sr_mod cdrom > > 2011-08-29T02:00:46.529267+01:00 picard kernel: [ 4155.444569] > > 2011-08-29T02:00:46.529270+01:00 picard kernel: [ 4155.444576] Pid: > > 3167, comm: btrfs Tainted: P C 3.0.3-gentoo #1 LENOVO > > 2242CTO/2242CTO > > 2011-08-29T02:00:46.529274+01:00 picard kernel: [ 4155.444586] RIP: > > 0010:[<ffffffffa031339b>] [<ffffffffa031339b>] btrfs_orphan_del > > +0x8d/0xa9 [btrfs] > > 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: > > 0000:ffff880111c21b58 EFLAGS: 00010282 > > 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: > > 00000000fffffffe RBX: ffff88011141bd60 RCX: 0000000000001000 > > 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: > > 000000000000000f RSI: ffff880110c8d250 RDI: ffff880134092d80 > > 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: > > 0000000000000000 R08: ffff880111c21a30 R09: 0000000000000011 > > 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: > > 00000000000001dc R11: 0000000000000286 R12: ffff880136866300 > > 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: > > ffff880130cac800 R14: 0000000000000001 R15: ffff880130cacb88 > > 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: > > 00007f126715a740(0000) GS:ffff88013bc80000(0000) knlGS:0000000000000000 > > 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 > > DS: 0000 ES: 0000 CR0: 000000008005003b > > 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: > > 00007f5149eb7008 CR3: 000000009caca000 CR4: 00000000000406e0 > > 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: > > 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 > > 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: > > 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 > > 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process > > btrfs (pid: 3167, threadinfo ffff880111c20000, task ffff88010b35a140) > > 2011-08-29T02:00:46.529316+01:00 picard kernel: [ 4155.444705] Stack: > > 2011-08-29T02:00:46.529318+01:00 picard kernel: [ 4155.444711] > > 0000000000c369c5 ffff880130cac800 ffff880110c8dbe0 0000000000c369c5 > > 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] > > 0000000000000000 ffff880130cacb88 ffff880130cacb90 ffffffffa03173c4 > > 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] > > ffff880136866300 ffff88011141bd60 ffff880110c8dbe0 fffb000000000000 > > 2011-08-29T02:00:46.562959+01:00 picard kernel: [ 4155.444739] Call > > Trace: > > 2011-08-29T02:00:46.562964+01:00 picard kernel: [ 4155.444756] > > [<ffffffffa03173c4>] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] > > 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] > > [<ffffffffa0317862>] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] > > 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] > > [<ffffffffa03178a2>] ? btrfs_lookup+0x9/0x1f [btrfs] > > 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] > > [<ffffffff810ccf63>] ? d_alloc_and_lookup+0x3a/0x60 > > 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] > > [<ffffffff810ce356>] ? walk_component+0x210/0x3b7 > > 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] > > [<ffffffff810cea4a>] ? path_lookupat+0x7c/0x29e > > 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] > > [<ffffffff810cfc34>] ? do_path_lookup+0x1c/0x87 > > 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] > > [<ffffffff810cffe2>] ? user_path_at+0x49/0x7b > > 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] > > [<ffffffff810c8781>] ? vfs_fstatat+0x3c/0x6a > > 2011-08-29T02:00:46.562986+01:00 picard kernel: [ 4155.444853] > > [<ffffffff8102d38e>] ? sub_preempt_count+0x83/0x94 > > 2011-08-29T02:00:46.562988+01:00 picard kernel: [ 4155.444861] > > [<ffffffff810c88ab>] ? sys_newstat+0x12/0x2b > > 2011-08-29T02:00:46.562990+01:00 picard kernel: [ 4155.444870] > > [<ffffffff81346d4f>] ? page_fault+0x1f/0x30 > > 2011-08-29T02:00:46.562992+01:00 picard kernel: [ 4155.444878] > > [<ffffffff8134713b>] ? system_call_fastpath+0x16/0x1b > > 2011-08-29T02:00:46.562995+01:00 picard kernel: [ 4155.444885] Code: 4d > > 85 e4 74 28 48 8b 93 70 fe ff ff 48 81 fa 00 01 00 00 77 07 48 8b 93 b0 > > 00 00 00 4c 89 ee 4c 89 e7 e8 af 28 02 00 85 c0 74 02 <0f> 0b 85 ed 74 > > 08 48 89 df e8 e0 ca fe ff 5d 31 c0 5b 5d 41 5c > > 2011-08-29T02:00:46.563001+01:00 picard kernel: [ 4155.444926] RIP > > [<ffffffffa031339b>] btrfs_orphan_del+0x8d/0xa9 [btrfs] > > 2011-08-29T02:00:46.563004+01:00 picard kernel: [ 4155.444943] RSP > > <ffff880111c21b58> > > 2011-08-29T02:00:46.563014+01:00 picard kernel: [ 4155.464057] ---[ end > > trace 276655044a13c7af ]--- > > > > Unfortunately it results in freeze of system and I cannot give more > > details. Sometimes it happens not from fcron but then it does not result > > in freeze (???). > > Could you give me the method to reproduce it? > > Thanks > MiaoI still have no idea how to reproduce it but: a) It happens on both clean 3.1 kernels as on 3.0 with various patches b) The snapshotting is not a cause (it was coincident) c) It usually happens a few minutes past 3 UTC. I have no idea what is casuing this but it is consistent. d) Compression is not a cause Best regards
On Fri, 09 Sep 2011 02:13:38 +0100 Maciej Marcin Piechotka <uzytkownik2@gmail.com> wrote:> c) It usually happens a few minutes past 3 UTC. I have no idea what is > casuing this but it is consistent.Did you check if your /etc/crontab or /etc/cron.d/* have anything scheduled for that time? -- With respect, Roman
On Fri, 2011-09-09 at 09:02 +0600, Roman Mamedov wrote:> On Fri, 09 Sep 2011 02:13:38 +0100 > Maciej Marcin Piechotka <uzytkownik2@gmail.com> wrote: > > > c) It usually happens a few minutes past 3 UTC. I have no idea what is > > casuing this but it is consistent. > > Did you check if your /etc/crontab or /etc/cron.d/* have anything scheduled for that time? >It does: - logrotate (configured to do it weekly in /etc/logrotate) - makewhatis -u Neither of which should touch /home. Before it snapshotted the volume and I thought it was the direct cause. Best regards
1. Once the blank screen happened ot 23:00 UTC instead of 03:00 UTC 2. I tried to disable the caches 3. I tried to rsync via ext3 + btrfs-convert. I noticed something - in old fs the df looked like: Data: total=30.01GB, used=28.42GB System, DUP: total=8.00MB, used=4.00KB System: total=4.00MB, used=0.00 Metadata, DUP: total=1.00GB, used=199.47MB Metadata: total=8.00MB, used=0.00 on new one (with ext3 image): Data: total=33.33GB, used=20.52GB System: total=32.00MB, used=4.00KB Metadata: total=16.64GB, used=11.36GB and without: Data: total=33.33GB, used=20.04GB System: total=32.00MB, used=4.00KB Metadata: total=16.64GB, used=10.88GB Given that lzo compression was used about 4 GB data difference was expected (difference depending on mount options on rsynbc). However: 1. What is DUP? 2. Why metadata is so big on convertion from ext3 while data is small (the sum is about right - 31GB vs 28.5GB)? Regards
On Sat, 2011-09-17 at 20:25 +0200, Maciej Marcin Piechotka wrote:> 1. Once the blank screen happened ot 23:00 UTC instead of 03:00 UTC > 2. I tried to disable the caches > 3. I tried to rsync via ext3 + btrfs-convert. I noticed something - in > old fs the df looked like: > > Data: total=30.01GB, used=28.42GB > System, DUP: total=8.00MB, used=4.00KB > System: total=4.00MB, used=0.00 > Metadata, DUP: total=1.00GB, used=199.47MB > Metadata: total=8.00MB, used=0.00 > > on new one (with ext3 image): > Data: total=33.33GB, used=20.52GB > System: total=32.00MB, used=4.00KB > Metadata: total=16.64GB, used=11.36GB > > and without: > Data: total=33.33GB, used=20.04GB > System: total=32.00MB, used=4.00KB > Metadata: total=16.64GB, used=10.88GB > > Given that lzo compression was used about 4 GB data difference was > expected (difference depending on mount options on rsynbc). However: > > 1. What is DUP? > 2. Why metadata is so big on convertion from ext3 while data is small > (the sum is about right - 31GB vs 28.5GB)? >That should be a) b) 4. I think it triggered a bug with data loss - the new data was not written to disk despite being visible from userspace after mount during the same mount.> Regards
On Sat, 2011-09-17 at 20:30 +0200, Maciej Marcin Piechotka wrote:> On Sat, 2011-09-17 at 20:25 +0200, Maciej Marcin Piechotka wrote: > > 1. Once the blank screen happened ot 23:00 UTC instead of 03:00 UTC > > 2. I tried to disable the caches > > 3. I tried to rsync via ext3 + btrfs-convert. I noticed something - in > > old fs the df looked like: > > > > Data: total=30.01GB, used=28.42GB > > System, DUP: total=8.00MB, used=4.00KB > > System: total=4.00MB, used=0.00 > > Metadata, DUP: total=1.00GB, used=199.47MB > > Metadata: total=8.00MB, used=0.00 > > > > on new one (with ext3 image): > > Data: total=33.33GB, used=20.52GB > > System: total=32.00MB, used=4.00KB > > Metadata: total=16.64GB, used=11.36GB > > > > and without: > > Data: total=33.33GB, used=20.04GB > > System: total=32.00MB, used=4.00KB > > Metadata: total=16.64GB, used=10.88GB > > > > Given that lzo compression was used about 4 GB data difference was > > expected (difference depending on mount options on rsynbc). However: > > > > 1. What is DUP?Ok.After some digging I found out.> > 2. Why metadata is so big on convertion from ext3 while data is small > > (the sum is about right - 31GB vs 28.5GB)? > >After rebalancing I got: Data: total=32.00GB, used=30.83GB System: total=32.00MB, used=12.00KB Metadata: total=1.00GB, used=227.16MB> > That should be a) b) > > 4. I think it triggered a bug with data loss - the new data was not > written to disk despite being visible from userspace after mount during > the same mount. > > > Regards > >
On Tue, 2011-08-30 at 14:27 +0800, Miao Xie wrote:> > > > Unfortunately it results in freeze of system and I cannot give more > > details. Sometimes it happens not from fcron but then it does not result > > in freeze (???). > > Could you give me the method to reproduce it? > > Thanks > MiaoSorry for spamming in this thread but I''m trying to post my findings in hope that somebody will understand what''s going on. Recent crash gave some valuable information IMHO: 1. I started the autocompletion of path in zsh 2. At some point the zsh hanged. In ps the process was listed as runnable 3. Any access to root volume (the one that zsh was trying to readdir) finished in hang. 4. I was able to access the child volume (/home) 5. After some time the bug is hit. At this time strange things happens (screen freeze etc.). I guess that there is some strange interaction between KMS, X and now-hanged composite manager Next time it happend (also during listing root directory of volume 0) I observed the following thing - I can log out and unmount home but the volume 0 remains busy and cannot be unmounted. Things to consider: - It is not enabled/disabled by any mount option - Is it triggered when the parent volume (say volume 0) and child volume are both mounted? - Which case is it failing (I''ve tried to add printk but I cannot find the option in printk to print u64) - Why it happens only during night? Regards -- 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
On Mon, 2011-09-19 at 02:44 +0200, Maciej Marcin Piechotka wrote:> On Tue, 2011-08-30 at 14:27 +0800, Miao Xie wrote: > > > > > > Unfortunately it results in freeze of system and I cannot give more > > > details. Sometimes it happens not from fcron but then it does not result > > > in freeze (???). > > > > Could you give me the method to reproduce it? > > > > Thanks > > Miao > > Sorry for spamming in this thread but I''m trying to post my findings in > hope that somebody will understand what''s going on. > > Recent crash gave some valuable information IMHO: > > 1. I started the autocompletion of path in zsh > 2. At some point the zsh hanged. In ps the process was listed as > runnable > 3. Any access to root volume (the one that zsh was trying to readdir) > finished in hang. > 4. I was able to access the child volume (/home) > 5. After some time the bug is hit. At this time strange things happens > (screen freeze etc.). I guess that there is some strange interaction > between KMS, X and now-hanged composite manager > > Next time it happend (also during listing root directory of volume 0) I > observed the following thing - I can log out and unmount home but the > volume 0 remains busy and cannot be unmounted. > > Things to consider: > > - It is not enabled/disabled by any mount option > - Is it triggered when the parent volume (say volume 0) and child > volume are both mounted?I cannot reproduce it when the parent volume is not mounted (snapshots are to subvolume)> - Which case is it failing (I''ve tried to add printk but I cannot find > the option in printk to print u64) > - Why it happens only during night? > > RegardsRegards