Bug#908216: btrfs blocked for more than 120 seconds

From	"Russell Mosemann" <rmosemann@futurefoam.com>
Newsgroups	linux.debian.bugs.dist, linux.debian.kernel
Subject	Bug#908216: btrfs blocked for more than 120 seconds
Date	2019-02-20 01:40 +0100
Message-ID	<xto5z-378-3@gated-at.bofh.it> (permalink)
References	<wvon0-eY-13@gated-at.bofh.it>
Organization	linux.* mail to news gateway

Cross-posted to 2 groups.

Show all headers | View raw

[Multipart message — attachments visible in raw view] - view raw

 
Package: src:linux
Version: 4.19.12-1~bpo9+1
Severity: important
 
 
Feb 19 19:01:04 vhost182 kernel: INFO: task btrfs-transacti:613 blocked for more than 120 seconds.
Feb 19 19:01:04 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:01:04 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:01:04 vhost182 kernel: btrfs-transacti D    0   613      2 0x80000000
Feb 19 19:01:04 vhost182 kernel: Call Trace:
Feb 19 19:01:04 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:01:04 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:01:04 vhost182 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:01:04 vhost182 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  btrfs_write_dirty_block_groups+0x109/0x3d0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? btrfs_run_delayed_refs+0x88/0x1b0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  commit_cowonly_roots+0x245/0x2f0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  btrfs_commit_transaction+0x36f/0x8a0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:01:04 vhost182 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:01:04 vhost182 kernel:  ret_from_fork+0x35/0x40
Feb 19 19:01:04 vhost182 kernel: INFO: task kworker/u24:4:5280 blocked for more than 120 seconds.
Feb 19 19:01:04 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:01:04 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:01:04 vhost182 kernel: kworker/u24:4   D    0  5280      2 0x80000000
Feb 19 19:01:04 vhost182 kernel: Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
Feb 19 19:01:04 vhost182 kernel: Call Trace:
Feb 19 19:01:04 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:01:04 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:01:04 vhost182 kernel:  wait_current_trans+0xb9/0xf0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:01:04 vhost182 kernel:  start_transaction+0x201/0x3e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  btrfs_finish_ordered_io+0x48d/0x7e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? wb_workfn+0x3ac/0x450
Feb 19 19:01:04 vhost182 kernel:  normal_work_helper+0xd0/0x320 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  process_one_work+0x191/0x370
Feb 19 19:01:04 vhost182 kernel:  worker_thread+0x4f/0x3b0
Feb 19 19:01:04 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:01:04 vhost182 kernel:  ? rescuer_thread+0x340/0x340
Feb 19 19:01:04 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:01:04 vhost182 kernel:  ret_from_fork+0x35/0x40
Feb 19 19:01:04 vhost182 kernel: INFO: task kworker/u24:0:7477 blocked for more than 120 seconds.
Feb 19 19:01:04 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:01:04 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:01:04 vhost182 kernel: kworker/u24:0   D    0  7477      2 0x80000000
Feb 19 19:01:04 vhost182 kernel: Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
Feb 19 19:01:04 vhost182 kernel: Call Trace:
Feb 19 19:01:04 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:01:04 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:01:04 vhost182 kernel:  wait_current_trans+0xb9/0xf0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:01:04 vhost182 kernel:  start_transaction+0x201/0x3e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  btrfs_finish_ordered_io+0x48d/0x7e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  normal_work_helper+0xd0/0x320 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? move_linked_works+0x6e/0xa0
Feb 19 19:01:04 vhost182 kernel:  process_one_work+0x191/0x370
Feb 19 19:01:04 vhost182 kernel:  worker_thread+0x4f/0x3b0
Feb 19 19:01:04 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:01:04 vhost182 kernel:  ? rescuer_thread+0x340/0x340
Feb 19 19:01:04 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:01:04 vhost182 kernel:  ret_from_fork+0x35/0x40
Feb 19 19:01:04 vhost182 kernel: INFO: task kworker/u24:5:7480 blocked for more than 120 seconds.
Feb 19 19:01:04 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:01:04 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:01:04 vhost182 kernel: kworker/u24:5   D    0  7480      2 0x80000000
Feb 19 19:01:04 vhost182 kernel: Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
Feb 19 19:01:04 vhost182 kernel: Call Trace:
Feb 19 19:01:04 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:01:04 vhost182 kernel:  ? _raw_spin_lock_irqsave+0x32/0x40
Feb 19 19:01:04 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:01:04 vhost182 kernel:  wait_current_trans+0xb9/0xf0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:01:04 vhost182 kernel:  start_transaction+0x201/0x3e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  btrfs_finish_ordered_io+0x48d/0x7e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? __switch_to_asm+0x34/0x70
Feb 19 19:01:04 vhost182 kernel:  ? __switch_to_asm+0x40/0x70
Feb 19 19:01:04 vhost182 kernel:  ? __switch_to+0x8c/0x450
Feb 19 19:01:04 vhost182 kernel:  normal_work_helper+0xd0/0x320 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? __schedule+0x3fd/0x880
Feb 19 19:01:04 vhost182 kernel:  process_one_work+0x191/0x370
Feb 19 19:01:04 vhost182 kernel:  worker_thread+0x4f/0x3b0
Feb 19 19:01:04 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:01:04 vhost182 kernel:  ? rescuer_thread+0x340/0x340
Feb 19 19:01:04 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:01:04 vhost182 kernel:  ret_from_fork+0x35/0x40
Feb 19 19:01:04 vhost182 kernel: INFO: task kworker/u24:11:8600 blocked for more than 120 seconds.
Feb 19 19:01:04 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:01:04 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:01:04 vhost182 kernel: kworker/u24:11  D    0  8600      2 0x80000000
Feb 19 19:01:04 vhost182 kernel: Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
Feb 19 19:01:04 vhost182 kernel: Call Trace:
Feb 19 19:01:04 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:01:04 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:01:04 vhost182 kernel:  wait_current_trans+0xb9/0xf0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:01:04 vhost182 kernel:  start_transaction+0x201/0x3e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  btrfs_finish_ordered_io+0x48d/0x7e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  normal_work_helper+0xd0/0x320 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? move_linked_works+0x6e/0xa0
Feb 19 19:01:04 vhost182 kernel:  process_one_work+0x191/0x370
Feb 19 19:01:04 vhost182 kernel:  worker_thread+0x4f/0x3b0
Feb 19 19:01:04 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:01:04 vhost182 kernel:  ? rescuer_thread+0x340/0x340
Feb 19 19:01:04 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:01:04 vhost182 kernel:  ret_from_fork+0x35/0x40
Feb 19 19:01:04 vhost182 kernel: INFO: task kworker/u24:17:8606 blocked for more than 120 seconds.
Feb 19 19:01:04 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:01:04 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:01:04 vhost182 kernel: kworker/u24:17  D    0  8606      2 0x80000000
Feb 19 19:01:04 vhost182 kernel: Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
Feb 19 19:01:04 vhost182 kernel: Call Trace:
Feb 19 19:01:04 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:01:04 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:01:04 vhost182 kernel:  wait_current_trans+0xb9/0xf0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:01:04 vhost182 kernel:  start_transaction+0x201/0x3e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  btrfs_finish_ordered_io+0x48d/0x7e0 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  normal_work_helper+0xd0/0x320 [btrfs]
Feb 19 19:01:04 vhost182 kernel:  ? move_linked_works+0x6e/0xa0
Feb 19 19:01:04 vhost182 kernel:  process_one_work+0x191/0x370
Feb 19 19:01:04 vhost182 kernel:  worker_thread+0x4f/0x3b0
Feb 19 19:01:04 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:01:04 vhost182 kernel:  ? rescuer_thread+0x340/0x340
Feb 19 19:01:04 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:01:04 vhost182 kernel:  ret_from_fork+0x35/0x40
Feb 19 19:03:05 vhost182 kernel: INFO: task btrfs-transacti:613 blocked for more than 120 seconds.
Feb 19 19:03:05 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:03:05 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:03:05 vhost182 kernel: btrfs-transacti D    0   613      2 0x80000000
Feb 19 19:03:05 vhost182 kernel: Call Trace:
Feb 19 19:03:05 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:03:05 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:03:05 vhost182 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:03:05 vhost182 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  btrfs_write_dirty_block_groups+0x109/0x3d0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? btrfs_run_delayed_refs+0x88/0x1b0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  commit_cowonly_roots+0x245/0x2f0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  btrfs_commit_transaction+0x36f/0x8a0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:03:05 vhost182 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:03:05 vhost182 kernel:  ret_from_fork+0x35/0x40
Feb 19 19:03:05 vhost182 kernel: INFO: task kworker/u24:4:5280 blocked for more than 120 seconds.
Feb 19 19:03:05 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:03:05 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:03:05 vhost182 kernel: kworker/u24:4   D    0  5280      2 0x80000000
Feb 19 19:03:05 vhost182 kernel: Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
Feb 19 19:03:05 vhost182 kernel: Call Trace:
Feb 19 19:03:05 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:03:05 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:03:05 vhost182 kernel:  wait_current_trans+0xb9/0xf0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:03:05 vhost182 kernel:  start_transaction+0x201/0x3e0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  btrfs_finish_ordered_io+0x48d/0x7e0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? wb_workfn+0x3ac/0x450
Feb 19 19:03:05 vhost182 kernel:  normal_work_helper+0xd0/0x320 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  process_one_work+0x191/0x370
Feb 19 19:03:05 vhost182 kernel:  worker_thread+0x4f/0x3b0
Feb 19 19:03:05 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:03:05 vhost182 kernel:  ? rescuer_thread+0x340/0x340
Feb 19 19:03:05 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:03:05 vhost182 kernel:  ret_from_fork+0x35/0x40
Feb 19 19:03:05 vhost182 kernel: INFO: task kworker/u24:0:7477 blocked for more than 120 seconds.
Feb 19 19:03:05 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:03:05 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:03:05 vhost182 kernel: kworker/u24:0   D    0  7477      2 0x80000000
Feb 19 19:03:05 vhost182 kernel: Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
Feb 19 19:03:05 vhost182 kernel: Call Trace:
Feb 19 19:03:05 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:03:05 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:03:05 vhost182 kernel:  wait_current_trans+0xb9/0xf0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:03:05 vhost182 kernel:  start_transaction+0x201/0x3e0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  btrfs_finish_ordered_io+0x48d/0x7e0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  normal_work_helper+0xd0/0x320 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? move_linked_works+0x6e/0xa0
Feb 19 19:03:05 vhost182 kernel:  process_one_work+0x191/0x370
Feb 19 19:03:05 vhost182 kernel:  worker_thread+0x4f/0x3b0
Feb 19 19:03:05 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:03:05 vhost182 kernel:  ? rescuer_thread+0x340/0x340
Feb 19 19:03:05 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:03:05 vhost182 kernel:  ret_from_fork+0x35/0x40
Feb 19 19:03:05 vhost182 kernel: INFO: task kworker/u24:5:7480 blocked for more than 120 seconds.
Feb 19 19:03:05 vhost182 kernel:       Tainted: G            E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Feb 19 19:03:05 vhost182 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Feb 19 19:03:05 vhost182 kernel: kworker/u24:5   D    0  7480      2 0x80000000
Feb 19 19:03:05 vhost182 kernel: Workqueue: btrfs-endio-write btrfs_endio_write_helper [btrfs]
Feb 19 19:03:05 vhost182 kernel: Call Trace:
Feb 19 19:03:05 vhost182 kernel:  ? __schedule+0x3f5/0x880
Feb 19 19:03:05 vhost182 kernel:  ? _raw_spin_lock_irqsave+0x32/0x40
Feb 19 19:03:05 vhost182 kernel:  schedule+0x32/0x80
Feb 19 19:03:05 vhost182 kernel:  wait_current_trans+0xb9/0xf0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? remove_wait_queue+0x60/0x60
Feb 19 19:03:05 vhost182 kernel:  start_transaction+0x201/0x3e0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  btrfs_finish_ordered_io+0x48d/0x7e0 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? __switch_to_asm+0x34/0x70
Feb 19 19:03:05 vhost182 kernel:  ? __switch_to_asm+0x40/0x70
Feb 19 19:03:05 vhost182 kernel:  ? __switch_to+0x8c/0x450
Feb 19 19:03:05 vhost182 kernel:  normal_work_helper+0xd0/0x320 [btrfs]
Feb 19 19:03:05 vhost182 kernel:  ? __schedule+0x3fd/0x880
Feb 19 19:03:05 vhost182 kernel:  process_one_work+0x191/0x370
Feb 19 19:03:05 vhost182 kernel:  worker_thread+0x4f/0x3b0
Feb 19 19:03:05 vhost182 kernel:  kthread+0xf8/0x130
Feb 19 19:03:05 vhost182 kernel:  ? rescuer_thread+0x340/0x340
Feb 19 19:03:05 vhost182 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Feb 19 19:03:05 vhost182 kernel:  ret_from_fork+0x35/0x40

Back to linux.debian.kernel | Previous | Next | Find similar | Unroll thread

Thread

Bug#908216: btrfs blocked for more than 120 seconds "Russell Mosemann" <rmosemann@futurefoam.com> - 2019-02-20 01:40 +0100

csiph-web