Bug 893854
| Summary: | [abrt]: WARNING: at fs/btrfs/tree-log.c:3716 btrfs_log_inode_parent+0x3f8/0x450 [btrfs]() | ||
|---|---|---|---|
| Product: | [Fedora] Fedora | Reporter: | Amit Saha <asaha> |
| Component: | kernel | Assignee: | Zach Brown <zab> |
| Status: | CLOSED UPSTREAM | QA Contact: | Fedora Extras Quality Assurance <extras-qa> |
| Severity: | unspecified | Docs Contact: | |
| Priority: | unspecified | ||
| Version: | 18 | CC: | amcnabb, bugzilla.redhat.com, ebaak, fedora-kernel-btrfs, gansalmon, itamar, jeff, john5342, johnhughes, jonathan, josef, kernel-maint, madhu.chinakonda, paulbsch, peter.meier, sweil, wwoods, yasushi.shoji |
| Target Milestone: | --- | Keywords: | Triaged |
| Target Release: | --- | ||
| Hardware: | x86_64 | ||
| OS: | Unspecified | ||
| Whiteboard: | abrt_hash:baa3c676c57114e2c887e47cdc71ff89b6c9dec3 | ||
| Fixed In Version: | Doc Type: | Bug Fix | |
| Doc Text: | Story Points: | --- | |
| Clone Of: | Environment: | ||
| Last Closed: | 2013-09-23 20:37:49 UTC | Type: | --- |
| Regression: | --- | Mount Type: | --- |
| Documentation: | --- | CRM: | |
| Verified Versions: | Category: | --- | |
| oVirt Team: | --- | RHEL 7.3 requirements from Atomic Host: | |
| Cloudforms Team: | --- | Target Upstream Version: | |
| Embargoed: | |||
|
Description
Amit Saha
2013-01-10 03:12:47 UTC
Interestingly, this doesn't seem to happen if I'm using a guest with a qcow2 disk image; guests with raw disk images trigger this quite a bit. I experience the same in Archlinux, using btrfs-progs 0.20rc1.1 and linux 3.7.6; playing with libvirt and kvm too [611038.094632] ------------[ cut here ]------------ [611038.094653] WARNING: at fs/btrfs/tree-log.c:3716 btrfs_log_inode_parent+0x3f8/0x450 [btrfs]() [611038.094654] Hardware name: PowerEdge R210 [611038.094655] Modules linked in: loop vhost_net macvtap macvlan tun ip6table_filter ip6_tables ebtable_nat ebtables ipt_MASQUERADE iptable_nat nf_nat_ipv4 nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack ipt_REJECT xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter ip_tables x_tables bridge stp llc ext4 crc16 jbd2 mbcache mgag200 ttm drm_kms_helper coretemp kvm_intel processor iTCO_wdt i7core_edac dcdbas drm kvm edac_core gpio_ich microcode bnx2 syscopyarea sysfillrect sysimgblt i2c_algo_bit i2c_core iTCO_vendor_support lpc_ich acpi_power_meter button evdev pcspkr btrfs libcrc32c zlib_deflate sd_mod sr_mod cdrom ahci libahci libata crc32c_intel mpt2sas ehci_hcd raid_class scsi_transport_sas usbcore scsi_mod usb_common [611038.094688] Pid: 630, comm: qemu-kvm Not tainted 3.7.4-1-ARCH #1 [611038.094689] Call Trace: [611038.094694] [<ffffffff8105750f>] warn_slowpath_common+0x7f/0xc0 [611038.094696] [<ffffffff8105756a>] warn_slowpath_null+0x1a/0x20 [611038.094704] [<ffffffffa01b0708>] btrfs_log_inode_parent+0x3f8/0x450 [btrfs] [611038.094713] [<ffffffffa01b07a7>] btrfs_log_dentry_safe+0x47/0x70 [btrfs] [611038.094722] [<ffffffffa018844f>] btrfs_sync_file+0x18f/0x250 [btrfs] [611038.094725] [<ffffffff811b344d>] do_fsync+0x5d/0x90 [611038.094727] [<ffffffff811b3823>] sys_fdatasync+0x13/0x20 [611038.094731] [<ffffffff814b435d>] system_call_fastpath+0x1a/0x1f [611038.094733] ---[ end trace e17640f98ca870e4 ]--- Which is quickly followed by: [612738.293836] INFO: task flush-btrfs-1:327 blocked for more than 120 seconds. [612738.294287] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [612738.294741] flush-btrfs-1 D ffff8804275f3750 0 327 2 0x00000000 [612738.294744] ffff8804275f36a8 0000000000000046 ffff880428d769a0 ffff8804275f3fd8 [612738.294746] ffff8804275f3fd8 ffff8804275f3fd8 ffff8804290c71c0 ffff880428d769a0 [612738.294748] 00000000000021c0 0000000000000000 ffffea0002590940 ffff88042f233e30 [612738.294751] Call Trace: [612738.294757] [<ffffffff81090dd8>] ? __enqueue_entity+0x78/0x80 [612738.294760] [<ffffffff810935d0>] ? enqueue_entity+0xc0/0x4a0 [612738.294762] [<ffffffff81093a58>] ? enqueue_task_fair+0xa8/0xf0 [612738.294765] [<ffffffff8101ca99>] ? read_tsc+0x9/0x20 [612738.294768] [<ffffffff81120dd0>] ? __lock_page+0x70/0x70 [612738.294772] [<ffffffff814ab899>] schedule+0x29/0x70 [612738.294774] [<ffffffff814ab96f>] io_schedule+0x8f/0xd0 [612738.294776] [<ffffffff81120dde>] sleep_on_page+0xe/0x20 [612738.294778] [<ffffffff814a94cb>] __wait_on_bit_lock+0x5b/0xc0 [612738.294780] [<ffffffff81120dc7>] __lock_page+0x67/0x70 [612738.294782] [<ffffffff8107bd80>] ? autoremove_wake_function+0x40/0x40 [612738.294801] [<ffffffffa01905a9>] lock_delalloc_pages+0x1d9/0x1f0 [btrfs] [612738.294811] [<ffffffffa0192cb2>] find_lock_delalloc_range.constprop.42+0x142/0x1e0 [btrfs] [612738.294820] [<ffffffffa01946f1>] __extent_writepage+0x141/0x7c0 [btrfs] [612738.294828] [<ffffffffa0193ab0>] ? end_extent_writepage+0x70/0x70 [btrfs] [612738.294837] [<ffffffffa019507a>] extent_write_cache_pages.isra.29.constprop.45+0x30a/0x410 [btrfs] [612738.294846] [<ffffffffa01953ee>] extent_writepages+0x4e/0x70 [btrfs] [612738.294855] [<ffffffffa017cce0>] ? btrfs_writepage+0x70/0x70 [btrfs] [612738.294863] [<ffffffffa017bee8>] btrfs_writepages+0x28/0x30 [btrfs] [612738.294866] [<ffffffff8112cb4e>] do_writepages+0x1e/0x40 [612738.294869] [<ffffffff811ac9bb>] __writeback_single_inode+0x3b/0x190 [612738.294872] [<ffffffff8107b9be>] ? wake_up_bit+0x2e/0x40 [612738.294875] [<ffffffff811ae7da>] writeback_sb_inodes+0x2ba/0x4a0 [612738.294877] [<ffffffff811aea5f>] __writeback_inodes_wb+0x9f/0xd0 [612738.294879] [<ffffffff811aeda3>] wb_writeback+0x313/0x340 [612738.294881] [<ffffffff811af72d>] wb_do_writeback+0xcd/0x260 [612738.294884] [<ffffffff810675f0>] ? call_timer_fn+0x180/0x180 [612738.294886] [<ffffffff811af953>] bdi_writeback_thread+0x93/0x2d0 [612738.294887] [<ffffffff811af8c0>] ? wb_do_writeback+0x260/0x260 [612738.294890] [<ffffffff8107b050>] kthread+0xc0/0xd0 [612738.294893] [<ffffffff81010000>] ? perf_trace_xen_mmu_set_pte_at+0xb0/0x100 [612738.294895] [<ffffffff8107af90>] ? kthread_freezable_should_stop+0x70/0x70 [612738.294898] [<ffffffff814b42ac>] ret_from_fork+0x7c/0xb0 [612738.294901] [<ffffffff8107af90>] ? kthread_freezable_should_stop+0x70/0x70 [612738.294905] INFO: task qemu-kvm:909 blocked for more than 120 seconds. [612738.295282] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [612738.295734] qemu-kvm D ffff88001e46f3f0 0 909 1 0x00000000 [612738.295736] ffff88001e46f348 0000000000000086 ffff8801914f0000 ffff88001e46ffd8 [612738.295738] ffff88001e46ffd8 ffff88001e46ffd8 ffff8804290c69a0 ffff8801914f0000 [612738.295740] ffff88001e46f2b8 ffffffffa0190f75 0000000003845000 ffff8802b5af11c0 [612738.295743] Call Trace: [612738.295753] [<ffffffffa0190f75>] ? clear_state_bit+0xb5/0x180 [btrfs] [612738.295762] [<ffffffffa019179c>] ? clear_extent_bit+0x23c/0x3b0 [btrfs] [612738.295764] [<ffffffff81120dd0>] ? __lock_page+0x70/0x70 [612738.295766] [<ffffffff814ab899>] schedule+0x29/0x70 [612738.295768] [<ffffffff814ab96f>] io_schedule+0x8f/0xd0 [612738.295770] [<ffffffff81120dde>] sleep_on_page+0xe/0x20 [612738.295772] [<ffffffff814a93b0>] __wait_on_bit+0x60/0x90 [612738.295774] [<ffffffff81121070>] wait_on_page_bit+0x80/0x90 [612738.295776] [<ffffffff8107bd80>] ? autoremove_wake_function+0x40/0x40 [612738.295778] [<ffffffff81133cc4>] shrink_page_list+0x5e4/0x9d0 [612738.295781] [<ffffffff811345ff>] shrink_inactive_list+0x14f/0x420 [612738.295784] [<ffffffff81261862>] ? cfq_set_request+0x212/0x460 [612738.295786] [<ffffffff81135001>] shrink_lruvec+0x391/0x5b0 [612738.295788] [<ffffffff81135295>] shrink_zone+0x75/0xa0 [612738.295790] [<ffffffff81135603>] do_try_to_free_pages+0xe3/0x500 [612738.295793] [<ffffffff810943d3>] ? find_busiest_group+0x143/0xaa0 [612738.295795] [<ffffffff81135baf>] try_to_free_mem_cgroup_pages+0xbf/0x1b0 [612738.295799] [<ffffffff8117c62e>] mem_cgroup_reclaim+0x4e/0xe0 [612738.295801] [<ffffffff8117d4c5>] __mem_cgroup_try_charge+0x405/0x900 [612738.295803] [<ffffffff8112e250>] ? release_pages+0x1f0/0x230 [612738.295805] [<ffffffff8108faf5>] ? sched_clock_cpu+0xc5/0x120 [612738.295807] [<ffffffff8117e051>] mem_cgroup_charge_common+0x51/0xa0 [612738.295810] [<ffffffff8117ea0a>] mem_cgroup_cache_charge+0x7a/0xa0 [612738.295812] [<ffffffff81121476>] add_to_page_cache_locked+0x46/0x160 [612738.295813] [<ffffffff811215aa>] add_to_page_cache_lru+0x1a/0x40 [612738.295815] [<ffffffff811219b5>] find_or_create_page+0x65/0xb0 [612738.295824] [<ffffffffa0188bec>] prepare_pages.isra.17+0x19c/0x350 [btrfs] [612738.295831] [<ffffffffa0164b6f>] ? btrfs_delalloc_reserve_metadata+0x26f/0x430 [btrfs] [612738.295840] [<ffffffffa01895b4>] __btrfs_buffered_write+0x164/0x350 [btrfs] [612738.295843] [<ffffffff8119da45>] ? update_time+0x25/0xc0 [612738.295851] [<ffffffffa0189991>] btrfs_file_aio_write+0x1f1/0x4a0 [btrfs] [612738.295854] [<ffffffff8107f942>] ? hrtimer_cancel+0x22/0x30 [612738.295862] [<ffffffffa01897a0>] ? __btrfs_buffered_write+0x350/0x350 [btrfs] [612738.295864] [<ffffffff811849d3>] do_sync_readv_writev+0xa3/0xe0 [612738.295867] [<ffffffff81184cb4>] do_readv_writev+0xd4/0x1e0 [612738.295869] [<ffffffff81184df5>] vfs_writev+0x35/0x60 [612738.295870] [<ffffffff811851b2>] sys_pwritev+0xb2/0xe0 [612738.295873] [<ffffffff814b435d>] system_call_fastpath+0x1a/0x1f Description of problem: I was running a few updates and then got the notification that the kernel had crashed. Unfortunately I can't give any more information than that. Version-Release number of selected component: kernel Additional info: cmdline: BOOT_IMAGE=/vmlinuz-3.8.4-202.fc18.x86_64 root=UUID=26559178-7740-4426-8c1a-03e5f89d8936 ro rootflags=subvol=root rd.md=0 rd.lvm=0 rd.dm=0 rd.luks=0 vconsole.keymap=us rhgb quiet LANG=en_US.UTF-8 kernel: 3.8.4-202.fc18.x86_64 type: Kerneloops ureports_counter: 1 Truncated backtrace: WARNING: at fs/btrfs/tree-log.c:3843 btrfs_log_inode_parent+0x3f8/0x450 [btrfs]() Hardware name: OptiPlex GX620 Modules linked in: nfsv4 auth_rpcgss nfs lockd dns_resolver fscache nf_conntrack_netbios_ns nf_conntrack_broadcast ipt_MASQUERADE ip6table_mangle ip6t_REJECT nf_conntrack_ipv6 nf_defrag_ipv6 iptable_nat nf_nat_ipv4 nf_nat bnep iptable_mangle nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack ebtable_filter ebtables bluetooth rfkill ip6table_filter ip6_tables be2iscsi iscsi_boot_sysfs bnx2i cnic uio cxgb4i cxgb4 cxgb3i cxgb3 mdio libcxgbi ib_iser rdma_cm ib_addr iw_cm ib_cm ib_sa ib_mad ib_core iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi iTCO_wdt snd_hda_codec_hdmi snd_hda_intel snd_intel8x0 snd_hda_codec snd_ac97_codec snd_hwdep ac97_bus snd_seq snd_seq_device snd_pcm iTCO_vendor_support joydev snd_page_alloc snd_timer ppdev lpc_ich snd microcode dcdbas mfd_core i2c_i801 serio_raw parport_pc soundcore parport uinput btrfs zlib_deflate libcrc32c radeon i2c_algo_bit drm_kms_helper ttm tg3 drm ptp pps_core i2c_core sunrpc Pid: 2070, comm: kate Not tainted 3.8.4-202.fc18.x86_64 #1 Call Trace: [<ffffffff8105e62f>] warn_slowpath_common+0x7f/0xc0 [<ffffffff8105e68a>] warn_slowpath_null+0x1a/0x20 [<ffffffffa027b938>] btrfs_log_inode_parent+0x3f8/0x450 [btrfs] [<ffffffffa027b9d7>] btrfs_log_dentry_safe+0x47/0x70 [btrfs] [<ffffffffa02523c7>] btrfs_sync_file+0x167/0x230 [btrfs] [<ffffffff811cbc4d>] do_fsync+0x5d/0x90 [<ffffffff811cbee3>] sys_fdatasync+0x13/0x20 [<ffffffff816588d9>] system_call_fastpath+0x16/0x1b *** Bug 904143 has been marked as a duplicate of this bug. *** Fixed in 3.10. |