Linux Btrfs filesystem development
 help / color / mirror / Atom feed
* Bug report: filesystem needed btrf-zero-log
@ 2015-04-30 17:17 Goffredo Baroncelli
  2015-04-30 17:33 ` Filipe David Manana
  2015-04-30 17:37 ` Hugo Mills
  0 siblings, 2 replies; 3+ messages in thread
From: Goffredo Baroncelli @ 2015-04-30 17:17 UTC (permalink / raw)
  To: linux-btrfs

Hi All,

after a crash for reason not related to btrfs [*], I was not able to
mount the root filesystem anymore. To investigate a bit I started from
another filesystem and I tried to mount manually the FS.

I run "mount /dev/sdd3 /mnt", but the command never returned.
The system was responsive: I was able to start a kernel compilation, to
launch a new shell... But the mount process stuck in 'D' state.

After 120sec in the log appeared:

Apr 30 18:47:14 venice kernel: INFO: task mount:2043 blocked for more than 120 seconds.
Apr 30 18:47:14 venice kernel:      Tainted: G          I    3.19.1 #193
Apr 30 18:47:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 30 18:47:14 venice kernel: mount           D ffff880215082b20     0  2043   2038 0x00000000
Apr 30 18:47:14 venice kernel: ffff880215082b20 ffff88021519b950 ffff880226239330 0000000000014140
Apr 30 18:47:14 venice kernel: ffff88021519bfd8 0000000000014140 ffff880215082b20 0000000000000246
Apr 30 18:47:14 venice kernel: ffff88021356b910 ffff88021356b978 ffff88021356b990 ffff88021519b8b8
Apr 30 18:47:14 venice kernel: Call Trace:
Apr 30 18:47:14 venice kernel: [<ffffffffa02414e5>] ? btrfs_tree_lock+0x55/0x1f0 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
Apr 30 18:47:14 venice kernel: [<ffffffffa01e567a>] ? btrfs_search_slot+0x71a/0xa90 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa01e5404>] ? btrfs_search_slot+0x4a4/0xa90 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa01e72e2>] ? btrfs_insert_empty_items+0x72/0xc0 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa02417a9>] ? btrfs_insert_orphan_item+0x59/0x80 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa0242d15>] ? insert_orphan_item+0x55/0x80 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa02478b1>] ? replay_one_buffer+0x391/0x3b0 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa0242672>] ? walk_up_log_tree+0xd2/0x240 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa024287c>] ? walk_log_tree+0x9c/0x1b0 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa0203d45>] ? btrfs_get_fs_root.part.45+0x45/0x190 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa0249ce7>] ? btrfs_recover_log_trees+0x1b7/0x440 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa0200580>] ? free_root_pointers+0x70/0x70 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa0247520>] ? replay_one_extent+0x650/0x650 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa0206f04>] ? open_ctree+0x1834/0x20d0 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffffa01dd5b5>] ? btrfs_mount+0x805/0x8d0 [btrfs]
Apr 30 18:47:14 venice kernel: [<ffffffff8116e28f>] ? pcpu_next_unpop+0x3f/0x50
Apr 30 18:47:14 venice kernel: [<ffffffff8116f28b>] ? pcpu_alloc+0x35b/0x690
Apr 30 18:47:14 venice kernel: [<ffffffff811c2356>] ? mount_fs+0x36/0x1b0
Apr 30 18:47:14 venice kernel: [<ffffffff811dd842>] ? vfs_kern_mount+0x62/0x110
Apr 30 18:47:14 venice kernel: [<ffffffff811e05ba>] ? do_mount+0x1fa/0xaf0
Apr 30 18:47:14 venice kernel: [<ffffffff811e11c8>] ? SyS_mount+0x88/0xd0
Apr 30 18:47:14 venice kernel: [<ffffffff815502ed>] ? system_call_fastpath+0x16/0x1b
Apr 30 18:49:14 venice kernel: INFO: task mount:2043 blocked for more than 120 seconds.
Apr 30 18:49:14 venice kernel:      Tainted: G          I    3.19.1 #193
Apr 30 18:49:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 30 18:49:14 venice kernel: mount           D ffff880215082b20     0  2043   2038 0x00000000
Apr 30 18:49:14 venice kernel: ffff880215082b20 ffff88021519b950 ffff880226239330 0000000000014140
Apr 30 18:49:14 venice kernel: ffff88021519bfd8 0000000000014140 ffff880215082b20 0000000000000246
Apr 30 18:49:14 venice kernel: ffff88021356b910 ffff88021356b978 ffff88021356b990 ffff88021519b8b8
Apr 30 18:49:14 venice kernel: Call Trace:
Apr 30 18:49:14 venice kernel: [<ffffffffa02414e5>] ? btrfs_tree_lock+0x55/0x1f0 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
Apr 30 18:49:14 venice kernel: [<ffffffffa01e567a>] ? btrfs_search_slot+0x71a/0xa90 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa01e5404>] ? btrfs_search_slot+0x4a4/0xa90 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa01e72e2>] ? btrfs_insert_empty_items+0x72/0xc0 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa02417a9>] ? btrfs_insert_orphan_item+0x59/0x80 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa0242d15>] ? insert_orphan_item+0x55/0x80 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa02478b1>] ? replay_one_buffer+0x391/0x3b0 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa0242672>] ? walk_up_log_tree+0xd2/0x240 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa024287c>] ? walk_log_tree+0x9c/0x1b0 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa0203d45>] ? btrfs_get_fs_root.part.45+0x45/0x190 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa0249ce7>] ? btrfs_recover_log_trees+0x1b7/0x440 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa0200580>] ? free_root_pointers+0x70/0x70 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa0247520>] ? replay_one_extent+0x650/0x650 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa0206f04>] ? open_ctree+0x1834/0x20d0 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa01dd5b5>] ? btrfs_mount+0x805/0x8d0 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffff8116e28f>] ? pcpu_next_unpop+0x3f/0x50
Apr 30 18:49:14 venice kernel: [<ffffffff8116f28b>] ? pcpu_alloc+0x35b/0x690
Apr 30 18:49:14 venice kernel: [<ffffffff811c2356>] ? mount_fs+0x36/0x1b0
Apr 30 18:49:14 venice kernel: [<ffffffff811dd842>] ? vfs_kern_mount+0x62/0x110
Apr 30 18:49:14 venice kernel: [<ffffffff811e05ba>] ? do_mount+0x1fa/0xaf0
Apr 30 18:49:14 venice kernel: [<ffffffff811e11c8>] ? SyS_mount+0x88/0xd0
Apr 30 18:49:14 venice kernel: [<ffffffff815502ed>] ? system_call_fastpath+0x16/0x1b
Apr 30 18:49:14 venice kernel: INFO: task btrfs-transacti:2065 blocked for more than 120 seconds.
Apr 30 18:49:14 venice kernel:      Tainted: G          I    3.19.1 #193
Apr 30 18:49:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 30 18:49:14 venice kernel: btrfs-transacti D ffff8800bcfac0d0     0  2065      2 0x00000000
Apr 30 18:49:14 venice kernel: ffff8800bcfac0d0 0000000000000000 ffff8802262289e0 0000000000014140
Apr 30 18:49:14 venice kernel: ffff88020c023fd8 0000000000014140 ffff8800bcfac0d0 0000000000000246
Apr 30 18:49:14 venice kernel: ffff8800b88779b8 ffff8800bcc9a800 ffff88020c023e20 ffff880223a69300
Apr 30 18:49:14 venice kernel: Call Trace:
Apr 30 18:49:14 venice kernel: [<ffffffffa0209735>] ? btrfs_commit_transaction+0x365/0x9d0 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
Apr 30 18:49:14 venice kernel: [<ffffffffa0205365>] ? transaction_kthread+0x245/0x260 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffffa0205120>] ? btrfs_cleanup_transaction+0x530/0x530 [btrfs]
Apr 30 18:49:14 venice kernel: [<ffffffff8108a033>] ? kthread+0xd3/0xf0
Apr 30 18:49:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
Apr 30 18:49:14 venice kernel: [<ffffffff8155023c>] ? ret_from_fork+0x7c/0xb0
Apr 30 18:49:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
Apr 30 18:51:09 venice kernel: usb 1-1.1: reset high-speed USB device number 3 using ehci-pci
Apr 30 18:51:14 venice kernel: INFO: task mount:2043 blocked for more than 120 seconds.
Apr 30 18:51:14 venice kernel:      Tainted: G          I    3.19.1 #193
Apr 30 18:51:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 30 18:51:14 venice kernel: mount           D ffff880215082b20     0  2043   2038 0x00000000
Apr 30 18:51:14 venice kernel: ffff880215082b20 ffff88021519b950 ffff880226239330 0000000000014140
Apr 30 18:51:14 venice kernel: ffff88021519bfd8 0000000000014140 ffff880215082b20 0000000000000246
Apr 30 18:51:14 venice kernel: ffff88021356b910 ffff88021356b978 ffff88021356b990 ffff88021519b8b8
Apr 30 18:51:14 venice kernel: Call Trace:
Apr 30 18:51:14 venice kernel: [<ffffffffa02414e5>] ? btrfs_tree_lock+0x55/0x1f0 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
Apr 30 18:51:14 venice kernel: [<ffffffffa01e567a>] ? btrfs_search_slot+0x71a/0xa90 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa01e5404>] ? btrfs_search_slot+0x4a4/0xa90 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa01e72e2>] ? btrfs_insert_empty_items+0x72/0xc0 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa02417a9>] ? btrfs_insert_orphan_item+0x59/0x80 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa0242d15>] ? insert_orphan_item+0x55/0x80 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa02478b1>] ? replay_one_buffer+0x391/0x3b0 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa0242672>] ? walk_up_log_tree+0xd2/0x240 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa024287c>] ? walk_log_tree+0x9c/0x1b0 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa0203d45>] ? btrfs_get_fs_root.part.45+0x45/0x190 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa0249ce7>] ? btrfs_recover_log_trees+0x1b7/0x440 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa0200580>] ? free_root_pointers+0x70/0x70 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa0247520>] ? replay_one_extent+0x650/0x650 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa0206f04>] ? open_ctree+0x1834/0x20d0 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa01dd5b5>] ? btrfs_mount+0x805/0x8d0 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffff8116e28f>] ? pcpu_next_unpop+0x3f/0x50
Apr 30 18:51:14 venice kernel: [<ffffffff8116f28b>] ? pcpu_alloc+0x35b/0x690
Apr 30 18:51:14 venice kernel: [<ffffffff811c2356>] ? mount_fs+0x36/0x1b0
Apr 30 18:51:14 venice kernel: [<ffffffff811dd842>] ? vfs_kern_mount+0x62/0x110
Apr 30 18:51:14 venice kernel: [<ffffffff811e05ba>] ? do_mount+0x1fa/0xaf0
Apr 30 18:51:14 venice kernel: [<ffffffff811e11c8>] ? SyS_mount+0x88/0xd0
Apr 30 18:51:14 venice kernel: [<ffffffff815502ed>] ? system_call_fastpath+0x16/0x1b
Apr 30 18:51:14 venice kernel: INFO: task btrfs-transacti:2065 blocked for more than 120 seconds.
Apr 30 18:51:14 venice kernel:      Tainted: G          I    3.19.1 #193
Apr 30 18:51:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 30 18:51:14 venice kernel: btrfs-transacti D ffff8800bcfac0d0     0  2065      2 0x00000000
Apr 30 18:51:14 venice kernel: ffff8800bcfac0d0 0000000000000000 ffff8802262289e0 0000000000014140
Apr 30 18:51:14 venice kernel: ffff88020c023fd8 0000000000014140 ffff8800bcfac0d0 0000000000000246
Apr 30 18:51:14 venice kernel: ffff8800b88779b8 ffff8800bcc9a800 ffff88020c023e20 ffff880223a69300
Apr 30 18:51:14 venice kernel: Call Trace:
Apr 30 18:51:14 venice kernel: [<ffffffffa0209735>] ? btrfs_commit_transaction+0x365/0x9d0 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
Apr 30 18:51:14 venice kernel: [<ffffffffa0205365>] ? transaction_kthread+0x245/0x260 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffffa0205120>] ? btrfs_cleanup_transaction+0x530/0x530 [btrfs]
Apr 30 18:51:14 venice kernel: [<ffffffff8108a033>] ? kthread+0xd3/0xf0
Apr 30 18:51:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
Apr 30 18:51:14 venice kernel: [<ffffffff8155023c>] ? ret_from_fork+0x7c/0xb0
Apr 30 18:51:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
Apr 30 18:52:08 venice kernel: perf interrupt took too long (2501 > 2500), lowering kernel.perf_event_max_sample_rate to 50000
ffffffffa0205120>] ? btrfs_cleanup_transaction+0x530/0x530 [btrfs]
Apr 30 18:53:14 venice kernel: [<ffffffff8108a033>] ? kthread+0xd3/0xf0
Apr 30 18:53:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
Apr 30 18:53:14 venice kernel: [<ffffffff8155023c>] ? ret_from_fork+0x7c/0xb0
Apr 30 18:53:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180


