btrfs gives kernel call trace just because send/receive on a ro fs

btrfs gives kernel call trace just because send/receive on a ro fs

[Christoph Anton Mitterer]

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

Hey.

$ uname -a
Linux heisenberg 4.6.0-1-amd64 #1 SMP Debian 4.6.4-1 (2016-07-18) x86_64 GNU/Linux


The following call trace happens, just because of a send/receive to a
read-only mounted btrfs. Isn't that a bit overkill and shouldn't one
rather get just a user space warning/error?

Aug 16 21:15:49 heisenberg kernel: ------------[ cut here ]------------
Aug 16 21:15:49 heisenberg kernel: WARNING: CPU: 1 PID: 32585 at /build/linux-aOJbQa/linux-4.6.4/fs/btrfs/send.c:5963 btrfs_ioctl_send+0x670/0x1170 [btrfs]
Aug 16 21:15:49 heisenberg kernel: Modules linked in: hmac(E) drbg(E) ansi_cprng(E) ctr(E) ccm(E) snd_seq_dummy(E) snd_hrtimer(E) snd_seq(E) snd_seq_device(E) ext4(E) ecb(E) jbd2(E) mbcache(E) algif_skcipher(E) af_alg(E) uas(E) vhost_net(E) vhost(E) macvtap(E) macvlan(E) xt_CHECKSUM(E) iptable_mangle(E) ipt_MASQUERADE(E) nf_nat_masquerade_ipv4(E) iptable_nat(E) nf_nat_ipv4(E) nf_nat(E) xt_tcpudp(E) tun(E) bridge(E) stp(E) llc(E) fuse(E) ebtable_filter(E) ebtables(E) joydev(E) rtsx_pci_ms(E) memstick(E) rtsx_pci_sdmmc(E) mmc_core(E) iTCO_wdt(E) iTCO_vendor_support(E) cpufreq_userspace(E) cpufreq_stats(E) cpufreq_powersave(E) cpufreq_conservative(E) ip6t_REJECT(E) nf_reject_ipv6(E) nf_conntrack_ipv6(E) nf_defrag_ipv6(E) ip6table_filter(E) ip6_tables(E) xt_policy(E) ipt_REJECT(E) nf_reject_ipv4(E) xt_comment(E) nf_conntrack_ipv4(E)
Aug 16 21:15:49 heisenberg kernel:  nf_defrag_ipv4(E) xt_multiport(E) xt_conntrack(E) nf_conntrack(E) iptable_filter(E) ip_tables(E) x_tables(E) binfmt_misc(E) intel_rapl(E) x86_pkg_temp_thermal(E) intel_powerclamp(E) coretemp(E) kvm_intel(E) kvm(E) irqbypass(E) crct10dif_pclmul(E) crc32_pclmul(E) ghash_clmulni_intel(E) btusb(E) btrtl(E) btbcm(E) btintel(E) bluetooth(E) crc16(E) sg(E) uvcvideo(E) videobuf2_vmalloc(E) videobuf2_memops(E) videobuf2_v4l2(E) videobuf2_core(E) videodev(E) media(E) psmouse(E) pcspkr(E) i2c_i801(E) arc4(E) iwldvm(E) mac80211(E) iwlwifi(E) rtsx_pci(E) cfg80211(E) rfkill(E) snd_hda_codec_hdmi(E) snd_hda_codec_realtek(E) snd_hda_codec_generic(E) 8250_fintek(E) snd_hda_intel(E) snd_hda_codec(E) snd_hda_core(E) snd_hwdep(E) e1000e(E) snd_pcm(E) snd_timer(E) lpc_ich(E) snd(E) fjes(E) fujitsu_laptop(E)
Aug 16 21:15:49 heisenberg kernel:  ac(E) battery(E) ptp(E) mfd_core(E) soundcore(E) pps_core(E) shpchp(E) tpm_tis(E) tpm(E) processor(E) i915(E) video(E) button(E) drm_kms_helper(E) drm(E) i2c_algo_bit(E) mei_me(E) mei(E) loop(E) sunrpc(E) parport_pc(E) ppdev(E) lp(E) parport(E) autofs4(E) dm_crypt(E) dm_mod(E) raid10(E) raid456(E) libcrc32c(E) async_raid6_recov(E) async_memcpy(E) async_pq(E) async_xor(E) async_tx(E) raid1(E) raid0(E) multipath(E) linear(E) md_mod(E) btrfs(E) crc32c_generic(E) xor(E) raid6_pq(E) uhci_hcd(E) usb_storage(E) sd_mod(E) crc32c_intel(E) aesni_intel(E) aes_x86_64(E) glue_helper(E) lrw(E) gf128mul(E) ablk_helper(E) cryptd(E) evdev(E) serio_raw(E) ahci(E) libahci(E) libata(E) scsi_mod(E) xhci_pci(E) ehci_pci(E) xhci_hcd(E) ehci_hcd(E) usbcore(E) usb_common(E)
Aug 16 21:15:49 heisenberg kernel: CPU: 1 PID: 32585 Comm: btrfs Tainted: G            E   4.6.0-1-amd64 #1 Debian 4.6.4-1
Aug 16 21:15:49 heisenberg kernel: Hardware name: FUJITSU LIFEBOOK E782/FJNB23E, BIOS Version 1.11 05/24/2012
Aug 16 21:15:49 heisenberg kernel:  0000000000000286 00000000ef07eaba ffffffff81311505 0000000000000000
Aug 16 21:15:49 heisenberg kernel:  0000000000000000 ffffffff8107a50e ffff8802927ce42c 00007fff2ac54650
Aug 16 21:15:49 heisenberg kernel:  ffff8802b4e82000 0000000040489426 ffff8802a7aa29c8 ffff8802927ce000
Aug 16 21:15:49 heisenberg kernel: Call Trace:
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff81311505>] ? dump_stack+0x5c/0x77
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff8107a50e>] ? __warn+0xbe/0xe0
Aug 16 21:15:49 heisenberg kernel:  [<ffffffffc0331d20>] ? btrfs_ioctl_send+0x670/0x1170 [btrfs]
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff81208401>] ? dput+0x81/0x210
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff811fb109>] ? terminate_walk+0x89/0xf0
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff810aa1a8>] ? attach_task_cfs_rq+0x38/0x70
Aug 16 21:15:49 heisenberg kernel:  [<ffffffffc02fb69d>] ? btrfs_ioctl+0x151d/0x21a0 [btrfs]
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff810afee9>] ? enqueue_task_fair+0x59/0x950
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff81035875>] ? sched_clock+0x5/0x10
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff81204a8d>] ? do_vfs_ioctl+0x9d/0x5c0
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff810797c1>] ? _do_fork+0x141/0x3f0
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff81205024>] ? SyS_ioctl+0x74/0x80
Aug 16 21:15:49 heisenberg kernel:  [<ffffffff815c6776>] ? system_call_fast_compare_end+0xc/0x96
Aug 16 21:15:49 heisenberg kernel: ---[ end trace 23571c17eb812726 ]---


Here I remount,rw:
Aug 16 21:16:30 heisenberg kernel: BTRFS info (device dm-2): disk space
caching is enabled


Interestingly, when I start the send/receive again, another call trace
occurs, but the user space tools move on just fine (at least
seemingly)??

Aug 16 21:16:51 heisenberg kernel: ------------[ cut here ]------------
Aug 16 21:16:51 heisenberg kernel: WARNING: CPU: 2 PID: 32624 at /build/linux-aOJbQa/linux-4.6.4/fs/btrfs/send.c:5963 btrfs_ioctl_send+0x670/0x1170 [btrfs]
Aug 16 21:16:51 heisenberg kernel: Modules linked in: hmac(E) drbg(E) ansi_cprng(E) ctr(E) ccm(E) snd_seq_dummy(E) snd_hrtimer(E) snd_seq(E) snd_seq_device(E) ext4(E) ecb(E) jbd2(E) mbcache(E) algif_skcipher(E) af_alg(E) uas(E) vhost_net(E) vhost(E) macvtap(E) macvlan(E) xt_CHECKSUM(E) iptable_mangle(E) ipt_MASQUERADE(E) nf_nat_masquerade_ipv4(E) iptable_nat(E) nf_nat_ipv4(E) nf_nat(E) xt_tcpudp(E) tun(E) bridge(E) stp(E) llc(E) fuse(E) ebtable_filter(E) ebtables(E) joydev(E) rtsx_pci_ms(E) memstick(E) rtsx_pci_sdmmc(E) mmc_core(E) iTCO_wdt(E) iTCO_vendor_support(E) cpufreq_userspace(E) cpufreq_stats(E) cpufreq_powersave(E) cpufreq_conservative(E) ip6t_REJECT(E) nf_reject_ipv6(E) nf_conntrack_ipv6(E) nf_defrag_ipv6(E) ip6table_filter(E) ip6_tables(E) xt_policy(E) ipt_REJECT(E) nf_reject_ipv4(E) xt_comment(E) nf_conntrack_ipv4(E)
Aug 16 21:16:51 heisenberg kernel:  nf_defrag_ipv4(E) xt_multiport(E) xt_conntrack(E) nf_conntrack(E) iptable_filter(E) ip_tables(E) x_tables(E) binfmt_misc(E) intel_rapl(E) x86_pkg_temp_thermal(E) intel_powerclamp(E) coretemp(E) kvm_intel(E) kvm(E) irqbypass(E) crct10dif_pclmul(E) crc32_pclmul(E) ghash_clmulni_intel(E) btusb(E) btrtl(E) btbcm(E) btintel(E) bluetooth(E) crc16(E) sg(E) uvcvideo(E) videobuf2_vmalloc(E) videobuf2_memops(E) videobuf2_v4l2(E) videobuf2_core(E) videodev(E) media(E) psmouse(E) pcspkr(E) i2c_i801(E) arc4(E) iwldvm(E) mac80211(E) iwlwifi(E) rtsx_pci(E) cfg80211(E) rfkill(E) snd_hda_codec_hdmi(E) snd_hda_codec_realtek(E) snd_hda_codec_generic(E) 8250_fintek(E) snd_hda_intel(E) snd_hda_codec(E) snd_hda_core(E) snd_hwdep(E) e1000e(E) snd_pcm(E) snd_timer(E) lpc_ich(E) snd(E) fjes(E) fujitsu_laptop(E)
Aug 16 21:16:51 heisenberg kernel:  ac(E) battery(E) ptp(E) mfd_core(E) soundcore(E) pps_core(E) shpchp(E) tpm_tis(E) tpm(E) processor(E) i915(E) video(E) button(E) drm_kms_helper(E) drm(E) i2c_algo_bit(E) mei_me(E) mei(E) loop(E) sunrpc(E) parport_pc(E) ppdev(E) lp(E) parport(E) autofs4(E) dm_crypt(E) dm_mod(E) raid10(E) raid456(E) libcrc32c(E) async_raid6_recov(E) async_memcpy(E) async_pq(E) async_xor(E) async_tx(E) raid1(E) raid0(E) multipath(E) linear(E) md_mod(E) btrfs(E) crc32c_generic(E) xor(E) raid6_pq(E) uhci_hcd(E) usb_storage(E) sd_mod(E) crc32c_intel(E) aesni_intel(E) aes_x86_64(E) glue_helper(E) lrw(E) gf128mul(E) ablk_helper(E) cryptd(E) evdev(E) serio_raw(E) ahci(E) libahci(E) libata(E) scsi_mod(E) xhci_pci(E) ehci_pci(E) xhci_hcd(E) ehci_hcd(E) usbcore(E) usb_common(E)
Aug 16 21:16:51 heisenberg kernel: CPU: 2 PID: 32624 Comm: btrfs Tainted: G        W   E   4.6.0-1-amd64 #1 Debian 4.6.4-1
Aug 16 21:16:51 heisenberg kernel: Hardware name: FUJITSU LIFEBOOK E782/FJNB23E, BIOS Version 1.11 05/24/2012
Aug 16 21:16:51 heisenberg kernel:  0000000000000286 0000000060551fec ffffffff81311505 0000000000000000
Aug 16 21:16:51 heisenberg kernel:  0000000000000000 ffffffff8107a50e ffff8802927ce42c 00007ffda18deb50
Aug 16 21:16:51 heisenberg kernel:  ffff8802b4e82000 0000000040489426 ffff8802a7aa29c8 ffff8802927ce000
Aug 16 21:16:51 heisenberg kernel: Call Trace:
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff81311505>] ? dump_stack+0x5c/0x77
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff8107a50e>] ? __warn+0xbe/0xe0
Aug 16 21:16:51 heisenberg kernel:  [<ffffffffc0331d20>] ? btrfs_ioctl_send+0x670/0x1170 [btrfs]
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff81208401>] ? dput+0x81/0x210
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff811fb109>] ? terminate_walk+0x89/0xf0
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff810aa1a8>] ? attach_task_cfs_rq+0x38/0x70
Aug 16 21:16:51 heisenberg kernel:  [<ffffffffc02fb69d>] ? btrfs_ioctl+0x151d/0x21a0 [btrfs]
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff8131131a>] ? cpumask_next_and+0x2a/0x40
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff810afee9>] ? enqueue_task_fair+0x59/0x950
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff81035875>] ? sched_clock+0x5/0x10
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff81204a8d>] ? do_vfs_ioctl+0x9d/0x5c0
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff810797c1>] ? _do_fork+0x141/0x3f0
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff81205024>] ? SyS_ioctl+0x74/0x80
Aug 16 21:16:51 heisenberg kernel:  [<ffffffff815c6776>] ? system_call_fast_compare_end+0xc/0x96
Aug 16 21:16:51 heisenberg kernel: ---[ end trace 23571c17eb812727 ]---



Cheers,
Chris.

[-- Attachment #2: smime.p7s --]
[-- Type: application/x-pkcs7-signature, Size: 5930 bytes --]