BTRFS dmesg error

Hi there,

I noticed in the dmesg logs that there seems to be a bug/issue with btrfs. Here is the trace/message from dmesg. Im going by the instructions from the https://btrfs.wiki.kernel.org/index.php/Problem_FAQ#How_do_I_report_bugs_and_issues.3F . Let me know if you need anything else.

Thanks,
Skender Haxhimolla.

skender@blackspider:~$ [655330.131113] ------------[ cut here ]------------
[655330.131123] WARNING: at fs/btrfs/super.c:246 __btrfs_abort_transaction+0x46/0xa0()
[655330.131125] Hardware name: Precision WorkStation T3400
[655330.131126] btrfs: Transaction aborted
[655330.131128] Modules linked in: xt_limit xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ip6table_filter ip6_tables iptable_filter ip_tables x_tables rfcomm bnep bluetooth dm_crypt dvb_usb_cxusb snd_hda_codec_analog dib7000p dibx000_common dvb_usb snd_hda_intel snd_hda_codec snd_hwdep dvb_core snd_pcm snd_seq_midi snd_rawmidi snd_seq_midi_event rc_core dib0070 snd_seq coretemp snd_timer snd_seq_device snd kvm_intel soundcore kvm psmouse snd_page_alloc microcode x38_edac lpc_ich dcdbas serio_raw edac_core ppdev parport_pc lp parport floppy firewire_ohci firewire_core crc_itu_t nouveau tg3 ttm drm_kms_helper drm i2c_algo_bit mxm_wmi video wmi
[655330.131182] Pid: 5838, comm: btrfs-delalloc- Not tainted 3.6.11-custom #1
[655330.131183] Call Trace:
[655330.131190]  [<ffffffff810507dc>] warn_slowpath_common+0x83/0x9c
[655330.131193]  [<ffffffff81050898>] warn_slowpath_fmt+0x46/0x48
[655330.131197]  [<ffffffff81231e0b>] ? btrfs_reserve_extent+0xbd/0x1c6
[655330.131200]  [<ffffffff812208a7>] __btrfs_abort_transaction+0x46/0xa0
[655330.131203]  [<ffffffff812468f4>] submit_compressed_extents+0x1bd/0x415
[655330.131207]  [<ffffffff81246bd2>] async_cow_submit+0x86/0x8b
[655330.131210]  [<ffffffff8125ec27>] run_ordered_completions+0x76/0xd5
[655330.131213]  [<ffffffff8125f49e>] worker_loop+0x183/0x4b9
[655330.131216]  [<ffffffff8125f31b>] ? btrfs_queue_worker+0x288/0x288
[655330.131220]  [<ffffffff8106d9d0>] kthread+0x6f/0x77
[655330.131225]  [<ffffffff815b59c4>] kernel_thread_helper+0x4/0x10
[655330.131228]  [<ffffffff8106d961>] ? kthread_freezable_should_stop+0x48/0x48
[655330.131231]  [<ffffffff815b59c0>] ? gs_change+0x13/0x13
[655330.131233] ---[ end trace bc2b68accbd79b9c ]---

Please paste a few lines around the messages, the error code is missing, though I'm suspecting it to be ENOSPC:

submit_compressed_extents:
659 if (IS_ERR(trans)) {
660 ret = PTR_ERR(trans);
661 } else {
662 trans->block_rsv = &root->fs_info->delalloc_block_rsv;
663 ret = btrfs_reserve_extent(trans, root,

 664                                            async_extent->compressed_size,
 665                                            async_extent->compressed_size,
 666                                            0, alloc_hint, &ins, 1);

667 if (ret)
668 btrfs_abort_transaction(trans, root, ret);

(here it aborts)

669 btrfs_end_transaction(trans, root);
670 }

I've never seen this error before.

david

Here are a couple of lines before and after.

Cheers,
Skender Haxhimolla

[432770.724678] virtuoso-t[17472]: segfault at 8 ip 00000000007f4147 sp 00007fff69250848 error 4 in virtuoso-t[400000+9ad000]
[615597.825359] mythtranscode[10886]: segfault at 4 ip 00000000004243c9 sp 00007fff27970368 error 4 in mythtranscode[400000+68000]
[655330.131113] ------------[ cut here ]------------
[655330.131123] WARNING: at fs/btrfs/super.c:246 __btrfs_abort_transaction+0x46/0xa0()
[655330.131125] Hardware name: Precision WorkStation T3400  
[655330.131126] btrfs: Transaction aborted
[655330.131128] Modules linked in: xt_limit xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ip6table_filter ip6_tables iptable_filter ip_tables x_tables rfcomm bnep bluetooth dm_crypt dvb_usb_cxusb snd_hda_codec_analog dib7000p dibx000_common dvb_usb snd_hda_intel snd_hda_codec snd_hwdep dvb_core snd_pcm snd_seq_midi snd_rawmidi snd_seq_midi_event rc_core dib0070 snd_seq coretemp snd_timer snd_seq_device snd kvm_intel soundcore kvm psmouse snd_page_alloc microcode x38_edac lpc_ich dcdbas serio_raw edac_core ppdev parport_pc lp parport floppy firewire_ohci firewire_core crc_itu_t nouveau tg3 ttm drm_kms_helper drm i2c_algo_bit mxm_wmi video wmi
[655330.131182] Pid: 5838, comm: btrfs-delalloc- Not tainted 3.6.11-custom #1
[655330.131183] Call Trace:
[655330.131190]  [<ffffffff810507dc>] warn_slowpath_common+0x83/0x9c
[655330.131193]  [<ffffffff81050898>] warn_slowpath_fmt+0x46/0x48
[655330.131197]  [<ffffffff81231e0b>] ? btrfs_reserve_extent+0xbd/0x1c6
[655330.131200]  [<ffffffff812208a7>] __btrfs_abort_transaction+0x46/0xa0
[655330.131203]  [<ffffffff812468f4>] submit_compressed_extents+0x1bd/0x415
[655330.131207]  [<ffffffff81246bd2>] async_cow_submit+0x86/0x8b
[655330.131210]  [<ffffffff8125ec27>] run_ordered_completions+0x76/0xd5
[655330.131213]  [<ffffffff8125f49e>] worker_loop+0x183/0x4b9
[655330.131216]  [<ffffffff8125f31b>] ? btrfs_queue_worker+0x288/0x288
[655330.131220]  [<ffffffff8106d9d0>] kthread+0x6f/0x77
[655330.131225]  [<ffffffff815b59c4>] kernel_thread_helper+0x4/0x10
[655330.131228]  [<ffffffff8106d961>] ? kthread_freezable_should_stop+0x48/0x48
[655330.131231]  [<ffffffff815b59c0>] ? gs_change+0x13/0x13
[655330.131233] ---[ end trace bc2b68accbd79b9c ]---
[655330.131235] BTRFS warning (device sda2): Aborting unused transaction.
[655729.210531] BTRFS warning (device sda2): Aborting unused transaction.

Still not with enough information though :)

Unrelated, please look for lines that contain 'btrfs' (there may be none).

Eg. the first line after a filesystem is mounted is

btrfs: disk space caching is enabled

so no need to go before that line

Are there lines like

BTRFS error (device sdc1) in __btrfs_free_extent:5222: error 28

?

thanks,
david

Hi David,

The only lines the have anythinf with btrfs are as follows:

[    2.172063] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    2.172671] fuse init (API version 7.20)
[    2.173039] Btrfs loaded
[    2.173047] msgmni has been set to 7518
[    2.173570] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)

[   32.798088] Adding 3907664k swap on /dev/mapper/cryptswap1.  Priority:-1 extents:1 across:3907664k 
[   33.115076] btrfs: enabling auto defrag
[   33.115081] btrfs: use lzo compression
[  189.880342] device fsid d395ae3b-9894-4fe8-bd85-4ae9ed8bc3b8 devid 1 transid 992207 /dev/sda2
[  190.078452] device fsid 595d0335-bfc5-4c58-9741-f7297cec791b devid 3 transid 22139 /dev/sdb
[  190.078754] device fsid 595d0335-bfc5-4c58-9741-f7297cec791b devid 1 transid 22139 /dev/sdc
[  190.079034] device fsid 595d0335-bfc5-4c58-9741-f7297cec791b devid 2 transid 22139 /dev/sdd
[  190.079162] device fsid 595d0335-bfc5-4c58-9741-f7297cec791b devid 3 transid 22139 /dev/sdb
[  190.080181] btrfs: use lzo compression
[  194.567241] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)

[...]

That lacks information to analyze the error, so let's try it the usual way. I'm not sure if you can grab the state of free space (via btrfs fi df) from the time it happened, but that would at least say if it was the ENOSPC case.

thanks,
david

Here you go

sudo btrfs fi df /media/Storage/
Data, RAID0: total=1.48TB, used=980.54GB
System, RAID1: total=8.00MB, used=112.00KB
System: total=4.00MB, used=0.00
Metadata, RAID1: total=94.00GB, used=1.45GB

sudo btrfs fi df /
Data: total=353.45GB, used=263.93GB
System, DUP: total=40.00MB, used=48.00KB
System: total=4.00MB, used=0.00
Metadata, DUP: total=7.44GB, used=2.17GB
Metadata: total=8.00MB, used=0.00

Cheers,
Skender.