"btrfs check" (btrfs-progs v4.0) didn't report anything.

Doing a btrfs-zero-log /dev/sdd3, solved this problem and now the 
filesystem works properly.
Before the btrfs-zero-log I made a btrfs-image (~44MB). If someone want to investigate
this issue more I can provide him the image.



The problem appeared both in the kernel 3.19.1 and 3.19.3.

Let me know.

BR
G.Baroncelli

[*] I am developing a driver for a mouse. This driver caused the crash.


-- 
gpg @keyserver.linux.it: Goffredo Baroncelli <kreijackATinwind.it>
Key fingerprint BBF5 1610 0B64 DAC6 5F7D  17B2 0EDA 9B37 8B82 E0B5

^ permalink raw reply	[flat|nested] 3+ messages in thread

* Re: Bug report: filesystem needed btrf-zero-log
  2015-04-30 17:17 Bug report: filesystem needed btrf-zero-log Goffredo Baroncelli
@ 2015-04-30 17:33 ` Filipe David Manana
  2015-04-30 17:37 ` Hugo Mills
  1 sibling, 0 replies; 3+ messages in thread
From: Filipe David Manana @ 2015-04-30 17:33 UTC (permalink / raw)
  To: kreijack; +Cc: linux-btrfs

On Thu, Apr 30, 2015 at 6:17 PM, Goffredo Baroncelli <kreijack@libero.it> wrote:
> Hi All,
>
> after a crash for reason not related to btrfs [*], I was not able to
> mount the root filesystem anymore. To investigate a bit I started from
> another filesystem and I tried to mount manually the FS.
>
> I run "mount /dev/sdd3 /mnt", but the command never returned.
> The system was responsive: I was able to start a kernel compilation, to
> launch a new shell... But the mount process stuck in 'D' state.
>
> After 120sec in the log appeared:
>
> Apr 30 18:47:14 venice kernel: INFO: task mount:2043 blocked for more than 120 seconds.
> Apr 30 18:47:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:47:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:47:14 venice kernel: mount           D ffff880215082b20     0  2043   2038 0x00000000
> Apr 30 18:47:14 venice kernel: ffff880215082b20 ffff88021519b950 ffff880226239330 0000000000014140
> Apr 30 18:47:14 venice kernel: ffff88021519bfd8 0000000000014140 ffff880215082b20 0000000000000246
> Apr 30 18:47:14 venice kernel: ffff88021356b910 ffff88021356b978 ffff88021356b990 ffff88021519b8b8
> Apr 30 18:47:14 venice kernel: Call Trace:
> Apr 30 18:47:14 venice kernel: [<ffffffffa02414e5>] ? btrfs_tree_lock+0x55/0x1f0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:47:14 venice kernel: [<ffffffffa01e567a>] ? btrfs_search_slot+0x71a/0xa90 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa01e5404>] ? btrfs_search_slot+0x4a4/0xa90 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa01e72e2>] ? btrfs_insert_empty_items+0x72/0xc0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa02417a9>] ? btrfs_insert_orphan_item+0x59/0x80 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0242d15>] ? insert_orphan_item+0x55/0x80 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa02478b1>] ? replay_one_buffer+0x391/0x3b0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0242672>] ? walk_up_log_tree+0xd2/0x240 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa024287c>] ? walk_log_tree+0x9c/0x1b0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0203d45>] ? btrfs_get_fs_root.part.45+0x45/0x190 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0249ce7>] ? btrfs_recover_log_trees+0x1b7/0x440 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0200580>] ? free_root_pointers+0x70/0x70 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0247520>] ? replay_one_extent+0x650/0x650 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0206f04>] ? open_ctree+0x1834/0x20d0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa01dd5b5>] ? btrfs_mount+0x805/0x8d0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffff8116e28f>] ? pcpu_next_unpop+0x3f/0x50
> Apr 30 18:47:14 venice kernel: [<ffffffff8116f28b>] ? pcpu_alloc+0x35b/0x690
> Apr 30 18:47:14 venice kernel: [<ffffffff811c2356>] ? mount_fs+0x36/0x1b0
> Apr 30 18:47:14 venice kernel: [<ffffffff811dd842>] ? vfs_kern_mount+0x62/0x110
> Apr 30 18:47:14 venice kernel: [<ffffffff811e05ba>] ? do_mount+0x1fa/0xaf0
> Apr 30 18:47:14 venice kernel: [<ffffffff811e11c8>] ? SyS_mount+0x88/0xd0
> Apr 30 18:47:14 venice kernel: [<ffffffff815502ed>] ? system_call_fastpath+0x16/0x1b
> Apr 30 18:49:14 venice kernel: INFO: task mount:2043 blocked for more than 120 seconds.
> Apr 30 18:49:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:49:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:49:14 venice kernel: mount           D ffff880215082b20     0  2043   2038 0x00000000
> Apr 30 18:49:14 venice kernel: ffff880215082b20 ffff88021519b950 ffff880226239330 0000000000014140
> Apr 30 18:49:14 venice kernel: ffff88021519bfd8 0000000000014140 ffff880215082b20 0000000000000246
> Apr 30 18:49:14 venice kernel: ffff88021356b910 ffff88021356b978 ffff88021356b990 ffff88021519b8b8
> Apr 30 18:49:14 venice kernel: Call Trace:
> Apr 30 18:49:14 venice kernel: [<ffffffffa02414e5>] ? btrfs_tree_lock+0x55/0x1f0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:49:14 venice kernel: [<ffffffffa01e567a>] ? btrfs_search_slot+0x71a/0xa90 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa01e5404>] ? btrfs_search_slot+0x4a4/0xa90 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa01e72e2>] ? btrfs_insert_empty_items+0x72/0xc0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa02417a9>] ? btrfs_insert_orphan_item+0x59/0x80 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0242d15>] ? insert_orphan_item+0x55/0x80 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa02478b1>] ? replay_one_buffer+0x391/0x3b0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0242672>] ? walk_up_log_tree+0xd2/0x240 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa024287c>] ? walk_log_tree+0x9c/0x1b0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0203d45>] ? btrfs_get_fs_root.part.45+0x45/0x190 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0249ce7>] ? btrfs_recover_log_trees+0x1b7/0x440 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0200580>] ? free_root_pointers+0x70/0x70 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0247520>] ? replay_one_extent+0x650/0x650 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0206f04>] ? open_ctree+0x1834/0x20d0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa01dd5b5>] ? btrfs_mount+0x805/0x8d0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffff8116e28f>] ? pcpu_next_unpop+0x3f/0x50
> Apr 30 18:49:14 venice kernel: [<ffffffff8116f28b>] ? pcpu_alloc+0x35b/0x690
> Apr 30 18:49:14 venice kernel: [<ffffffff811c2356>] ? mount_fs+0x36/0x1b0
> Apr 30 18:49:14 venice kernel: [<ffffffff811dd842>] ? vfs_kern_mount+0x62/0x110
> Apr 30 18:49:14 venice kernel: [<ffffffff811e05ba>] ? do_mount+0x1fa/0xaf0
> Apr 30 18:49:14 venice kernel: [<ffffffff811e11c8>] ? SyS_mount+0x88/0xd0
> Apr 30 18:49:14 venice kernel: [<ffffffff815502ed>] ? system_call_fastpath+0x16/0x1b
> Apr 30 18:49:14 venice kernel: INFO: task btrfs-transacti:2065 blocked for more than 120 seconds.
> Apr 30 18:49:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:49:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:49:14 venice kernel: btrfs-transacti D ffff8800bcfac0d0     0  2065      2 0x00000000
> Apr 30 18:49:14 venice kernel: ffff8800bcfac0d0 0000000000000000 ffff8802262289e0 0000000000014140
> Apr 30 18:49:14 venice kernel: ffff88020c023fd8 0000000000014140 ffff8800bcfac0d0 0000000000000246
> Apr 30 18:49:14 venice kernel: ffff8800b88779b8 ffff8800bcc9a800 ffff88020c023e20 ffff880223a69300
> Apr 30 18:49:14 venice kernel: Call Trace:
> Apr 30 18:49:14 venice kernel: [<ffffffffa0209735>] ? btrfs_commit_transaction+0x365/0x9d0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:49:14 venice kernel: [<ffffffffa0205365>] ? transaction_kthread+0x245/0x260 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0205120>] ? btrfs_cleanup_transaction+0x530/0x530 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffff8108a033>] ? kthread+0xd3/0xf0
> Apr 30 18:49:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:49:14 venice kernel: [<ffffffff8155023c>] ? ret_from_fork+0x7c/0xb0
> Apr 30 18:49:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:51:09 venice kernel: usb 1-1.1: reset high-speed USB device number 3 using ehci-pci
> Apr 30 18:51:14 venice kernel: INFO: task mount:2043 blocked for more than 120 seconds.
> Apr 30 18:51:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:51:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:51:14 venice kernel: mount           D ffff880215082b20     0  2043   2038 0x00000000
> Apr 30 18:51:14 venice kernel: ffff880215082b20 ffff88021519b950 ffff880226239330 0000000000014140
> Apr 30 18:51:14 venice kernel: ffff88021519bfd8 0000000000014140 ffff880215082b20 0000000000000246
> Apr 30 18:51:14 venice kernel: ffff88021356b910 ffff88021356b978 ffff88021356b990 ffff88021519b8b8
> Apr 30 18:51:14 venice kernel: Call Trace:
> Apr 30 18:51:14 venice kernel: [<ffffffffa02414e5>] ? btrfs_tree_lock+0x55/0x1f0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:51:14 venice kernel: [<ffffffffa01e567a>] ? btrfs_search_slot+0x71a/0xa90 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa01e5404>] ? btrfs_search_slot+0x4a4/0xa90 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa01e72e2>] ? btrfs_insert_empty_items+0x72/0xc0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa02417a9>] ? btrfs_insert_orphan_item+0x59/0x80 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0242d15>] ? insert_orphan_item+0x55/0x80 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa02478b1>] ? replay_one_buffer+0x391/0x3b0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0242672>] ? walk_up_log_tree+0xd2/0x240 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa024287c>] ? walk_log_tree+0x9c/0x1b0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0203d45>] ? btrfs_get_fs_root.part.45+0x45/0x190 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0249ce7>] ? btrfs_recover_log_trees+0x1b7/0x440 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0200580>] ? free_root_pointers+0x70/0x70 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0247520>] ? replay_one_extent+0x650/0x650 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0206f04>] ? open_ctree+0x1834/0x20d0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa01dd5b5>] ? btrfs_mount+0x805/0x8d0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffff8116e28f>] ? pcpu_next_unpop+0x3f/0x50
> Apr 30 18:51:14 venice kernel: [<ffffffff8116f28b>] ? pcpu_alloc+0x35b/0x690
> Apr 30 18:51:14 venice kernel: [<ffffffff811c2356>] ? mount_fs+0x36/0x1b0
> Apr 30 18:51:14 venice kernel: [<ffffffff811dd842>] ? vfs_kern_mount+0x62/0x110
> Apr 30 18:51:14 venice kernel: [<ffffffff811e05ba>] ? do_mount+0x1fa/0xaf0
> Apr 30 18:51:14 venice kernel: [<ffffffff811e11c8>] ? SyS_mount+0x88/0xd0
> Apr 30 18:51:14 venice kernel: [<ffffffff815502ed>] ? system_call_fastpath+0x16/0x1b
> Apr 30 18:51:14 venice kernel: INFO: task btrfs-transacti:2065 blocked for more than 120 seconds.
> Apr 30 18:51:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:51:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:51:14 venice kernel: btrfs-transacti D ffff8800bcfac0d0     0  2065      2 0x00000000
> Apr 30 18:51:14 venice kernel: ffff8800bcfac0d0 0000000000000000 ffff8802262289e0 0000000000014140
> Apr 30 18:51:14 venice kernel: ffff88020c023fd8 0000000000014140 ffff8800bcfac0d0 0000000000000246
> Apr 30 18:51:14 venice kernel: ffff8800b88779b8 ffff8800bcc9a800 ffff88020c023e20 ffff880223a69300
> Apr 30 18:51:14 venice kernel: Call Trace:
> Apr 30 18:51:14 venice kernel: [<ffffffffa0209735>] ? btrfs_commit_transaction+0x365/0x9d0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:51:14 venice kernel: [<ffffffffa0205365>] ? transaction_kthread+0x245/0x260 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0205120>] ? btrfs_cleanup_transaction+0x530/0x530 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffff8108a033>] ? kthread+0xd3/0xf0
> Apr 30 18:51:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:51:14 venice kernel: [<ffffffff8155023c>] ? ret_from_fork+0x7c/0xb0
> Apr 30 18:51:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:52:08 venice kernel: perf interrupt took too long (2501 > 2500), lowering kernel.perf_event_max_sample_rate to 50000
> ffffffffa0205120>] ? btrfs_cleanup_transaction+0x530/0x530 [btrfs]
> Apr 30 18:53:14 venice kernel: [<ffffffff8108a033>] ? kthread+0xd3/0xf0
> Apr 30 18:53:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:53:14 venice kernel: [<ffffffff8155023c>] ? ret_from_fork+0x7c/0xb0
> Apr 30 18:53:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
>
>
> "btrfs check" (btrfs-progs v4.0) didn't report anything.
>
> Doing a btrfs-zero-log /dev/sdd3, solved this problem and now the
> filesystem works properly.
> Before the btrfs-zero-log I made a btrfs-image (~44MB). If someone want to investigate
> this issue more I can provide him the image.
>
>
>
> The problem appeared both in the kernel 3.19.1 and 3.19.3.

