Bug 105681 - kernel BUG at /linux/fs/btrfs/extent-tree.c:1833!
Summary: kernel BUG at /linux/fs/btrfs/extent-tree.c:1833!
Status: NEW
Alias: None
Product: File System
Classification: Unclassified
Component: btrfs (show other bugs)
Hardware: All Linux
: P1 normal
Assignee: Josef Bacik
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2015-10-09 07:06 UTC by Peter Becker
Modified: 2016-03-20 09:57 UTC (History)
6 users (show)

See Also:
Kernel Version: 4.2.3 and 4.1.6
Tree: Mainline
Regression: No


Attachments
full syslog (1.04 MB, text/plain)
2015-10-09 07:08 UTC, Peter Becker
Details

Description Peter Becker 2015-10-09 07:06:31 UTC
At first i add a new device to my btrfs raid1 pool and start balance. After ~5 hours, balanace hangs and cpu-usage goes to 100% (kworker/u4 use all cpu-power).

Oct  9 01:25:25 nas kernel: [16880.495586] kernel BUG at /home/kernel/COD/linux/fs/btrfs/extent-tree.c:1833!
Oct  9 01:25:25 nas kernel: [16880.495603] invalid opcode: 0000 [#1] SMP 
Oct  9 01:25:25 nas kernel: [16880.495614] Modules linked in: xt_nat veth xt_conntrack xt_addrtype br_netfilter nvram dm_thin_pool dm_persistent_data msr dm_bio_prison dm_bufio libcrc32c ir_lirc_codec ir_xmp_decoder lirc_dev ir_mce_kbd_decoder ir_sharp_decoder ir_sony_decoder ir_sanyo_decoder ir_jvc_decoder ir_rc6_decoder ir_rc5_decoder ir_nec_decoder rc_rc6_mce xt_CHECKSUM iptable_mangle ipt_MASQUERADE nf_nat_masquerade_ipv4 iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 mceusb nf_nat_ipv4 rc_core nf_nat nf_conntrack input_leds joydev xt_tcpudp bridge stp llc iptable_filter ip_tables x_tables autofs4 eeepc_wmi asus_wmi sparse_keymap dm_multipath scsi_dh intel_rapl iosf_mbi x86_pkg_temp_thermal intel_powerclamp kvm crct10dif_pclmul crc32_pclmul snd_seq_midi snd_seq_midi_event snd_rawmidi ghash_clmulni_intel cryptd snd_hda_codec_hdmi rfcomm snd_seq serio_raw bnep snd_hda_codec_realtek snd_hda_codec_generic bluetooth snd_hda_intel snd_hda_codec snd_hda_core snd_hwdep snd_pcm snd_seq_device lpc_ich snd_timer mei_me mei snd shpchp mac_hid soundcore parport_pc ppdev nfsd nct6775 hwmon_vid coretemp auth_rpcgss nfs_acl nfs lockd grace binfmt_misc sunrpc lp parport fscache nls_iso8859_1 btrfs xor raid6_pq dm_mirror dm_region_hash dm_log hid_generic usbhid hid uas usb_storage psmouse ahci libahci wmi i915 video i2c_algo_bit drm_kms_helper drm e1000e ptp pps_core
Oct  9 01:25:25 nas kernel: [16880.495944] CPU: 0 PID: 5967 Comm: btrfs Tainted: G     U          4.2.3-040203-generic #201510030832
Oct  9 01:25:25 nas kernel: [16880.495964] Hardware name: ASUS All Series/H87I-PLUS, BIOS 2003 11/05/2014
Oct  9 01:25:25 nas kernel: [16880.495979] task: ffff8800a918b300 ti: ffff8800a9f00000 task.ti: ffff8800a9f00000
Oct  9 01:25:25 nas kernel: [16880.495995] RIP: 0010:[<ffffffffc02db016>]  [<ffffffffc02db016>] insert_inline_extent_backref+0xc6/0xd0 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496028] RSP: 0018:ffff8800a9f03718  EFLAGS: 00010293
Oct  9 01:25:25 nas kernel: [16880.496042] RAX: 0000000000000000 RBX: 0000000000000000 RCX: ffff8800a9f03750
Oct  9 01:25:25 nas kernel: [16880.496060] RDX: 0000000000000001 RSI: 0000000000000001 RDI: 0000000000000000
Oct  9 01:25:25 nas kernel: [16880.496077] RBP: ffff8800a9f03788 R08: 0000000000004000 R09: ffff8800a9f03608
Oct  9 01:25:25 nas kernel: [16880.496093] R10: 0000000000000000 R11: 0000000000000002 R12: ffff880214b11000
Oct  9 01:25:25 nas kernel: [16880.496109] R13: 000006a203798000 R14: 0000000000000000 R15: ffff8800d80c7990
Oct  9 01:25:25 nas kernel: [16880.496125] FS:  00007fd9f02fa900(0000) GS:ffff88021fa00000(0000) knlGS:0000000000000000
Oct  9 01:25:25 nas kernel: [16880.496146] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Oct  9 01:25:25 nas kernel: [16880.496159] CR2: 00007f438fbc2000 CR3: 00000000a90ae000 CR4: 00000000000406f0
Oct  9 01:25:25 nas kernel: [16880.496175] Stack:
Oct  9 01:25:25 nas kernel: [16880.496180]  000006a203798000 0000000000000414 0000000000000000 0000000000000000
Oct  9 01:25:25 nas kernel: [16880.496200]  0000000000000001 ffffffffc02cef1a ffff8800a9f037f8 0000000000002bbd
Oct  9 01:25:25 nas kernel: [16880.496220]  ffff88016f19f2e0 ffff880214b10000 ffff88016f19f2e0 ffff8800d80c7990
Oct  9 01:25:25 nas kernel: [16880.496239] Call Trace:
Oct  9 01:25:25 nas kernel: [16880.496251]  [<ffffffffc02cef1a>] ? btrfs_alloc_path+0x1a/0x20 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496271]  [<ffffffffc02db0b8>] __btrfs_inc_extent_ref.isra.51+0x98/0x250 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496295]  [<ffffffffc02e0d0a>] __btrfs_run_delayed_refs+0xcfa/0x1070 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496316]  [<ffffffff813bda85>] ? __percpu_counter_add+0x55/0x70
Oct  9 01:25:25 nas kernel: [16880.496337]  [<ffffffffc02e3c3e>] btrfs_run_delayed_refs.part.73+0x6e/0x280 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496360]  [<ffffffffc02e3e67>] btrfs_run_delayed_refs+0x17/0x20 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496383]  [<ffffffffc02f7dc9>] btrfs_should_end_transaction+0x49/0x60 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496407]  [<ffffffffc02e2439>] btrfs_drop_snapshot+0x439/0x830 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496431]  [<ffffffffc0346200>] ? invalidate_extent_cache+0x160/0x1a0 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496455]  [<ffffffffc034b2e2>] merge_reloc_roots+0xd2/0x230 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496475]  [<ffffffffc034b696>] relocate_block_group+0x256/0x600 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496495]  [<ffffffffc034bc03>] btrfs_relocate_block_group+0x1c3/0x2d0 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496517]  [<ffffffffc031f9be>] btrfs_relocate_chunk.isra.39+0x3e/0xc0 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496537]  [<ffffffffc0320e3f>] __btrfs_balance+0x48f/0x8c0 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496556]  [<ffffffffc03215ed>] btrfs_balance+0x37d/0x650 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496575]  [<ffffffffc032d7b4>] ? btrfs_ioctl_balance+0x284/0x510 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496594]  [<ffffffffc032d694>] btrfs_ioctl_balance+0x164/0x510 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496613]  [<ffffffffc032fb8f>] btrfs_ioctl+0x56f/0x2470 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496628]  [<ffffffff8118490b>] ? lru_cache_add_active_or_unevictable+0x2b/0xa0
Oct  9 01:25:25 nas kernel: [16880.496645]  [<ffffffff811a4d9a>] ? handle_mm_fault+0xb8a/0x1810
Oct  9 01:25:25 nas kernel: [16880.496658]  [<ffffffff811a8f39>] ? vma_link+0xb9/0xc0
Oct  9 01:25:25 nas kernel: [16880.496670]  [<ffffffff811fc6fd>] do_vfs_ioctl+0x2cd/0x4b0
Oct  9 01:25:25 nas kernel: [16880.496684]  [<ffffffff81063cc7>] ? __do_page_fault+0x1b7/0x430
Oct  9 01:25:25 nas kernel: [16880.496697]  [<ffffffff811fc959>] SyS_ioctl+0x79/0x90
Oct  9 01:25:25 nas kernel: [16880.496709]  [<ffffffff817a9b72>] entry_SYSCALL_64_fastpath+0x16/0x75
Oct  9 01:25:25 nas kernel: [16880.496723] Code: 45 10 49 89 d9 48 8b 55 c8 4c 89 34 24 4c 89 e9 4c 89 fe 4c 89 e7 48 89 44 24 10 8b 45 28 89 44 24 08 e8 4e e4 ff ff 31 c0 eb bb <0f> 0b 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 55 48 89 e5 41 57 
Oct  9 01:25:25 nas kernel: [16880.496802] RIP  [<ffffffffc02db016>] insert_inline_extent_backref+0xc6/0xd0 [btrfs]
Oct  9 01:25:25 nas kernel: [16880.496823]  RSP <ffff8800a9f03718>
Oct  9 01:25:25 nas kernel: [16880.502316] ---[ end trace bcd7d52a2c7cfdc7 ]---
Comment 1 Peter Becker 2015-10-09 07:08:00 UTC
Created attachment 189791 [details]
full syslog
Comment 2 Peter Becker 2015-10-09 07:14:49 UTC
Btrfs progs v4.2.1
Comment 3 Peter Becker 2015-10-09 09:26:32 UTC
Could be the same as: https://bugzilla.opensuse.org/show_bug.cgi?id=940212
Comment 4 Peter Becker 2015-10-10 19:24:37 UTC
I have try it with kernel 4.1.6 and btrfs progs 4.2.2 .. the same error

