Re: kernel BUG at fs/btrfs/inode.c:2299
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? Regards Regards signature.asc Description: This is a digitally signed message part
Re: kernel BUG at fs/btrfs/inode.c:2299
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? - 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 majord...@vger.kernel.org More majordomo info at http://vger.kernel.org/majordomo-info.html
Re: kernel BUG at fs/btrfs/inode.c:2299
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 signature.asc Description: This is a digitally signed message part
Re: kernel BUG at fs/btrfs/inode.c:2299
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 signature.asc Description: This is a digitally signed message part
Re: kernel BUG at fs/btrfs/inode.c:2299
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 signature.asc Description: This is a digitally signed message part
Re: kernel BUG at fs/btrfs/inode.c:2299
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.76] [ cut here ] 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.90] kernel BUG at fs/btrfs/inode.c:2299! 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.98] invalid opcode: [#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:[a031339b] [a031339b] btrfs_orphan_del +0x8d/0xa9 [btrfs] 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: :880111c21b58 EFLAGS: 00010282 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: fffe RBX: 88011141bd60 RCX: 1000 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: 000f RSI: 880110c8d250 RDI: 880134092d80 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: R08: 880111c21a30 R09: 0011 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: 01dc R11: 0286 R12: 880136866300 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: 880130cac800 R14: 0001 R15: 880130cacb88 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: 7f126715a740() GS:88013bc8() knlGS: 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 DS: ES: CR0: 8005003b 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: 7f5149eb7008 CR3: 9caca000 CR4: 000406e0 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: DR1: DR2: 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: DR6: 0ff0 DR7: 0400 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process btrfs (pid: 3167, threadinfo 880111c2, task 88010b35a140) 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] 00c369c5 880130cac800 880110c8dbe0 00c369c5 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] 880130cacb88 880130cacb90 a03173c4 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] 880136866300 88011141bd60 880110c8dbe0 fffb 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] [a03173c4] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] [a0317862] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] [a03178a2] ? btrfs_lookup+0x9/0x1f [btrfs] 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] [810ccf63] ? d_alloc_and_lookup+0x3a/0x60 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] [810ce356] ? walk_component+0x210/0x3b7 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] [810cea4a] ? path_lookupat+0x7c/0x29e 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] [810cfc34] ? do_path_lookup+0x1c/0x87 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] [810cffe2] ? user_path_at+0x49/0x7b 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] [810c8781] ? vfs_fstatat+0x3c/0x6a 2011-08-29T02:00:46.562986+01:00 picard kernel: [ 4155.444853] [8102d38e] ? sub_preempt_count+0x83/0x94 2011-08-29T02:00:46.562988+01:00 picard kernel: [ 4155.444861] [810c88ab] ? sys_newstat+0x12/0x2b 2011-08-29T02:00:46.562990+01:00 picard kernel: [ 4155.444870]
Re: kernel BUG at fs/btrfs/inode.c:2299
On Fri, 09 Sep 2011 02:13:38 +0100 Maciej Marcin Piechotka uzytkown...@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 signature.asc Description: PGP signature
Re: kernel BUG at fs/btrfs/inode.c:2299
On Fri, 2011-09-09 at 09:02 +0600, Roman Mamedov wrote: On Fri, 09 Sep 2011 02:13:38 +0100 Maciej Marcin Piechotka uzytkown...@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 signature.asc Description: This is a digitally signed message part
Re: kernel BUG at fs/btrfs/inode.c:2299
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.76] [ cut here ] 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.90] kernel BUG at fs/btrfs/inode.c:2299! 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.98] invalid opcode: [#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:[a031339b] [a031339b] btrfs_orphan_del +0x8d/0xa9 [btrfs] 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: :880111c21b58 EFLAGS: 00010282 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: fffe RBX: 88011141bd60 RCX: 1000 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: 000f RSI: 880110c8d250 RDI: 880134092d80 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: R08: 880111c21a30 R09: 0011 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: 01dc R11: 0286 R12: 880136866300 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: 880130cac800 R14: 0001 R15: 880130cacb88 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: 7f126715a740() GS:88013bc8() knlGS: 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 DS: ES: CR0: 8005003b 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: 7f5149eb7008 CR3: 9caca000 CR4: 000406e0 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: DR1: DR2: 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: DR6: 0ff0 DR7: 0400 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process btrfs (pid: 3167, threadinfo 880111c2, task 88010b35a140) 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] 00c369c5 880130cac800 880110c8dbe0 00c369c5 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] 880130cacb88 880130cacb90 a03173c4 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] 880136866300 88011141bd60 880110c8dbe0 fffb 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] [a03173c4] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] [a0317862] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] [a03178a2] ? btrfs_lookup+0x9/0x1f [btrfs] 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] [810ccf63] ? d_alloc_and_lookup+0x3a/0x60 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] [810ce356] ? walk_component+0x210/0x3b7 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] [810cea4a] ? path_lookupat+0x7c/0x29e 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] [810cfc34] ? do_path_lookup+0x1c/0x87 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] [810cffe2] ? user_path_at+0x49/0x7b 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] [810c8781] ? vfs_fstatat+0x3c/0x6a
Re: kernel BUG at fs/btrfs/inode.c:2299
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.76] [ cut here ] 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.90] kernel BUG at fs/btrfs/inode.c:2299! 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.98] invalid opcode: [#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:[a031339b] [a031339b] btrfs_orphan_del +0x8d/0xa9 [btrfs] 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: :880111c21b58 EFLAGS: 00010282 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: fffe RBX: 88011141bd60 RCX: 1000 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: 000f RSI: 880110c8d250 RDI: 880134092d80 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: R08: 880111c21a30 R09: 0011 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: 01dc R11: 0286 R12: 880136866300 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: 880130cac800 R14: 0001 R15: 880130cacb88 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: 7f126715a740() GS:88013bc8() knlGS: 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 DS: ES: CR0: 8005003b 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: 7f5149eb7008 CR3: 9caca000 CR4: 000406e0 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: DR1: DR2: 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: DR6: 0ff0 DR7: 0400 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process btrfs (pid: 3167, threadinfo 880111c2, task 88010b35a140) 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] 00c369c5 880130cac800 880110c8dbe0 00c369c5 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] 880130cacb88 880130cacb90 a03173c4 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] 880136866300 88011141bd60 880110c8dbe0 fffb 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] [a03173c4] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] [a0317862] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] [a03178a2] ? btrfs_lookup+0x9/0x1f [btrfs] 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] [810ccf63] ? d_alloc_and_lookup+0x3a/0x60 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] [810ce356] ? walk_component+0x210/0x3b7 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] [810cea4a] ? path_lookupat+0x7c/0x29e 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] [810cfc34] ? do_path_lookup+0x1c/0x87 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] [810cffe2] ? user_path_at+0x49/0x7b 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] [810c8781] ? vfs_fstatat+0x3c/0x6a 2011-08-29T02:00:46.562986+01:00 picard kernel: [ 4155.444853] [8102d38e] ? sub_preempt_count+0x83/0x94 2011-08-29T02:00:46.562988+01:00
Re: kernel BUG at fs/btrfs/inode.c:2299
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.76] [ cut here ] 2011-08-29T02:00:46.529253+01:00 picard kernel: [ 4155.90] kernel BUG at fs/btrfs/inode.c:2299! 2011-08-29T02:00:46.529256+01:00 picard kernel: [ 4155.98] invalid opcode: [#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:[a031339b] [a031339b] btrfs_orphan_del +0x8d/0xa9 [btrfs] 2011-08-29T02:00:46.529276+01:00 picard kernel: [ 4155.444611] RSP: :880111c21b58 EFLAGS: 00010282 2011-08-29T02:00:46.529279+01:00 picard kernel: [ 4155.444619] RAX: fffe RBX: 88011141bd60 RCX: 1000 2011-08-29T02:00:46.529289+01:00 picard kernel: [ 4155.444626] RDX: 000f RSI: 880110c8d250 RDI: 880134092d80 2011-08-29T02:00:46.529292+01:00 picard kernel: [ 4155.444634] RBP: R08: 880111c21a30 R09: 0011 2011-08-29T02:00:46.529297+01:00 picard kernel: [ 4155.444642] R10: 01dc R11: 0286 R12: 880136866300 2011-08-29T02:00:46.529299+01:00 picard kernel: [ 4155.444650] R13: 880130cac800 R14: 0001 R15: 880130cacb88 2011-08-29T02:00:46.529302+01:00 picard kernel: [ 4155.444658] FS: 7f126715a740() GS:88013bc8() knlGS: 2011-08-29T02:00:46.529304+01:00 picard kernel: [ 4155.444666] CS: 0010 DS: ES: CR0: 8005003b 2011-08-29T02:00:46.529307+01:00 picard kernel: [ 4155.444673] CR2: 7f5149eb7008 CR3: 9caca000 CR4: 000406e0 2011-08-29T02:00:46.529309+01:00 picard kernel: [ 4155.444681] DR0: DR1: DR2: 2011-08-29T02:00:46.529311+01:00 picard kernel: [ 4155.444689] DR3: DR6: 0ff0 DR7: 0400 2011-08-29T02:00:46.529314+01:00 picard kernel: [ 4155.444697] Process btrfs (pid: 3167, threadinfo 880111c2, task 88010b35a140) 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] 00c369c5 880130cac800 880110c8dbe0 00c369c5 2011-08-29T02:00:46.529321+01:00 picard kernel: [ 4155.444720] 880130cacb88 880130cacb90 a03173c4 2011-08-29T02:00:46.562822+01:00 picard kernel: [ 4155.444730] 880136866300 88011141bd60 880110c8dbe0 fffb 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] [a03173c4] ? btrfs_orphan_cleanup+0x196/0x2d9 [btrfs] 2011-08-29T02:00:46.562966+01:00 picard kernel: [ 4155.444773] [a0317862] ? btrfs_lookup_dentry+0x35b/0x392 [btrfs] 2011-08-29T02:00:46.562969+01:00 picard kernel: [ 4155.444790] [a03178a2] ? btrfs_lookup+0x9/0x1f [btrfs] 2011-08-29T02:00:46.562971+01:00 picard kernel: [ 4155.444801] [810ccf63] ? d_alloc_and_lookup+0x3a/0x60 2011-08-29T02:00:46.562973+01:00 picard kernel: [ 4155.444810] [810ce356] ? walk_component+0x210/0x3b7 2011-08-29T02:00:46.562975+01:00 picard kernel: [ 4155.444818] [810cea4a] ? path_lookupat+0x7c/0x29e 2011-08-29T02:00:46.562979+01:00 picard kernel: [ 4155.444827] [810cfc34] ? do_path_lookup+0x1c/0x87 2011-08-29T02:00:46.562981+01:00 picard kernel: [ 4155.444835] [810cffe2] ? user_path_at+0x49/0x7b 2011-08-29T02:00:46.562983+01:00 picard kernel: [ 4155.444843] [810c8781] ? vfs_fstatat+0x3c/0x6a 2011-08-29T02:00:46.562986+01:00 picard kernel: [ 4155.444853] [8102d38e] ? sub_preempt_count+0x83/0x94 2011-08-29T02:00:46.562988+01:00 picard kernel: [ 4155.444861] [810c88ab] ? sys_newstat+0x12/0x2b 2011-08-29T02:00:46.562990+01:00 picard kernel: [ 4155.444870]