https://btrfs.wiki.kernel.org/index.php/Gotchas


>
> Let me know.
>
> BR
> G.Baroncelli
>
> [*] I am developing a driver for a mouse. This driver caused the crash.
>
>
> --
> gpg @keyserver.linux.it: Goffredo Baroncelli <kreijackATinwind.it>
> Key fingerprint BBF5 1610 0B64 DAC6 5F7D  17B2 0EDA 9B37 8B82 E0B5
> --
> To unsubscribe from this list: send the line "unsubscribe linux-btrfs" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html



-- 
Filipe David Manana,

"Reasonable men adapt themselves to the world.
 Unreasonable men adapt the world to themselves.
 That's why all progress depends on unreasonable men."

^ permalink raw reply	[flat|nested] 3+ messages in thread

* Re: Bug report: filesystem needed btrf-zero-log
  2015-04-30 17:17 Bug report: filesystem needed btrf-zero-log Goffredo Baroncelli
  2015-04-30 17:33 ` Filipe David Manana
@ 2015-04-30 17:37 ` Hugo Mills
  1 sibling, 0 replies; 3+ messages in thread
From: Hugo Mills @ 2015-04-30 17:37 UTC (permalink / raw)
  To: kreijack; +Cc: linux-btrfs

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

On Thu, Apr 30, 2015 at 07:17:22PM +0200, Goffredo Baroncelli wrote:
> Hi All,
> 
> after a crash for reason not related to btrfs [*], I was not able to
> mount the root filesystem anymore. To investigate a bit I started from
> another filesystem and I tried to mount manually the FS.
> 
> I run "mount /dev/sdd3 /mnt", but the command never returned.
> The system was responsive: I was able to start a kernel compilation, to
> launch a new shell... But the mount process stuck in 'D' state.
> 
> After 120sec in the log appeared:

   Known bug... see https://btrfs.wiki.kernel.org/index.php/Gotchas

   Hugo.