scrub completes without an error

any hint what i can do now or how i can help to solve this problem?
Comment 5 Peter Becker 2015-10-10 19:47:54 UTC
btrfs balance start -m /media/RAID

complete with out any error but the resulte of device usage is confusing me.
Metadata on sdb and sdc are 2 GiB, but on sdd (the new added device) is 4 GiB. And the 2. one that's confusing me, is that sdd has a "System" entry but sdb and sdc dosn't

floyd@nas ~ $ sudo btrfs dev us /media/RAID/
/dev/sdb, ID: 1
   Device size:             2.73TiB
   Data,RAID1:              2.11TiB
   Metadata,RAID1:          2.00GiB
   System,RAID1:           32.00MiB
   Unallocated:           628.49GiB

/dev/sdc, ID: 2
   Device size:             2.73TiB
   Data,RAID1:              2.11TiB
   Metadata,RAID1:          2.00GiB
   Unallocated:           628.52GiB

/dev/sdd, ID: 3
   Device size:             2.73TiB
   Data,RAID1:            792.00GiB
   Metadata,RAID1:          4.00GiB
   System,RAID1:           32.00MiB
   Unallocated:             1.95TiB
Comment 6 Peter Becker 2015-10-10 21:22:12 UTC
I'm must correct me .. sdb and sdd has a "System" entry and "sdc" not
Comment 7 Peter Becker 2015-10-11 11:04:03 UTC
I also see some of this blocks in my syslog: Bug 105791

[ 3840.616538] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3840.616541]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3840.616542] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3840.616543] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3840.616545]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3840.616547]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3840.616548]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3840.616549] Call Trace:
[ 3840.616555]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3840.616569]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3840.616572]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3840.616576]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3840.616579]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3840.616584]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3840.616592]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3840.616598]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3840.616604]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3840.616609]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3840.616615]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3840.616621]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3840.616626]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3840.616631]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3840.616636]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3840.616637]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3840.616638]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3840.616640]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3840.616641]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
Comment 8 Peter Becker 2015-10-11 11:16:58 UTC
dmesg:

[ 3000.613310] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3000.613314]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3000.613314] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3000.613315] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3000.613318]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3000.613319]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3000.613321]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3000.613322] Call Trace:
[ 3000.613327]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3000.613341]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3000.613344]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3000.613349]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3000.613351]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3000.613356]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3000.613364]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3000.613370]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3000.613376]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3000.613382]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3000.613388]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3000.613395]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3000.613400]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3000.613406]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3000.613411]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3000.613412]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3000.613414]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3000.613415]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3000.613416]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3120.613665] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3120.613669]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3120.613669] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3120.613670] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3120.613672]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3120.613674]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3120.613675]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3120.613676] Call Trace:
[ 3120.613682]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3120.613695]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3120.613698]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3120.613702]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3120.613704]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3120.613709]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3120.613716]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3120.613721]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3120.613727]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3120.613732]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3120.613744]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3120.613749]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3120.613754]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3120.613759]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3120.613764]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3120.613766]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3120.613767]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3120.613768]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3120.613769]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3240.614145] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3240.614148]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3240.614149] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3240.614150] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3240.614152]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3240.614154]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3240.614155]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3240.614156] Call Trace:
[ 3240.614161]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3240.614174]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3240.614177]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3240.614181]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3240.614183]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3240.614188]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3240.614194]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3240.614200]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3240.614206]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3240.614211]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3240.614217]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3240.614222]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3240.614227]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3240.614232]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3240.614236]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3240.614238]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3240.614239]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3240.614240]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3240.614241]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3360.614630] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3360.614634]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3360.614635] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3360.614636] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3360.614638]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3360.614640]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3360.614641]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3360.614642] Call Trace:
[ 3360.614647]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3360.614660]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3360.614663]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3360.614667]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3360.614669]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3360.614674]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3360.614681]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3360.614687]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3360.614692]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3360.614698]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3360.614703]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3360.614709]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3360.614713]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3360.614718]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3360.614723]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3360.614724]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3360.614725]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3360.614727]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3360.614728]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3480.615115] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3480.615118]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3480.615118] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3480.615119] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3480.615122]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3480.615124]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3480.615125]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3480.615126] Call Trace:
[ 3480.615131]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3480.615145]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3480.615148]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3480.615152]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3480.615154]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3480.615159]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3480.615166]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3480.615171]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3480.615177]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3480.615182]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3480.615187]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3480.615193]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3480.615198]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3480.615203]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3480.615207]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3480.615209]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3480.615210]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3480.615211]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3480.615212]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3600.615585] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3600.615589]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3600.615589] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3600.615590] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3600.615593]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3600.615594]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3600.615596]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3600.615597] Call Trace:
[ 3600.615602]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3600.615616]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3600.615619]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3600.615623]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3600.615625]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3600.615630]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3600.615637]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3600.615642]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3600.615647]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3600.615653]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3600.615658]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3600.615664]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3600.615669]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3600.615673]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3600.615678]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3600.615680]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3600.615681]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3600.615682]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3600.615683]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3720.616073] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3720.616076]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3720.616077] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3720.616078] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3720.616080]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3720.616082]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3720.616083]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3720.616084] Call Trace:
[ 3720.616089]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3720.616103]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3720.616106]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3720.616111]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3720.616113]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3720.616119]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3720.616127]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3720.616133]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3720.616138]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3720.616144]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3720.616150]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3720.616155]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3720.616161]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3720.616166]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3720.616170]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3720.616172]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3720.616173]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3720.616175]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3720.616176]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3840.616538] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3840.616541]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3840.616542] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3840.616543] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3840.616545]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3840.616547]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3840.616548]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3840.616549] Call Trace:
[ 3840.616555]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3840.616569]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3840.616572]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3840.616576]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3840.616579]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3840.616584]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3840.616592]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3840.616598]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3840.616604]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3840.616609]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3840.616615]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3840.616621]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3840.616626]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3840.616631]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3840.616636]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3840.616637]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3840.616638]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3840.616640]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3840.616641]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3960.617033] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 3960.617037]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 3960.617037] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3960.617038] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 3960.617041]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 3960.617042]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 3960.617043]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 3960.617045] Call Trace:
[ 3960.617050]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 3960.617064]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 3960.617067]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 3960.617072]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 3960.617075]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 3960.617081]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 3960.617088]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 3960.617095]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 3960.617101]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 3960.617106]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 3960.617112]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 3960.617118]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 3960.617123]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 3960.617128]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 3960.617133]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 3960.617134]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 3960.617136]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 3960.617137]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 3960.617138]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 4080.617509] INFO: task btrfs-transacti:11423 blocked for more than 120 seconds.
[ 4080.617512]       Tainted: G     U          4.2.3-040203-generic #201510030832
[ 4080.617513] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 4080.617514] btrfs-transacti D 0000000000000000     0 11423      2 0x00000000
[ 4080.617516]  ffff88007bd2bb58 0000000000000046 ffff880215d7e600 ffff8802121d2640
[ 4080.617518]  0000000000000246 ffff88007bd2c000 ffff8801aa607928 ffff8801aa607940
[ 4080.617519]  ffff88007bd2bba8 ffff8801aa607920 ffff88007bd2bb78 ffffffff817a6357
[ 4080.617520] Call Trace:
[ 4080.617525]  [<ffffffff817a6357>] schedule+0x37/0x80
[ 4080.617539]  [<ffffffffc0332038>] btrfs_tree_lock+0x98/0x1c0 [btrfs]
[ 4080.617542]  [<ffffffff810b6fd0>] ? prepare_to_wait_event+0xf0/0xf0
[ 4080.617546]  [<ffffffffc02d4095>] btrfs_search_slot+0x6e5/0x9c0 [btrfs]
[ 4080.617548]  [<ffffffff81180ef7>] ? __set_page_dirty_nobuffers+0xe7/0x140
[ 4080.617553]  [<ffffffffc02ed8cf>] btrfs_lookup_inode+0x2f/0xa0 [btrfs]
[ 4080.617560]  [<ffffffffc034db13>] ? btrfs_delete_delayed_items+0x313/0x330 [btrfs]
[ 4080.617566]  [<ffffffffc034d484>] ? btrfs_insert_delayed_items+0xa4/0x420 [btrfs]
[ 4080.617571]  [<ffffffffc034dd3a>] __btrfs_update_delayed_inode+0x5a/0x1e0 [btrfs]
[ 4080.617576]  [<ffffffffc034e03c>] __btrfs_run_delayed_items+0x17c/0x210 [btrfs]
[ 4080.617582]  [<ffffffffc034e2e3>] btrfs_run_delayed_items+0x13/0x20 [btrfs]
[ 4080.617587]  [<ffffffffc02f85c8>] btrfs_commit_transaction+0x2c8/0xb10 [btrfs]
[ 4080.617592]  [<ffffffffc02f8ea3>] ? start_transaction+0x93/0x580 [btrfs]
[ 4080.617597]  [<ffffffffc02f395a>] transaction_kthread+0x1ba/0x240 [btrfs]
[ 4080.617601]  [<ffffffffc02f37a0>] ? btrfs_cleanup_transaction+0x540/0x540 [btrfs]
[ 4080.617603]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[ 4080.617604]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[ 4080.617605]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[ 4080.617606]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[44929.058013] ------------[ cut here ]------------
[44929.058035] WARNING: CPU: 0 PID: 39 at /home/kernel/COD/linux/drivers/gpu/drm/i915/intel_uncore.c:70 assert_device_not_suspended.isra.11+0x53/0x60 [i915]()
[44929.058036] Device suspended
[44929.058037] Modules linked in: xt_nat veth xt_conntrack xt_addrtype br_netfilter nvram msr dm_thin_pool dm_persistent_data dm_bio_prison dm_bufio libcrc32c ir_lirc_codec lirc_dev ir_xmp_decoder ir_sony_decoder ir_sharp_decoder ir_mce_kbd_decoder ir_sanyo_decoder ir_rc6_decoder ir_nec_decoder ir_jvc_decoder ir_rc5_decoder rc_rc6_mce xt_CHECKSUM iptable_mangle mceusb ipt_MASQUERADE nf_nat_masquerade_ipv4 rc_core input_leds iptable_nat nf_conntrack_ipv4 joydev nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack xt_tcpudp bridge stp llc iptable_filter ip_tables x_tables autofs4 eeepc_wmi asus_wmi sparse_keymap snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic snd_hda_intel snd_hda_codec snd_hda_core dm_multipath snd_hwdep scsi_dh snd_pcm intel_rapl iosf_mbi x86_pkg_temp_thermal snd_seq_midi snd_seq_midi_event intel_powerclamp kvm snd_rawmidi crct10dif_pclmul crc32_pclmul snd_seq rfcomm bnep ghash_clmulni_intel bluetooth snd_seq_device cryptd snd_timer serio_raw snd lpc_ich mei_me mei soundcore shpchp mac_hid parport_pc ppdev nct6775 hwmon_vid coretemp lp parport nfsd auth_rpcgss nfs_acl nfs binfmt_misc lockd grace sunrpc fscache btrfs xor raid6_pq nls_iso8859_1 dm_mirror dm_region_hash dm_log hid_generic usbhid hid uas usb_storage psmouse ahci libahci wmi i915 video i2c_algo_bit drm_kms_helper drm e1000e ptp pps_core
[44929.058088] CPU: 0 PID: 39 Comm: kswapd0 Tainted: G     U          4.2.3-040203-generic #201510030832
[44929.058089] Hardware name: ASUS All Series/H87I-PLUS, BIOS 2003 11/05/2014
[44929.058090]  ffffffffc01e5c78 ffff8800d5edb988 ffffffff817a2803 0000000000000000
[44929.058092]  ffff8800d5edb9d8 ffff8800d5edb9c8 ffffffff810771da ffff88020a942d28
[44929.058093]  ffff8802120b0000 0000000000100010 0000000000100010 ffff8802120b0080
[44929.058094] Call Trace:
[44929.058098]  [<ffffffff817a2803>] dump_stack+0x45/0x57
[44929.058100]  [<ffffffff810771da>] warn_slowpath_common+0x8a/0xc0
[44929.058102]  [<ffffffff81077256>] warn_slowpath_fmt+0x46/0x50
[44929.058110]  [<ffffffffc0169cc3>] assert_device_not_suspended.isra.11+0x53/0x60 [i915]
[44929.058116]  [<ffffffffc016b61a>] hsw_write32+0x3a/0x130 [i915]
[44929.058123]  [<ffffffffc0149eb0>] i915_gem_write_fence+0x210/0x520 [i915]
[44929.058125]  [<ffffffff813a5ea1>] ? __sg_free_table+0x71/0x90
[44929.058131]  [<ffffffffc014a266>] i915_gem_object_update_fence+0xa6/0xd0 [i915]
[44929.058137]  [<ffffffffc014a31a>] i915_gem_object_put_fence+0x8a/0xd0 [i915]
[44929.058142]  [<ffffffffc014a4e7>] i915_vma_unbind+0x187/0x230 [i915]
[44929.058148]  [<ffffffffc014d2d6>] i915_gem_shrink+0x156/0x230 [i915]
[44929.058153]  [<ffffffffc014d43d>] i915_gem_shrinker_scan+0x8d/0xa0 [i915]
[44929.058156]  [<ffffffff81186b37>] shrink_slab+0x207/0x380
[44929.058157]  [<ffffffff8118b1f1>] shrink_zone+0x291/0x2b0
[44929.058158]  [<ffffffff8118c160>] kswapd+0x500/0x9b0
[44929.058159]  [<ffffffff8118bc60>] ? mem_cgroup_shrink_node_zone+0x130/0x130
[44929.058160]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[44929.058161]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[44929.058163]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[44929.058164]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[44929.058165] ---[ end trace 975c39f656782ad7 ]---
[44929.058171] ------------[ cut here ]------------
[44929.058179] WARNING: CPU: 0 PID: 39 at /home/kernel/COD/linux/drivers/gpu/drm/i915/intel_uncore.c:620 hsw_unclaimed_reg_debug.isra.14+0x78/0xa0 [i915]()
[44929.058179] Unclaimed register detected before reading register 0x100010
[44929.058180] Modules linked in: xt_nat veth xt_conntrack xt_addrtype br_netfilter nvram msr dm_thin_pool dm_persistent_data dm_bio_prison dm_bufio libcrc32c ir_lirc_codec lirc_dev ir_xmp_decoder ir_sony_decoder ir_sharp_decoder ir_mce_kbd_decoder ir_sanyo_decoder ir_rc6_decoder ir_nec_decoder ir_jvc_decoder ir_rc5_decoder rc_rc6_mce xt_CHECKSUM iptable_mangle mceusb ipt_MASQUERADE nf_nat_masquerade_ipv4 rc_core input_leds iptable_nat nf_conntrack_ipv4 joydev nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack xt_tcpudp bridge stp llc iptable_filter ip_tables x_tables autofs4 eeepc_wmi asus_wmi sparse_keymap snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic snd_hda_intel snd_hda_codec snd_hda_core dm_multipath snd_hwdep scsi_dh snd_pcm intel_rapl iosf_mbi x86_pkg_temp_thermal snd_seq_midi snd_seq_midi_event intel_powerclamp kvm snd_rawmidi crct10dif_pclmul crc32_pclmul snd_seq rfcomm bnep ghash_clmulni_intel bluetooth snd_seq_device cryptd snd_timer serio_raw snd lpc_ich mei_me mei soundcore shpchp mac_hid parport_pc ppdev nct6775 hwmon_vid coretemp lp parport nfsd auth_rpcgss nfs_acl nfs binfmt_misc lockd grace sunrpc fscache btrfs xor raid6_pq nls_iso8859_1 dm_mirror dm_region_hash dm_log hid_generic usbhid hid uas usb_storage psmouse ahci libahci wmi i915 video i2c_algo_bit drm_kms_helper drm e1000e ptp pps_core
[44929.058204] CPU: 0 PID: 39 Comm: kswapd0 Tainted: G     U  W       4.2.3-040203-generic #201510030832
[44929.058205] Hardware name: ASUS All Series/H87I-PLUS, BIOS 2003 11/05/2014
[44929.058205]  ffffffffc01e5c78 ffff8800d5edb968 ffffffff817a2803 0000000000000000
[44929.058206]  ffff8800d5edb9b8 ffff8800d5edb9a8 ffffffff810771da ffff8800d5edb988
[44929.058207]  ffff8802120b0078 ffff8802120b0000 ffff8802120b0080 ffff8802120b0078
[44929.058208] Call Trace:
[44929.058210]  [<ffffffff817a2803>] dump_stack+0x45/0x57
[44929.058211]  [<ffffffff810771da>] warn_slowpath_common+0x8a/0xc0
[44929.058212]  [<ffffffff81077256>] warn_slowpath_fmt+0x46/0x50
[44929.058219]  [<ffffffffc0169e58>] hsw_unclaimed_reg_debug.isra.14+0x78/0xa0 [i915]
[44929.058225]  [<ffffffffc016bd9f>] gen6_read32+0x5f/0x1a0 [i915]
[44929.058231]  [<ffffffffc016b686>] ? hsw_write32+0xa6/0x130 [i915]
[44929.058237]  [<ffffffffc0149ec0>] i915_gem_write_fence+0x220/0x520 [i915]
[44929.058238]  [<ffffffff813a5ea1>] ? __sg_free_table+0x71/0x90
[44929.058244]  [<ffffffffc014a266>] i915_gem_object_update_fence+0xa6/0xd0 [i915]
[44929.058249]  [<ffffffffc014a31a>] i915_gem_object_put_fence+0x8a/0xd0 [i915]
[44929.058254]  [<ffffffffc014a4e7>] i915_vma_unbind+0x187/0x230 [i915]
[44929.058259]  [<ffffffffc014d2d6>] i915_gem_shrink+0x156/0x230 [i915]
[44929.058264]  [<ffffffffc014d43d>] i915_gem_shrinker_scan+0x8d/0xa0 [i915]
[44929.058266]  [<ffffffff81186b37>] shrink_slab+0x207/0x380
[44929.058267]  [<ffffffff8118b1f1>] shrink_zone+0x291/0x2b0
[44929.058268]  [<ffffffff8118c160>] kswapd+0x500/0x9b0
[44929.058269]  [<ffffffff8118bc60>] ? mem_cgroup_shrink_node_zone+0x130/0x130
[44929.058270]  [<ffffffff81094e69>] kthread+0xc9/0xe0
[44929.058271]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[44929.058272]  [<ffffffff817a9f9f>] ret_from_fork+0x3f/0x70
[44929.058273]  [<ffffffff81094da0>] ? kthread_create_on_node+0x180/0x180
[44929.058274] ---[ end trace 975c39f656782ad8 ]---
Comment 9 Peter Becker 2015-10-11 11:28:38 UTC
scrub device /dev/sdb (id 1) done
	scrub started at Sun Oct 11 00:41:06 2015 and finished after 05:36:43
	total bytes scrubbed: 2.10TiB with 0 errors
scrub device /dev/sdc (id 2) done
	scrub started at Sun Oct 11 00:41:06 2015 and finished after 05:11:29
	total bytes scrubbed: 2.10TiB with 0 errors
scrub device /dev/sdd (id 3) done
	scrub started at Sun Oct 11 00:41:06 2015 and finished after 01:48:51
	total bytes scrubbed: 772.22GiB with 0 errors
Comment 10 Peter Becker 2015-10-11 19:25:06 UTC
sudo btrfs check --readonly /media/RAID

Superblock bytenr is larger than device size
Couldn't open file system
Comment 11 Peter Becker 2015-10-11 20:54:32 UTC
btrfs check --readonly /dev/sdb

http://pastebin.com/UxkeVd7Y

many entrys with "extent buffer leak"
Comment 12 Peter Becker 2015-10-11 20:55:17 UTC
btrfs-show-super -i0 /dev/sd[bcd] 
btrfs-show-super -i1 /dev/sd[bcd] 
btrfs-show-super -i2 /dev/sd[bcd]

sdb: http://pastebin.com/zs7B8827
sdc: http://pastebin.com/Kn1kwgYv
sdd: http://pastebin.com/CHC52ef7
Comment 13 Peter Becker 2015-10-12 18:13:07 UTC
sudo btrfs check --repair /dev/sdb
enabling repair mode
Checking filesystem on /dev/sdb
UUID: cba641be-f5a2-444b-af88-359f7edae8aa
checking extents
Fixed 0 roots.
checking free space cache
cache and super generation don't match, space cache will be invalidated
checking fs roots
checking csums
checking root refs
checking quota groups
found 2770194741781 bytes used err is 0
total csum bytes: 2702120812
total tree bytes: 3223027712
total fs tree bytes: 299204608
total extent tree bytes: 91504640
btree space waste bytes: 175969438
file data blocks allocated: 4480820461568
 referenced 4366171271168
btrfs-progs v4.2.2
extent buffer leak: start 8789296545792 len 16384
extent buffer leak: start 8786100617216 len 16384
extent buffer leak: start 8786447040512 len 16384
extent buffer leak: start 8786639388672 len 16384
extent buffer leak: start 8786665259008 len 16384
extent buffer leak: start 8787662929920 len 16384
extent buffer leak: start 8787663683584 len 16384
extent buffer leak: start 8787663880192 len 16384
extent buffer leak: start 8787663978496 len 16384
extent buffer leak: start 8788232437760 len 16384
extent buffer leak: start 8788232519680 len 16384
extent buffer leak: start 8788232634368 len 16384
extent buffer leak: start 8788232667136 len 16384
extent buffer leak: start 8788232699904 len 16384
extent buffer leak: start 8788232732672 len 16384
extent buffer leak: start 8788232798208 len 16384
extent buffer leak: start 8788232830976 len 16384
extent buffer leak: start 8788232863744 len 16384
extent buffer leak: start 8788611612672 len 16384
extent buffer leak: start 8788612579328 len 16384
extent buffer leak: start 8788614594560 len 16384
extent buffer leak: start 8788692369408 len 16384
extent buffer leak: start 8788692598784 len 16384
extent buffer leak: start 8788693598208 len 16384
extent buffer leak: start 8788693811200 len 16384
extent buffer leak: start 8788693827584 len 16384
extent buffer leak: start 8788693843968 len 16384
extent buffer leak: start 8788693925888 len 16384
extent buffer leak: start 8788701691904 len 16384
extent buffer leak: start 8788702560256 len 16384
extent buffer leak: start 8788703559680 len 16384
extent buffer leak: start 8788721221632 len 16384
extent buffer leak: start 8788722515968 len 16384
extent buffer leak: start 8788724924416 len 16384
extent buffer leak: start 8788728348672 len 16384
extent buffer leak: start 8788731133952 len 16384
extent buffer leak: start 8788732575744 len 16384
extent buffer leak: start 8788734853120 len 16384
extent buffer leak: start 8788739686400 len 16384
extent buffer leak: start 8789296496640 len 16384
extent buffer leak: start 8789296562176 len 16384
extent buffer leak: start 8789296644096 len 16384
extent buffer leak: start 8789296676864 len 16384
extent buffer leak: start 8789296709632 len 16384
extent buffer leak: start 8789296742400 len 16384
extent buffer leak: start 8789296775168 len 16384
extent buffer leak: start 8789296807936 len 16384
extent buffer leak: start 8789296840704 len 16384
extent buffer leak: start 8789296873472 len 16384
extent buffer leak: start 8789296922624 len 16384
extent buffer leak: start 8789296955392 len 16384
extent buffer leak: start 8789306015744 len 16384
extent buffer leak: start 8789306507264 len 16384
extent buffer leak: start 8789306540032 len 16384
extent buffer leak: start 8789306589184 len 16384
extent buffer leak: start 8789331361792 len 16384
extent buffer leak: start 8789340651520 len 16384
extent buffer leak: start 8789340684288 len 16384
extent buffer leak: start 8789340717056 len 16384
extent buffer leak: start 8789340749824 len 16384
extent buffer leak: start 8789340782592 len 16384
extent buffer leak: start 8789340815360 len 16384
extent buffer leak: start 8789340848128 len 16384
extent buffer leak: start 8789340880896 len 16384
extent buffer leak: start 8789340913664 len 16384
extent buffer leak: start 8789340946432 len 16384
extent buffer leak: start 8789340979200 len 16384
extent buffer leak: start 8789353512960 len 16384
extent buffer leak: start 8789827715072 len 16384
extent buffer leak: start 8789828255744 len 16384
extent buffer leak: start 8789828616192 len 16384
extent buffer leak: start 8789829730304 len 16384
extent buffer leak: start 8790050111488 len 16384
extent buffer leak: start 8790050308096 len 16384
extent buffer leak: start 8790050340864 len 16384
extent buffer leak: start 8790050390016 len 16384
extent buffer leak: start 8790050455552 len 16384
extent buffer leak: start 8790050488320 len 16384
extent buffer leak: start 8790050504704 len 16384
extent buffer leak: start 8790050570240 len 16384
extent buffer leak: start 8790050619392 len 16384
extent buffer leak: start 8790050717696 len 16384
extent buffer leak: start 8790050750464 len 16384
extent buffer leak: start 8790104932352 len 16384
extent buffer leak: start 8790113943552 len 16384
Comment 14 Kyle 2015-10-21 14:03:57 UTC
Just encountered this on Arch Linux v4.2.3-1 and btrfs-progs v4.2.2-1.

My RAID1 array lost a device due to failure recent and went from 4 to 3 devices.  To move data off the missing device I had issued:

btrfs balance start -f -ddevid=4 -mdevid=4 -sdevid=4 /mnt/btrfs

After several hours the system was hung reporting this same bug line.

Btrfs check revealed no problems (log @ https://gist.github.com/8eaa0c23a5e4712476a0 )

It seems that my system triggered the bug by starting a scheduled back-up on the volume that was undergoing the balance.  The system had run for hours balancing without issue until the back-up ran.

Perhaps there is a race condition with the creation/deletion/modification of files while balancing?
Comment 15 bugzilla.kernel.org 2015-11-26 22:04:06 UTC
I'm experiencing the same issue while removing a device from my system. btrfs check doesn't find any errors. The system completely locks up, I had to use net console to log the error. I'm running kernel "Linux nasbak 4.2.6-040206-generic #201511091832 SMP Mon Nov 9 23:34:22 UTC 2015 x86_64 x86_64 x86_64 GNU/Linux" with the latest btrfs tools compiled from the github repository (btrfs-progs v4.3.1).


[ 2542.444227] ------------[ cut here ]------------
[ 2542.444309] kernel BUG at /home/kernel/COD/linux/fs/btrfs/extent-tree.c:1833!
[ 2542.444408] invalid opcode: 0000 [#1] SMP
[ 2542.444478] Modules linked in: cuse bridge stp llc deflate ctr twofish_generic twofish_x86_64_3way twofish_x86_64 twofish_common camellia_generic dm_thin_pool camellia_x86_64 dm_persistent_data dm_bio_prison dm_bufio libcrc32c serpent_sse2_x86_64 xts serpent_generic lrw gf128mul glue_helper blowfish_generic blowfish_x86_64 blowfish_common cast5_generic cast_common ablk_helper cryptd des_generic cmac xcbc rmd160 crypto_null af_key xfrm_algo nfsd auth_rpcgss nfs_acl nfs lockd grace sunrpc fscache ip6t_REJECT nf_reject_ipv6 dm_crypt xt_hl ip6t_rt nf_conntrack_ipv6 nf_defrag_ipv6 ppdev ipt_REJECT nf_reject_ipv4 xt_comment xt_recent xt_multiport kvm xt_limit xt_tcpudp xt_addrtype ipt_MASQUERADE nf_nat_masquerade_ipv4 iptable_nat nf_nat_ipv4 nf_conntrack_ipv4 nf_defrag_ipv4 serio_raw xt_conntrack edac_core k10temp edac_mce_amd ip6table_filter i2c_piix4 ip6_tables nf_conntrack_netbios_ns nf_conntrack_broadcast nf_nat_ftp nf_nat nf_conntrack_ftp joydev nf_conntrack iptable_filter snd_hda_codec_via snd_hda_codec_generic snd_hda_codec_hdmi input_leds ip_tables x_tables snd_hda_intel snd_hda_codec snd_hda_core snd_hwdep snd_pcm snd_timer snd soundcore 8250_fintek parport_pc asus_atk0110 shpchp mac_hid netconsole configfs lp parport btrfs xor raid6_pq pata_acpi hid_generic usbhid hid uas usb_storage amdkfd amd_iommu_v2 radeon psmouse i2c_algo_bit pata_atiixp ttm drm_kms_helper r8169 wmi mii drm sata_sil24 ahci libahci
[ 2542.446979] CPU: 0 PID: 142 Comm: kworker/u12:7 Not tainted 4.2.6-040206-generic #201511091832
[ 2542.447090] Hardware name: System manufacturer System Product Name/M4A78LT-M, BIOS 0802    08/24/2010
[ 2542.447237] Workqueue: btrfs-extent-refs btrfs_extent_refs_helper [btrfs]
[ 2542.447329] task: ffff8800c95a9900 ti: ffff880212e4c000 task.ti: ffff880212e4c000
[ 2542.447427] RIP: 0010:[<ffffffffc0350d07>]  [<ffffffffc0350d07>] insert_inline_extent_backref+0xe7/0xf0 [btrfs]
[ 2542.447574] RSP: 0018:ffff880212e4fac8  EFLAGS: 00010293
[ 2542.447646] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000
[ 2542.447739] RDX: ffff880000000000 RSI: 0000000000000001 RDI: 0000000000000000
[ 2542.447832] RBP: ffff880212e4fb48 R08: 0000000000004000 R09: ffff880212e4f9c0
[ 2542.447925] R10: 0000000000000000 R11: 0000000000000003 R12: ffff8800cb1e1800
[ 2542.448018] R13: ffff88004c4cef30 R14: 0000199d4bff8000 R15: 0000000000000000
[ 2542.448111] FS:  00007ff3c9940700(0000) GS:ffff88021fc00000(0000) knlGS:0000000000000000
[ 2542.448215] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[ 2542.448291] CR2: 00007f70ec02ca28 CR3: 00000000bce95000 CR4: 00000000000006f0
[ 2542.448385] Stack:
[ 2542.448416]  0000199d4bff8000 0000000000011711 0000000000000001 0000000000000000
[ 2542.448536]  ffff880200000001 ffffffff811de3aa ffff880212e4fb28 ffffffffc0343e7a
[ 2542.448657]  0000000000003b92 000000000655f589 ffff8802130e3000 0000000000000000
[ 2542.448778] Call Trace:
[ 2542.448818]  [<ffffffff811de3aa>] ? kmem_cache_alloc+0x1ca/0x200
[ 2542.448908]  [<ffffffffc0343e7a>] ? btrfs_alloc_path+0x1a/0x20 [btrfs]
[ 2542.449005]  [<ffffffffc0351219>] __btrfs_inc_extent_ref.isra.52+0xa9/0x270 [btrfs]
[ 2542.449107]  [<ffffffff817db68c>] ? __schedule+0x36c/0x950
[ 2542.449193]  [<ffffffffc03569fc>] __btrfs_run_delayed_refs+0xc6c/0x1080 [btrfs]
[ 2542.449289]  [<ffffffff810a4e2d>] ? ttwu_do_wakeup+0x1d/0xd0
[ 2542.449377]  [<ffffffffc0359b1e>] btrfs_run_delayed_refs.part.73+0x6e/0x270 [btrfs]
[ 2542.449487]  [<ffffffffc0359dbe>] delayed_ref_async_start+0x7e/0x90 [btrfs]
[ 2542.449593]  [<ffffffffc03a036a>] btrfs_scrubparity_helper+0xca/0x290 [btrfs]
[ 2542.449700]  [<ffffffffc03a056e>] btrfs_extent_refs_helper+0xe/0x10 [btrfs]
[ 2542.449792]  [<ffffffff8109413a>] process_one_work+0x1aa/0x440
[ 2542.449871]  [<ffffffff8109441b>] worker_thread+0x4b/0x4c0
[ 2542.449946]  [<ffffffff810943d0>] ? process_one_work+0x440/0x440
[ 2542.450028]  [<ffffffff810943d0>] ? process_one_work+0x440/0x440
[ 2542.450109]  [<ffffffff8109a798>] kthread+0xd8/0xf0
[ 2542.450176]  [<ffffffff8109a6c0>] ? kthread_create_on_node+0x1b0/0x1b0
[ 2542.450263]  [<ffffffff817e011f>] ret_from_fork+0x3f/0x70
[ 2542.450336]  [<ffffffff8109a6c0>] ? kthread_create_on_node+0x1b0/0x1b0
[ 2542.450422] Code: 10 49 89 d9 48 8b 55 c0 4c 89 7c 24 10 4c 89 f1 4c 89 ee 4c 89 e7 89 44 24 08 48 8b 45 20 48 89 04 24 e8 dd d4 ff ff 31 c0 eb ac <0f> 0b e8 92 a4 d2 c0 66 90 0f 1f 44 00 00 55 48 89 e5 41 57 41
[ 2542.451118] RIP  [<ffffffffc0350d07>] insert_inline_extent_backref+0xe7/0xf0 [btrfs]
[ 2542.451238]  RSP <ffff880212e4fac8>
[ 2542.503919] ---[ end trace 35f589f57ae41b90 ]---
[ 2542.504213] BUG: unable to handle kernel paging request at ffffffffffffffd8
[ 2542.504327] IP: [<ffffffff8109adc0>] kthread_data+0x10/0x20
[ 2542.504415] PGD 1c0f067 PUD 1c11067 PMD 0
[ 2542.504505] Oops: 0000 [#2] SMP
[ 2542.504574] Modules linked in: cuse bridge stp llc deflate ctr twofish_generic twofish_x86_64_3way twofish_x86_64 twofish_common camellia_generic dm_thin_pool camellia_x86_64 dm_persistent_data dm_bio_prison dm_bufio libcrc32c serpent_sse2_x86_64 xts serpent_generic lrw gf128mul glue_helper blowfish_generic blowfish_x86_64 blowfish_common cast5_generic cast_common ablk_helper cryptd des_generic cmac xcbc rmd160 crypto_null af_key xfrm_algo nfsd auth_rpcgss nfs_acl nfs lockd grace sunrpc fscache ip6t_REJECT nf_reject_ipv6 dm_crypt xt_hl ip6t_rt nf_conntrack_ipv6 nf_defrag_ipv6 ppdev ipt_REJECT nf_reject_ipv4 xt_comment xt_recent xt_multiport kvm xt_limit xt_tcpudp xt_addrtype ipt_MASQUERADE nf_nat_masquerade_ipv4 iptable_nat nf_nat_ipv4 nf_conntrack_ipv4 nf_defrag_ipv4 serio_raw xt_conntrack edac_core k10temp edac_mce_amd ip6table_filter i2c_piix4 ip6_tables nf_conntrack_netbios_ns nf_conntrack_broadcast nf_nat_ftp nf_nat nf_conntrack_ftp joydev nf_conntrack iptable_filter snd_hda_codec_via snd_hda_codec_generic snd_hda_codec_hdmi input_leds ip_tables x_tables snd_hda_intel snd_hda_codec snd_hda_core snd_hwdep snd_pcm snd_timer snd soundcore 8250_fintek parport_pc asus_atk0110 shpchp mac_hid netconsole configfs lp parport btrfs xor raid6_pq pata_acpi hid_generic usbhid hid uas usb_storage amdkfd amd_iommu_v2 radeon psmouse i2c_algo_bit pata_atiixp ttm drm_kms_helper r8169 wmi mii drm sata_sil24 ahci libahci
[ 2542.507473] CPU: 0 PID: 142 Comm: kworker/u12:7 Tainted: G      D         4.2.6-040206-generic #201511091832
[ 2542.514862] Hardware name: System manufacturer System Product Name/M4A78LT-M, BIOS 0802    08/24/2010
[ 2542.522317] task: ffff8800c95a9900 ti: ffff880212e4c000 task.ti: ffff880212e4c000
[ 2542.529808] RIP: 0010:[<ffffffff8109adc0>]  [<ffffffff8109adc0>] kthread_data+0x10/0x20
[ 2542.537333] RSP: 0018:ffff880212e4f758  EFLAGS: 00010092
[ 2542.544824] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 000000000000000e
[ 2542.552401] RDX: 000000000000000e RSI: 0000000000000000 RDI: ffff8800c95a9900
[ 2542.559952] RBP: ffff880212e4f758 R08: 00000000ffffffff R09: 0000000000000000
[ 2542.567482] R10: ffff8802132c9958 R11: 000000000000001a R12: 00000000000164c0
[ 2542.574969] R13: ffff88021fc164c0 R14: ffff8800c95a9900 R15: 0000000000000000
[ 2542.582260] FS:  00007f422fec7740(0000) GS:ffff88021fc00000(0000) knlGS:0000000000000000
[ 2542.589393] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[ 2542.596319] CR2: 0000000000000028 CR3: 00000000c3f1f000 CR4: 00000000000006f0
[ 2542.603069] Stack:
[ 2542.609590]  ffff880212e4f778 ffffffff81095465 ffff880212e4f778 ffff88021fc164c0
[ 2542.616041]  ffff880212e4f7c8 ffffffff817db92d ffff880200000000 ffff8800c95a9900
[ 2542.622276]  ffff880212e4f7c8 ffff880212e50000 ffff8800c95a9fc8 ffff880212e4f390
[ 2542.628452] Call Trace:
[ 2542.634519]  [<ffffffff81095465>] wq_worker_sleeping+0x15/0xa0
[ 2542.640610]  [<ffffffff817db92d>] __schedule+0x60d/0x950
[ 2542.646627]  [<ffffffff817dbca7>] schedule+0x37/0x80
[ 2542.652574]  [<ffffffff8107dff2>] do_exit+0x822/0xb10
[ 2542.658494]  [<ffffffff810184a5>] oops_end+0xa5/0xe0
[ 2542.664422]  [<ffffffff8101896b>] die+0x4b/0x70
[ 2542.670325]  [<ffffffff81015251>] do_trap+0xb1/0x140
[ 2542.676228]  [<ffffffff81015609>] do_error_trap+0x89/0x110
[ 2542.682132]  [<ffffffffc0350d07>] ? insert_inline_extent_backref+0xe7/0xf0 [btrfs]
[ 2542.688044]  [<ffffffffc03494d2>] ? btrfs_search_slot+0x952/0xa10 [btrfs]
[ 2542.693940]  [<ffffffffc0387d05>] ? btrfs_get_token_64+0x105/0x120 [btrfs]
[ 2542.699854]  [<ffffffff81015bd0>] do_invalid_op+0x20/0x30
[ 2542.705683]  [<ffffffff817e161e>] invalid_op+0x1e/0x30
[ 2542.711483]  [<ffffffffc0350d07>] ? insert_inline_extent_backref+0xe7/0xf0 [btrfs]
[ 2542.717303]  [<ffffffffc0350c88>] ? insert_inline_extent_backref+0x68/0xf0 [btrfs]
[ 2542.723059]  [<ffffffff811de3aa>] ? kmem_cache_alloc+0x1ca/0x200
[ 2542.728797]  [<ffffffffc0343e7a>] ? btrfs_alloc_path+0x1a/0x20 [btrfs]
[ 2542.734536]  [<ffffffffc0351219>] __btrfs_inc_extent_ref.isra.52+0xa9/0x270 [btrfs]
[ 2542.740294]  [<ffffffff817db68c>] ? __schedule+0x36c/0x950
[ 2542.746037]  [<ffffffffc03569fc>] __btrfs_run_delayed_refs+0xc6c/0x1080 [btrfs]
[ 2542.751794]  [<ffffffff810a4e2d>] ? ttwu_do_wakeup+0x1d/0xd0
[ 2542.757536]  [<ffffffffc0359b1e>] btrfs_run_delayed_refs.part.73+0x6e/0x270 [btrfs]
[ 2542.763305]  [<ffffffffc0359dbe>] delayed_ref_async_start+0x7e/0x90 [btrfs]
[ 2542.769075]  [<ffffffffc03a036a>] btrfs_scrubparity_helper+0xca/0x290 [btrfs]
[ 2542.774843]  [<ffffffffc03a056e>] btrfs_extent_refs_helper+0xe/0x10 [btrfs]
[ 2542.780577]  [<ffffffff8109413a>] process_one_work+0x1aa/0x440
[ 2542.786315]  [<ffffffff8109441b>] worker_thread+0x4b/0x4c0
[ 2542.792038]  [<ffffffff810943d0>] ? process_one_work+0x440/0x440
[ 2542.797773]  [<ffffffff810943d0>] ? process_one_work+0x440/0x440
[ 2542.803470]  [<ffffffff8109a798>] kthread+0xd8/0xf0
[ 2542.809197]  [<ffffffff8109a6c0>] ? kthread_create_on_node+0x1b0/0x1b0
[ 2542.814953]  [<ffffffff817e011f>] ret_from_fork+0x3f/0x70
[ 2542.820693]  [<ffffffff8109a6c0>] ? kthread_create_on_node+0x1b0/0x1b0
[ 2542.826453] Code: ff ff ff be 2b 02 00 00 48 c7 c7 90 9a aa 81 e8 37 07 fe ff e9 a6 fe ff ff 66 90 0f 1f 44 00 00 48 8b 87 08 05 00 00 55 48 89 e5 <48> 8b 40 d8 5d c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00
[ 2542.833162] RIP  [<ffffffff8109adc0>] kthread_data+0x10/0x20
[ 2542.839079]  RSP <ffff880212e4f758>
[ 2542.844798] CR2: ffffffffffffffd8
[ 2542.850465] ---[ end trace 35f589f57ae41b91 ]---
[ 2542.856145] Fixing recursive fault but reboot is needed!
Comment 16 y_t 2015-12-15 12:31:51 UTC
Dear all,
I seem to have the same issue. BTRFS was running stable for a long time now. But today I try to remove a device from my system and my home server keeps freezing after some minutes. Managed to relocate about 0.09TiB with 4 freezes/reboots from devid 4 now.
	devid    1 size 3.64TiB used 3.17TiB path /dev/mapper/hitachi4tb1
	devid    4 size 3.64TiB used 2.75TiB path /dev/mapper/hitachi4tb2
	devid    5 size 5.46TiB used 4.57TiB path /dev/mapper/wdred6tb
	devid    6 size 5.46TiB used 2.45TiB path /dev/mapper/wdred6tb2
btrfs-progs v4.0
Linux server 4.2.0-19-generic #23-Ubuntu SMP Wed Nov 11 11:39:30 UTC 2015 x86_64 x86_64 x86_64 GNU/Linux

sudo btrfs device delete /dev/mapper/hitachi4tb2 /data
->
Dec 15 13:00:03 server kernel: [ 1097.041852] BTRFS info (device dm-4): found 1698 extents
Dec 15 13:00:08 server kernel: [ 1101.186721] BTRFS info (device dm-4): relocating block group 32640841285632 flags 1
Dec 15 13:00:28 server kernel: [ 1122.113340] BTRFS info (device dm-4): found 1771 extents
Dec 15 13:02:29 server kernel: [ 1242.331160] BTRFS info (device dm-4): found 1771 extents
Dec 15 13:02:33 server kernel: [ 1246.890472] BTRFS info (device dm-4): relocating block group 32638693801984 flags 1
Dec 15 13:02:57 server kernel: [ 1270.455640] BTRFS info (device dm-4): found 1739 extents
Dec 15 13:03:30 server kernel: [ 1303.367707] ------------[ cut here ]------------
Dec 15 13:03:30 server kernel: [ 1303.367728] kernel BUG at /build/linux-26_gwp/linux-4.2.0/fs/btrfs/extent-tree.c:1833!
Dec 15 13:03:30 server kernel: [ 1303.367751] invalid opcode: 0000 [#1] SMP 
Dec 15 13:03:30 server kernel: [ 1303.367766] Modules linked in: cls_fw xt_length xt_owner ip6table_mangle ip6_tables xt_tcpudp xt_mark sch_sfq sch_htb iptable_mangle iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack iptable_filter ip_tables x_tables bridge stp llc intel_rapl iosf_mbi x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm snd_hda_codec_hdmi serio_raw snd_hda_intel snd_hda_codec lpc_ich joydev input_leds snd_hda_core snd_hwdep snd_pcm mei_me mei snd_timer snd soundcore shpchp 8250_fintek tpm_infineon mac_hid nfsd auth_rpcgss nfs_acl lockd grace sunrpc autofs4 btrfs xor raid6_pq drbg ansi_cprng xts gf128mul algif_skcipher af_alg dm_crypt hid_generic mxm_wmi pata_acpi crct10dif_pclmul crc32_pclmul usbhid i915 ahci i2c_algo_bit drm_kms_helper cryptd psmouse pata_marvell libahci hid drm r8169 mii wmi video
Dec 15 13:03:30 server kernel: [ 1303.368061] CPU: 1 PID: 154 Comm: kworker/u4:9 Not tainted 4.2.0-19-generic #23-Ubuntu
Dec 15 13:03:30 server kernel: [ 1303.368084] Hardware name: MSI MS-7817/H81M-P33 (MS-7817), BIOS V1.2 09/17/2013
Dec 15 13:03:30 server kernel: [ 1303.368120] Workqueue: btrfs-extent-refs btrfs_extent_refs_helper [btrfs]
Dec 15 13:03:30 server kernel: [ 1303.368141] task: ffff88030c6e9980 ti: ffff88030c764000 task.ti: ffff88030c764000
Dec 15 13:03:30 server kernel: [ 1303.368162] RIP: 0010:[<ffffffffc0333d07>]  [<ffffffffc0333d07>] insert_inline_extent_backref+0xe7/0xf0 [btrfs]
Dec 15 13:03:30 server kernel: [ 1303.368197] RSP: 0018:ffff88030c767ac8  EFLAGS: 00010293
Dec 15 13:03:30 server kernel: [ 1303.368212] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
Dec 15 13:03:30 server kernel: [ 1303.368232] RDX: ffff880000000000 RSI: 0000000000000001 RDI: 0000000000000000
Dec 15 13:03:30 server kernel: [ 1303.368252] RBP: ffff88030c767b48 R08: 0000000000004000 R09: ffff88030c7679c0
Dec 15 13:03:30 server kernel: [ 1303.368272] R10: 0000000000000000 R11: 0000000000000002 R12: ffff88030bd36800
Dec 15 13:03:30 server kernel: [ 1303.368292] R13: ffff8801907a0120 R14: 0000000000000000 R15: 0000000000000000
Dec 15 13:03:30 server kernel: [ 1303.368313] FS:  0000000000000000(0000) GS:ffff88031fb00000(0000) knlGS:0000000000000000
Dec 15 13:03:30 server kernel: [ 1303.368335] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Dec 15 13:03:30 server kernel: [ 1303.368352] CR2: 00007fe2b3038680 CR3: 00000002358a6000 CR4: 00000000000406e0
Dec 15 13:03:30 server kernel: [ 1303.368372] Stack:
Dec 15 13:03:30 server kernel: [ 1303.368378]  0000000000000000 0000000000000005 0000000000000000 0000000000000000
Dec 15 13:03:30 server kernel: [ 1303.368403]  0000000000000001 ffffffff811de5ea 0000000000270026 ffffffffc0326e7a
Dec 15 13:03:30 server kernel: [ 1303.368428]  0000000000002e0f 000000003e01e9b6 ffff88030bd34800 0000000000000000
Dec 15 13:03:30 server kernel: [ 1303.368453] Call Trace:
Dec 15 13:03:30 server kernel: [ 1303.368464]  [<ffffffff811de5ea>] ? kmem_cache_alloc+0x1ca/0x200
Dec 15 13:03:30 server kernel: [ 1303.368486]  [<ffffffffc0326e7a>] ? btrfs_alloc_path+0x1a/0x20 [btrfs]
Dec 15 13:03:30 server kernel: [ 1303.368509]  [<ffffffffc0334219>] __btrfs_inc_extent_ref.isra.52+0xa9/0x270 [btrfs]
Dec 15 13:03:30 server kernel: [ 1303.368536]  [<ffffffffc03399fc>] __btrfs_run_delayed_refs+0xc6c/0x1080 [btrfs]
Dec 15 13:03:30 server kernel: [ 1303.368562]  [<ffffffffc033cb1e>] btrfs_run_delayed_refs.part.73+0x6e/0x270 [btrfs]
Dec 15 13:03:30 server kernel: [ 1303.368588]  [<ffffffffc033cdbe>] delayed_ref_async_start+0x7e/0x90 [btrfs]
Dec 15 13:03:30 server kernel: [ 1303.368617]  [<ffffffffc038336a>] btrfs_scrubparity_helper+0xca/0x290 [btrfs]
Dec 15 13:03:30 server kernel: [ 1303.368644]  [<ffffffffc038356e>] btrfs_extent_refs_helper+0xe/0x10 
[remaining lost due to freeze]

btrfs-progs v4.0
Data, single: total=12.92TiB, used=12.71TiB
System, single: total=32.00MiB, used=1.59MiB
Metadata, single: total=26.00GiB, used=20.56GiB
GlobalReserve, single: total=512.00MiB, used=0.00B
[/dev/mapper/hitachi4tb1].write_io_errs   0
[/dev/mapper/hitachi4tb1].read_io_errs    0
[/dev/mapper/hitachi4tb1].flush_io_errs   0
[/dev/mapper/hitachi4tb1].corruption_errs 0
[/dev/mapper/hitachi4tb1].generation_errs 0
[/dev/mapper/hitachi4tb2].write_io_errs   0
[/dev/mapper/hitachi4tb2].read_io_errs    0
[/dev/mapper/hitachi4tb2].flush_io_errs   0
[/dev/mapper/hitachi4tb2].corruption_errs 0
[/dev/mapper/hitachi4tb2].generation_errs 0
[/dev/mapper/wdred6tb].write_io_errs   0
[/dev/mapper/wdred6tb].read_io_errs    0
[/dev/mapper/wdred6tb].flush_io_errs   0
[/dev/mapper/wdred6tb].corruption_errs 0
[/dev/mapper/wdred6tb].generation_errs 0
[/dev/mapper/wdred6tb2].write_io_errs   0
[/dev/mapper/wdred6tb2].read_io_errs    0
[/dev/mapper/wdred6tb2].flush_io_errs   0
[/dev/mapper/wdred6tb2].corruption_errs 0
[/dev/mapper/wdred6tb2].generation_errs 0
Comment 17 bugzilla.kernel.org 2015-12-15 19:03:39 UTC
For me the fix was upgrading to the latest 4.4. Kernel (still in release candidate status)

This issue already seemed to be fixed. Unfortunately I can't remember the name of the patch.
Comment 18 y_t 2015-12-15 21:43:25 UTC
(In reply to bugzilla.kernel.org from comment #17)
> For me the fix was upgrading to the latest 4.4. Kernel (still in release
> candidate status)
> This issue already seemed to be fixed. Unfortunately I can't remember the
> name of the patch.

Thank you very much for that hint! Installed kernel 4.4rc5 and the "device delete" is now running fine since almost one hour.

Seems to be a good idea for the future to try new kernels when there is a bug...

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