194667 – ext4 converted file system: errno=-95 unknown at at fs/btrfs/inode.c:2980 btrfs_finish_ordered_io+0x6b4/0x6c0

Bug 194667 - ext4 converted file system: errno=-95 unknown at at fs/btrfs/inode.c:2980 btrfs_finish_ordered_io+0x6b4/0x6c0

Summary: ext4 converted file system: errno=-95 unknown at at fs/btrfs/inode.c:2980 btr...

Status:	RESOLVED OBSOLETE

Alias:	None

Product:	File System
Classification:	Unclassified
Component:	btrfs (show other bugs)
Hardware:	x86-64 Linux

Importance:	P1 high
Assignee:	Josef Bacik

URL:
Keywords:

Depends on:
Blocks:

Reported:	2017-02-23 00:19 UTC by linux-ide
Modified:	2022-10-06 17:44 UTC (History)
CC List:	2 users (show)

See Also:
Kernel Version:	4.9.11-1-ARCH
Subsystem:
Regression:	No
Bisected commit-id:

Attachments
Add an attachment (proposed patch, testcase, etc.)

Description linux-ide 2017-02-23 00:19:16 UTC

Both an existing ext4 root and ext4 home file system were both converted to btrfs.

For the second time an error occurs on the root file system resulting in a read-only remount.

$ btrfs fi show
$ btrfs fi df /
Data, single: total=3.19GiB, used=3.14GiB
System, single: total=4.00MiB, used=16.00KiB
Metadata, single: total=416.00MiB, used=79.42MiB
GlobalReserve, single: total=16.00MiB, used=0.00B

[ 4046.113967]  sda: sda1 sda2
[ 6699.629457] ------------[ cut here ]------------
[ 6699.629557] WARNING: CPU: 0 PID: 12009 at fs/btrfs/inode.c:2980 btrfs_finish_ordered_io+0x6b4/0x6c0 [btrfs]
[ 6699.629562] BTRFS: Transaction aborted (error -95)
[ 6699.629566] Modules linked in: dm_mod snd_hda_codec_hdmi cfg80211 snd_hda_codec_realtek snd_hda_codec_generic rfkill intel_soc_dts_iosf intel_powerclamp coretemp iTCO_wdt kvm_intel iTCO_vendor_support kvm irqbypass crct10dif_pclmul crc32_pclmul ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul nls_iso8859_1 glue_helper nls_cp437 ablk_helper vfat fat cryptd intel_cstate psmouse pcspkr i915 r8169 i2c_i801 i2c_smbus mii lpc_ich drm_kms_helper shpchp snd_hda_intel drm snd_hda_codec input_leds evdev joydev mac_hid mei_txe intel_gtt syscopyarea mei sysfillrect snd_hda_core snd_hwdep sysimgblt fb_sys_fops i2c_algo_bit i2c_designware_pci battery snd_intel_sst_acpi snd_intel_sst_core fjes snd_soc_rt5645 video snd_soc_sst_mfld_platform snd_soc_rt5640 snd_soc_rl6231 snd_soc_sst_match snd_soc_core i2c_hid
[ 6699.629860]  i2c_designware_platform snd_compress snd_pcm_dmaengine i2c_designware_core snd_pcm snd_timer spi_pxa2xx_platform snd soundcore ac97_bus tpm_tis 8250_dw tpm_tis_core tpm button sch_fq_codel ip_tables x_tables btrfs xor raid6_pq sd_mod hid_generic usbhid hid uas usb_storage mmc_block serio_raw atkbd libps2 crc32c_intel ahci libahci xhci_pci xhci_hcd sdhci_pci usbcore usb_common libata scsi_mod i8042 serio sdhci_acpi sdhci led_class mmc_core
[ 6699.632946] CPU: 0 PID: 12009 Comm: kworker/u2:0 Not tainted 4.9.11-1-ARCH #1
[ 6699.632953] Hardware name: \xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff \xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff/DE3815TYKH, BIOS TYBYT10H.86A.0054.2016.0929.1501 09/29/201
[ 6699.633045] Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
[ 6699.633056]  ffffc900045afc78 ffffffff81305830 ffffc900045afcc8 0000000000000000
[ 6699.633075]  ffffc900045afcb8 ffffffff8107eb3b 00000ba400000035 ffff88022dc101f0
[ 6699.633092]  ffff8802340f3000 ffff88022e158d00 ffff88022dc10000 ffff880235b74b40
[ 6699.633109] Call Trace:
[ 6699.633132]  [<ffffffff81305830>] dump_stack+0x63/0x83
[ 6699.633146]  [<ffffffff8107eb3b>] __warn+0xcb/0xf0
[ 6699.633158]  [<ffffffff8107ebbf>] warn_slowpath_fmt+0x5f/0x80
[ 6699.633238]  [<ffffffffa023c1fc>] ? unpin_extent_cache+0x9c/0xe0 [btrfs]
[ 6699.633304]  [<ffffffffa01f46d6>] ? btrfs_free_path+0x26/0x30 [btrfs]
[ 6699.633380]  [<ffffffffa022ca64>] btrfs_finish_ordered_io+0x6b4/0x6c0 [btrfs]
[ 6699.633457]  [<ffffffffa022cd95>] finish_ordered_fn+0x15/0x20 [btrfs]
[ 6699.633532]  [<ffffffffa0257a9d>] btrfs_scrubparity_helper+0x7d/0x350 [btrfs]
[ 6699.633608]  [<ffffffffa0257dfe>] btrfs_endio_write_helper+0xe/0x10 [btrfs]
[ 6699.633621]  [<ffffffff81098ad5>] process_one_work+0x1e5/0x470
[ 6699.633633]  [<ffffffff81098da8>] worker_thread+0x48/0x4e0
[ 6699.633645]  [<ffffffff81098d60>] ? process_one_work+0x470/0x470
[ 6699.633655]  [<ffffffff81098d60>] ? process_one_work+0x470/0x470
[ 6699.633665]  [<ffffffff8109e939>] kthread+0xd9/0xf0
[ 6699.633676]  [<ffffffff8109e860>] ? kthread_park+0x60/0x60
[ 6699.633687]  [<ffffffff8160ad55>] ret_from_fork+0x25/0x30
[ 6699.636502] ---[ end trace 9b512a04caee99b5 ]---
[ 6699.636518] BTRFS: error (device sdb2) in btrfs_finish_ordered_io:2980: errno=-95 unknown
[ 6699.636891] BTRFS info (device sdb2): forced readonly
[ 6699.637146] BTRFS error (device sdb2): pending csums is 4096