> Apr 30 18:47:14 venice kernel: INFO: task mount:2043 blocked for more than 120 seconds.
> Apr 30 18:47:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:47:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:47:14 venice kernel: mount           D ffff880215082b20     0  2043   2038 0x00000000
> Apr 30 18:47:14 venice kernel: ffff880215082b20 ffff88021519b950 ffff880226239330 0000000000014140
> Apr 30 18:47:14 venice kernel: ffff88021519bfd8 0000000000014140 ffff880215082b20 0000000000000246
> Apr 30 18:47:14 venice kernel: ffff88021356b910 ffff88021356b978 ffff88021356b990 ffff88021519b8b8
> Apr 30 18:47:14 venice kernel: Call Trace:
> Apr 30 18:47:14 venice kernel: [<ffffffffa02414e5>] ? btrfs_tree_lock+0x55/0x1f0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:47:14 venice kernel: [<ffffffffa01e567a>] ? btrfs_search_slot+0x71a/0xa90 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa01e5404>] ? btrfs_search_slot+0x4a4/0xa90 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa01e72e2>] ? btrfs_insert_empty_items+0x72/0xc0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa02417a9>] ? btrfs_insert_orphan_item+0x59/0x80 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0242d15>] ? insert_orphan_item+0x55/0x80 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa02478b1>] ? replay_one_buffer+0x391/0x3b0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0242672>] ? walk_up_log_tree+0xd2/0x240 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa024287c>] ? walk_log_tree+0x9c/0x1b0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0203d45>] ? btrfs_get_fs_root.part.45+0x45/0x190 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0249ce7>] ? btrfs_recover_log_trees+0x1b7/0x440 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0200580>] ? free_root_pointers+0x70/0x70 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0247520>] ? replay_one_extent+0x650/0x650 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa0206f04>] ? open_ctree+0x1834/0x20d0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffffa01dd5b5>] ? btrfs_mount+0x805/0x8d0 [btrfs]
> Apr 30 18:47:14 venice kernel: [<ffffffff8116e28f>] ? pcpu_next_unpop+0x3f/0x50
> Apr 30 18:47:14 venice kernel: [<ffffffff8116f28b>] ? pcpu_alloc+0x35b/0x690
> Apr 30 18:47:14 venice kernel: [<ffffffff811c2356>] ? mount_fs+0x36/0x1b0
> Apr 30 18:47:14 venice kernel: [<ffffffff811dd842>] ? vfs_kern_mount+0x62/0x110
> Apr 30 18:47:14 venice kernel: [<ffffffff811e05ba>] ? do_mount+0x1fa/0xaf0
> Apr 30 18:47:14 venice kernel: [<ffffffff811e11c8>] ? SyS_mount+0x88/0xd0
> Apr 30 18:47:14 venice kernel: [<ffffffff815502ed>] ? system_call_fastpath+0x16/0x1b
> Apr 30 18:49:14 venice kernel: INFO: task mount:2043 blocked for more than 120 seconds.
> Apr 30 18:49:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:49:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:49:14 venice kernel: mount           D ffff880215082b20     0  2043   2038 0x00000000
> Apr 30 18:49:14 venice kernel: ffff880215082b20 ffff88021519b950 ffff880226239330 0000000000014140
> Apr 30 18:49:14 venice kernel: ffff88021519bfd8 0000000000014140 ffff880215082b20 0000000000000246
> Apr 30 18:49:14 venice kernel: ffff88021356b910 ffff88021356b978 ffff88021356b990 ffff88021519b8b8
> Apr 30 18:49:14 venice kernel: Call Trace:
> Apr 30 18:49:14 venice kernel: [<ffffffffa02414e5>] ? btrfs_tree_lock+0x55/0x1f0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:49:14 venice kernel: [<ffffffffa01e567a>] ? btrfs_search_slot+0x71a/0xa90 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa01e5404>] ? btrfs_search_slot+0x4a4/0xa90 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa01e72e2>] ? btrfs_insert_empty_items+0x72/0xc0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa02417a9>] ? btrfs_insert_orphan_item+0x59/0x80 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0242d15>] ? insert_orphan_item+0x55/0x80 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa02478b1>] ? replay_one_buffer+0x391/0x3b0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0242672>] ? walk_up_log_tree+0xd2/0x240 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa024287c>] ? walk_log_tree+0x9c/0x1b0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0203d45>] ? btrfs_get_fs_root.part.45+0x45/0x190 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0249ce7>] ? btrfs_recover_log_trees+0x1b7/0x440 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0200580>] ? free_root_pointers+0x70/0x70 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0247520>] ? replay_one_extent+0x650/0x650 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0206f04>] ? open_ctree+0x1834/0x20d0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa01dd5b5>] ? btrfs_mount+0x805/0x8d0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffff8116e28f>] ? pcpu_next_unpop+0x3f/0x50
> Apr 30 18:49:14 venice kernel: [<ffffffff8116f28b>] ? pcpu_alloc+0x35b/0x690
> Apr 30 18:49:14 venice kernel: [<ffffffff811c2356>] ? mount_fs+0x36/0x1b0
> Apr 30 18:49:14 venice kernel: [<ffffffff811dd842>] ? vfs_kern_mount+0x62/0x110
> Apr 30 18:49:14 venice kernel: [<ffffffff811e05ba>] ? do_mount+0x1fa/0xaf0
> Apr 30 18:49:14 venice kernel: [<ffffffff811e11c8>] ? SyS_mount+0x88/0xd0
> Apr 30 18:49:14 venice kernel: [<ffffffff815502ed>] ? system_call_fastpath+0x16/0x1b
> Apr 30 18:49:14 venice kernel: INFO: task btrfs-transacti:2065 blocked for more than 120 seconds.
> Apr 30 18:49:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:49:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:49:14 venice kernel: btrfs-transacti D ffff8800bcfac0d0     0  2065      2 0x00000000
> Apr 30 18:49:14 venice kernel: ffff8800bcfac0d0 0000000000000000 ffff8802262289e0 0000000000014140
> Apr 30 18:49:14 venice kernel: ffff88020c023fd8 0000000000014140 ffff8800bcfac0d0 0000000000000246
> Apr 30 18:49:14 venice kernel: ffff8800b88779b8 ffff8800bcc9a800 ffff88020c023e20 ffff880223a69300
> Apr 30 18:49:14 venice kernel: Call Trace:
> Apr 30 18:49:14 venice kernel: [<ffffffffa0209735>] ? btrfs_commit_transaction+0x365/0x9d0 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:49:14 venice kernel: [<ffffffffa0205365>] ? transaction_kthread+0x245/0x260 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffffa0205120>] ? btrfs_cleanup_transaction+0x530/0x530 [btrfs]
> Apr 30 18:49:14 venice kernel: [<ffffffff8108a033>] ? kthread+0xd3/0xf0
> Apr 30 18:49:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:49:14 venice kernel: [<ffffffff8155023c>] ? ret_from_fork+0x7c/0xb0
> Apr 30 18:49:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:51:09 venice kernel: usb 1-1.1: reset high-speed USB device number 3 using ehci-pci
> Apr 30 18:51:14 venice kernel: INFO: task mount:2043 blocked for more than 120 seconds.
> Apr 30 18:51:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:51:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:51:14 venice kernel: mount           D ffff880215082b20     0  2043   2038 0x00000000
> Apr 30 18:51:14 venice kernel: ffff880215082b20 ffff88021519b950 ffff880226239330 0000000000014140
> Apr 30 18:51:14 venice kernel: ffff88021519bfd8 0000000000014140 ffff880215082b20 0000000000000246
> Apr 30 18:51:14 venice kernel: ffff88021356b910 ffff88021356b978 ffff88021356b990 ffff88021519b8b8
> Apr 30 18:51:14 venice kernel: Call Trace:
> Apr 30 18:51:14 venice kernel: [<ffffffffa02414e5>] ? btrfs_tree_lock+0x55/0x1f0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:51:14 venice kernel: [<ffffffffa01e567a>] ? btrfs_search_slot+0x71a/0xa90 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa01e5404>] ? btrfs_search_slot+0x4a4/0xa90 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa01e72e2>] ? btrfs_insert_empty_items+0x72/0xc0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa02417a9>] ? btrfs_insert_orphan_item+0x59/0x80 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0242d15>] ? insert_orphan_item+0x55/0x80 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa02478b1>] ? replay_one_buffer+0x391/0x3b0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0242672>] ? walk_up_log_tree+0xd2/0x240 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa024287c>] ? walk_log_tree+0x9c/0x1b0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0203d45>] ? btrfs_get_fs_root.part.45+0x45/0x190 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0249ce7>] ? btrfs_recover_log_trees+0x1b7/0x440 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0200580>] ? free_root_pointers+0x70/0x70 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0247520>] ? replay_one_extent+0x650/0x650 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0206f04>] ? open_ctree+0x1834/0x20d0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa01dd5b5>] ? btrfs_mount+0x805/0x8d0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffff8116e28f>] ? pcpu_next_unpop+0x3f/0x50
> Apr 30 18:51:14 venice kernel: [<ffffffff8116f28b>] ? pcpu_alloc+0x35b/0x690
> Apr 30 18:51:14 venice kernel: [<ffffffff811c2356>] ? mount_fs+0x36/0x1b0
> Apr 30 18:51:14 venice kernel: [<ffffffff811dd842>] ? vfs_kern_mount+0x62/0x110
> Apr 30 18:51:14 venice kernel: [<ffffffff811e05ba>] ? do_mount+0x1fa/0xaf0
> Apr 30 18:51:14 venice kernel: [<ffffffff811e11c8>] ? SyS_mount+0x88/0xd0
> Apr 30 18:51:14 venice kernel: [<ffffffff815502ed>] ? system_call_fastpath+0x16/0x1b
> Apr 30 18:51:14 venice kernel: INFO: task btrfs-transacti:2065 blocked for more than 120 seconds.
> Apr 30 18:51:14 venice kernel:      Tainted: G          I    3.19.1 #193
> Apr 30 18:51:14 venice kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> Apr 30 18:51:14 venice kernel: btrfs-transacti D ffff8800bcfac0d0     0  2065      2 0x00000000
> Apr 30 18:51:14 venice kernel: ffff8800bcfac0d0 0000000000000000 ffff8802262289e0 0000000000014140
> Apr 30 18:51:14 venice kernel: ffff88020c023fd8 0000000000014140 ffff8800bcfac0d0 0000000000000246
> Apr 30 18:51:14 venice kernel: ffff8800b88779b8 ffff8800bcc9a800 ffff88020c023e20 ffff880223a69300
> Apr 30 18:51:14 venice kernel: Call Trace:
> Apr 30 18:51:14 venice kernel: [<ffffffffa0209735>] ? btrfs_commit_transaction+0x365/0x9d0 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffff810a8b60>] ? wait_woken+0x90/0x90
> Apr 30 18:51:14 venice kernel: [<ffffffffa0205365>] ? transaction_kthread+0x245/0x260 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffffa0205120>] ? btrfs_cleanup_transaction+0x530/0x530 [btrfs]
> Apr 30 18:51:14 venice kernel: [<ffffffff8108a033>] ? kthread+0xd3/0xf0
> Apr 30 18:51:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:51:14 venice kernel: [<ffffffff8155023c>] ? ret_from_fork+0x7c/0xb0
> Apr 30 18:51:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:52:08 venice kernel: perf interrupt took too long (2501 > 2500), lowering kernel.perf_event_max_sample_rate to 50000
> ffffffffa0205120>] ? btrfs_cleanup_transaction+0x530/0x530 [btrfs]
> Apr 30 18:53:14 venice kernel: [<ffffffff8108a033>] ? kthread+0xd3/0xf0
> Apr 30 18:53:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> Apr 30 18:53:14 venice kernel: [<ffffffff8155023c>] ? ret_from_fork+0x7c/0xb0
> Apr 30 18:53:14 venice kernel: [<ffffffff81089f60>] ? kthread_create_on_node+0x180/0x180
> 
> 
> "btrfs check" (btrfs-progs v4.0) didn't report anything.
> 
> Doing a btrfs-zero-log /dev/sdd3, solved this problem and now the 
> filesystem works properly.
> Before the btrfs-zero-log I made a btrfs-image (~44MB). If someone want to investigate
> this issue more I can provide him the image.
> 
> 
> 
> The problem appeared both in the kernel 3.19.1 and 3.19.3.
> 
> Let me know.
> 
> BR
> G.Baroncelli
> 
> [*] I am developing a driver for a mouse. This driver caused the crash.
> 
> 

-- 
Hugo Mills             | There's many a slip 'twixt wicket-keeper and gully.
hugo@... carfax.org.uk |
http://carfax.org.uk/  |
PGP: E2AB1DE4          |

[-- Attachment #2: Digital signature --]
[-- Type: application/pgp-signature, Size: 836 bytes --]

^ permalink raw reply	[flat|nested] 3+ messages in thread

end of thread, other threads:[~2015-04-30 17:37 UTC | newest]

Thread overview: 3+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2015-04-30 17:17 Bug report: filesystem needed btrf-zero-log Goffredo Baroncelli
2015-04-30 17:33 ` Filipe David Manana
2015-04-30 17:37 ` Hugo Mills

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox