kernel BUG at fs/btrfs/inode.c:2299
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
Quoted 93 lines · Maciej Marcin Piechotka
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] Moduleslinked in: microcode reiserfs btrfs
zlib_deflatelibcrc32c arc4snd_hda_codec_conexantiwlagn mac80211 uvcvideo videodevv4l2_compat_ioctl32cfg80211snd_hda_intelsnd_hda_codecsnd_hwdepsnd_pcmsnd_timersndthinkpad_acpisoundcoresnd_page_allochwmon rfkill nvram e1000e zram(C)rtc_cmostp_smapithinkpad_ecpsmouse fscachekvm_intelkvm configs ipv6 autofs4 usbhiduhci_hcdfirewire_ohcifirewire_coresdhci_pciehci_hcdcrc_itu_tsdhcimmc_coresd_modusbcoresr_modcdrom2011-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
Quoted 5 lines
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
Quoted 2 lines
Regards
Quoted 96 lines · Miao Xie
Quoted 95 lines · Maciej Marcin Piechotka
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] Moduleslinked in: microcode reiserfs btrfs
zlib_deflatelibcrc32c arc4snd_hda_codec_conexantiwlagn mac80211 uvcvideo videodevv4l2_compat_ioctl32cfg80211snd_hda_intelsnd_hda_codecsnd_hwdepsnd_pcmsnd_timersndthinkpad_acpisoundcoresnd_page_allochwmon rfkill nvram e1000e zram(C)rtc_cmostp_smapithinkpad_ecpsmouse fscachekvm_intelkvm configs ipv6 autofs4 usbhiduhci_hcdfirewire_ohcifirewire_coresdhci_pciehci_hcdcrc_itu_tsdhcimmc_coresd_modusbcoresr_modcdrom2011-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] ---[ endtrace
276655044a13c7af]---
Quoted 9 lines
Quoted 3 lines
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.
Quoted 3 lines
Quoted 2 lines
Regards
Regards
Quoted 122 lines · Maciej Marcin Piechotka
Quoted 96 lines · Miao Xie
Quoted 95 lines · Maciej Marcin Piechotka
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] Moduleslinked in: microcode reiserfs btrfs
zlib_deflatelibcrc32c arc4snd_hda_codec_conexantiwlagn mac80211 uvcvideo videodevv4l2_compat_ioctl32cfg80211snd_hda_intelsnd_hda_codecsnd_hwdepsnd_pcmsnd_timersndthinkpad_acpisoundcoresnd_page_allochwmon rfkill nvram e1000e zram(C)rtc_cmostp_smapithinkpad_ecpsmouse fscachekvm_intelkvm configs ipv6 autofs4 usbhiduhci_hcdfirewire_ohcifirewire_coresdhci_pciehci_hcdcrc_itu_tsdhcimmc_coresd_modusbcoresr_modcdrom2011-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] ---[ endtrace
276655044a13c7af]---Quoted 9 lines
Quoted 3 lines
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
MiaoUnfortunatly 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 contentsand it didn't solved the problem.
Additionally very often restarts result in known OOPS bug.
Quoted 8 lines
I do have the old image but I've just tried 3.1 kernel when you sent this message.
Quoted 3 lines
Quoted 2 lines
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
Quoted 132 lines · Maciej Marcin Piechotka
Quoted 122 lines · Maciej Marcin Piechotka
Quoted 96 lines · Miao Xie
Quoted 95 lines · Maciej Marcin Piechotka
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] Moduleslinked in: microcode reiserfs btrfs
zlib_deflatelibcrc32c arc4snd_hda_codec_conexantiwlagn mac80211 uvcvideo videodevv4l2_compat_ioctl32cfg80211snd_hda_intelsnd_hda_codecsnd_hwdepsnd_pcmsnd_timersndthinkpad_acpisoundcoresnd_page_allochwmon rfkill nvram e1000e zram(C)rtc_cmostp_smapithinkpad_ecpsmouse fscachekvm_intelkvm configs ipv6 autofs4 usbhiduhci_hcdfirewire_ohcifirewire_coresdhci_pciehci_hcdcrc_itu_tsdhcimmc_coresd_modusbcoresr_modcdrom2011-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] ---[ endtrace
276655044a13c7af]---Quoted 9 lines
Quoted 3 lines
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
MiaoUnfortunatly 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 contentsand it didn't solved the problem.
Additionally very often restarts result in known OOPS bug.
Quoted 8 lines
I do have the old image but I've just tried 3.1 kernel when you sent this message.
Quoted 3 lines
Quoted 2 lines
Regards
Regards
Quoted 8 lines
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
Quoted 96 lines · Miao Xie
Quoted 95 lines · Maciej Marcin Piechotka
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] Moduleslinked in: microcode reiserfs btrfs
zlib_deflatelibcrc32c arc4snd_hda_codec_conexantiwlagn mac80211 uvcvideo videodevv4l2_compat_ioctl32cfg80211snd_hda_intelsnd_hda_codecsnd_hwdepsnd_pcmsnd_timersndthinkpad_acpisoundcoresnd_page_allochwmon rfkill nvram e1000e zram(C)rtc_cmostp_smapithinkpad_ecpsmouse fscachekvm_intelkvm configs ipv6 autofs4 usbhiduhci_hcdfirewire_ohcifirewire_coresdhci_pciehci_hcdcrc_itu_tsdhcimmc_coresd_modusbcoresr_modcdrom2011-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] ---[ endtrace
276655044a13c7af]---
Quoted 8 lines
Quoted 3 lines
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
I 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
Quoted 2 lines · On Fri, 09 Sep 2011 02:13:38 +0100 Maciej Marcin Piechotka…
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?
Signature · 2 lines
With respect, Roman
Quoted 8 lines · Roman Mamedov
Quoted 2 lines · On Fri, 09 Sep 2011 02:13:38 +0100 Maciej Marcin Piechotka…
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/crontabor/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
Quoted 9 lines · Miao Xie
Quoted 4 lines
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?
- 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
Quoted 30 lines · Maciej Marcin Piechotka
Quoted 9 lines · Miao Xie
Quoted 4 lines
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 managerNext 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.
Quoted 5 lines
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)
Quoted 5 lines
- 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
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
Quoted 21 lines · Maciej Marcin Piechotka
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.00on 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.36GBand without:
Data: total=33.33GB, used=20.04GB
System: total=32.00MB, used=4.00KB
Metadata: total=16.64GB, used=10.88GB
Quoted 7 lines
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.
Quoted 1 line
Regards
Quoted 22 lines · Maciej Marcin Piechotka
Quoted 21 lines · Maciej Marcin Piechotka
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.00on 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.36GBand without:
Data: total=33.33GB, used=20.04GB
System: total=32.00MB, used=4.00KB
Metadata: total=16.64GB, used=10.88GB
Quoted 4 lines
Quoted 4 lines
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.
Quoted 3 lines
Quoted 3 lines
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
Quoted 10 lines
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.
Quoted 1 line
Regards