Comment 1 linux-ide 2017-02-23 00:21:29 UTC

# parted /dev/sdb unit MiB print
Warning: Not all of the space available to /dev/sdb appears to be used, you can fix the GPT to use all of the space (an extra 121792
blocks) or continue with the current setting?
Fix/Ignore? i
Model: Samsung Flash Drive FIT (scsi)
Disk /dev/sdb: 30592MiB
Sector size (logical/physical): 512B/512B
Partition Table: gpt
Disk Flags:

Number  Start     End       Size      File system  Name     Flags
 1      4.00MiB   1028MiB   1024MiB   fat32        EFIboot  boot, esp
 2      1028MiB   21028MiB  20000MiB  btrfs        root
 3      21028MiB  30532MiB  9504MiB   btrfs        home

Comment 2 linux-ide 2017-02-23 00:23:07 UTC

# btrfs scrub status -d /dev/sda2
scrub status for b81e9d42-c0a2-4a1e-8197-f6775419f654
scrub device /dev/sda2 (id 1) status
	scrub started at Thu Feb 23 01:11:07 2017, running for 00:00:05
	total bytes scrubbed: 526.20MiB with 0 errors

Comment 3 linux-ide 2017-02-23 13:02:02 UTC

btrfs scrub status -d /dev/sda2
scrub status for b81e9d42-c0a2-4a1e-8197-f6775419f654
scrub device /dev/sda2 (id 1) history
	scrub started at Thu Feb 23 01:11:07 2017 and finished after 00:00:31
	total bytes scrubbed: 3.22GiB with 0 errors

dmesg | grep -i btrfs
[    3.611848] Btrfs loaded, crc32c=crc32c-intel
[    3.615186] BTRFS: device label root devid 1 transid 297 /dev/sda2
[    3.616527] BTRFS: device label home devid 1 transid 39 /dev/sda3
[    4.146089] BTRFS info (device sda2): disk space caching is enabled
[    4.146093] BTRFS info (device sda2): has skinny extents
[    4.356402] BTRFS info (device sda2): checking UUID tree
[    4.973491] BTRFS info (device sda2): disk space caching is enabled
[    6.315479] BTRFS info (device sda3): disk space caching is enabled
[    6.315484] BTRFS info (device sda3): has skinny extents

Comment 4 linux-ide 2017-02-24 10:44:22 UTC

The third time it took much longer waiting after boot before the "BTRFS: Transaction aborted (error -95)" to occur:

[68884.174295] r8169 0000:03:00.0 enp3s0: link up
[79857.661362] ------------[ cut here ]------------
[79857.661445] WARNING: CPU: 0 PID: 12870 at fs/btrfs/inode.c:2980 btrfs_finish_ordered_io+0x6b4/0x6c0 [btrfs]
[79857.661449] BTRFS: Transaction aborted (error -95)
[79857.661453] Modules linked in: dm_mod snd_hda_codec_hdmi cfg80211 snd_hda_codec_realtek rfkill snd_hda_codec_generic intel_soc_dts_iosf intel_powerclamp coretemp kvm_intel iTCO_wdt iTCO_vendor_support kvm irqbypass crct10dif_pclmul crc32_pclmul ghash_clmulni_intel nls_iso8859_1 nls_cp437 aesni_intel vfat fat aes_x86_64 i915 lrw gf128mul glue_helper ablk_helper cryptd intel_cstate psmouse pcspkr snd_hda_intel drm_kms_helper r8169 i2c_i801 i2c_smbus mii lpc_ich snd_hda_codec drm evdev mei_txe snd_hda_core snd_hwdep mei shpchp joydev input_leds i2c_designware_pci mac_hid intel_gtt syscopyarea sysfillrect sysimgblt fb_sys_fops i2c_algo_bit snd_soc_rt5645 snd_intel_sst_acpi battery snd_intel_sst_core snd_soc_rt5640 snd_soc_rl6231 snd_soc_sst_mfld_platform snd_soc_sst_match fjes video snd_soc_core snd_compress
[79857.661688]  snd_pcm_dmaengine i2c_hid snd_pcm snd_timer snd soundcore i2c_designware_platform ac97_bus i2c_designware_core spi_pxa2xx_platform 8250_dw tpm_tis tpm_tis_core tpm button sch_fq_codel ip_tables x_tables btrfs xor raid6_pq sd_mod uas usb_storage hid_generic usbhid hid mmc_block serio_raw atkbd libps2 crc32c_intel ahci libahci xhci_pci xhci_hcd usbcore sdhci_pci usb_common libata scsi_mod i8042 serio sdhci_acpi sdhci led_class mmc_core
[79857.661809] CPU: 0 PID: 12870 Comm: kworker/u2:0 Not tainted 4.9.11-1-ARCH #1
[79857.661814] Hardware name: \xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff \xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff\xffffffff/DE3815TYKH, BIOS TYBYT10H.86A.0054.2016.0929.1501 09/29/201
[79857.661879] Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
[79857.661888]  ffffc9000471fc78 ffffffff81305830 ffffc9000471fcc8 0000000000000000
[79857.661903]  ffffc9000471fcb8 ffffffff8107eb3b 00000ba400000035 ffff880233bf3d70
[79857.661917]  ffff88023452f000 ffff88022f3dd520 ffff880233bf3b80 ffff880235bdbdc0
[79857.661930] Call Trace:
[79857.661948]  [<ffffffff81305830>] dump_stack+0x63/0x83
[79857.661960]  [<ffffffff8107eb3b>] __warn+0xcb/0xf0
[79857.661969]  [<ffffffff8107ebbf>] warn_slowpath_fmt+0x5f/0x80
[79857.662033]  [<ffffffffa022e1fc>] ? unpin_extent_cache+0x9c/0xe0 [btrfs]
[79857.662087]  [<ffffffffa01e66d6>] ? btrfs_free_path+0x26/0x30 [btrfs]
[79857.662147]  [<ffffffffa021ea64>] btrfs_finish_ordered_io+0x6b4/0x6c0 [btrfs]
[79857.662209]  [<ffffffffa021ed95>] finish_ordered_fn+0x15/0x20 [btrfs]
[79857.662269]  [<ffffffffa0249a9d>] btrfs_scrubparity_helper+0x7d/0x350 [btrfs]
[79857.662330]  [<ffffffffa0249dfe>] btrfs_endio_write_helper+0xe/0x10 [btrfs]
[79857.662340]  [<ffffffff81098ad5>] process_one_work+0x1e5/0x470
[79857.662350]  [<ffffffff81098da8>] worker_thread+0x48/0x4e0
[79857.662359]  [<ffffffff81098d60>] ? process_one_work+0x470/0x470
[79857.662368]  [<ffffffff81098d60>] ? process_one_work+0x470/0x470
[79857.662376]  [<ffffffff8109e939>] kthread+0xd9/0xf0
[79857.662385]  [<ffffffff8102d752>] ? __switch_to+0x2d2/0x630
[79857.662393]  [<ffffffff8109e860>] ? kthread_park+0x60/0x60
[79857.662402]  [<ffffffff8160ad55>] ret_from_fork+0x25/0x30
[79857.662410] ---[ end trace 71b146de7a0912aa ]---
[79857.662420] BTRFS: error (device sda2) in btrfs_finish_ordered_io:2980: errno=-95 unknown
[79857.662771] BTRFS info (device sda2): forced readonly

Comment 5 lakshmipathi 2017-04-28 08:40:13 UTC

Did 'btrfs check' report any errors? Can you share that output too?

Comment 6 David Sterba 2022-10-06 17:44:40 UTC

This is a semi-automated bugzilla cleanup, report is against an old kernel version. If the problem still happens, please open a new bug. Thanks.

Note You need to log in before you can comment on or make changes to this bug.