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-01-20 02:20 +0100
Message-ID	<xi9Wh-51q-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
 
Yes, the btrfs hung task problem still exists in the latest kernel.
 
 
Jan 19 18:40:41 vhost003 kernel: INFO: task btrfs-transacti:666 blocked for more than 120 seconds.
Jan 19 18:40:41 vhost003 kernel: Tainted: G I E 4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Jan 19 18:40:41 vhost003 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 19 18:40:41 vhost003 kernel: btrfs-transacti D 0 666 2 0x80000000
Jan 19 18:40:41 vhost003 kernel: Call Trace:
Jan 19 18:40:41 vhost003 kernel: ? __schedule+0x3f5/0x880
Jan 19 18:40:41 vhost003 kernel: schedule+0x32/0x80
Jan 19 18:40:41 vhost003 kernel: btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Jan 19 18:40:41 vhost003 kernel: ? remove_wait_queue+0x60/0x60
Jan 19 18:40:41 vhost003 kernel: btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Jan 19 18:40:41 vhost003 kernel: __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Jan 19 18:40:41 vhost003 kernel: btrfs_start_dirty_block_groups+0x1ad/0x4b0 [btrfs]
Jan 19 18:40:41 vhost003 kernel: btrfs_commit_transaction+0xc8/0x8a0 [btrfs]
Jan 19 18:40:41 vhost003 kernel: ? start_transaction+0x8f/0x3e0 [btrfs]
Jan 19 18:40:41 vhost003 kernel: transaction_kthread+0x157/0x180 [btrfs]
Jan 19 18:40:41 vhost003 kernel: kthread+0xf8/0x130
Jan 19 18:40:41 vhost003 kernel: ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Jan 19 18:40:41 vhost003 kernel: ? kthread_create_worker_on_cpu+0x70/0x70
Jan 19 18:40:41 vhost003 kernel: ret_from_fork+0x35/0x40

Jan 19 18:42:42 vhost003 kernel: INFO: task btrfs-transacti:666 blocked for more than 120 seconds.
Jan 19 18:42:42 vhost003 kernel:       Tainted: G          I E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Jan 19 18:42:42 vhost003 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 19 18:42:42 vhost003 kernel: btrfs-transacti D    0   666      2 0x80000000
Jan 19 18:42:42 vhost003 kernel: Call Trace:
Jan 19 18:42:42 vhost003 kernel:  ? __schedule+0x3f5/0x880
Jan 19 18:42:42 vhost003 kernel:  schedule+0x32/0x80
Jan 19 18:42:42 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Jan 19 18:42:42 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Jan 19 18:42:42 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Jan 19 18:42:42 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Jan 19 18:42:42 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 [btrfs]
Jan 19 18:42:42 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8a0 [btrfs]
Jan 19 18:42:42 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Jan 19 18:42:42 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Jan 19 18:42:42 vhost003 kernel:  kthread+0xf8/0x130
Jan 19 18:42:42 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Jan 19 18:42:42 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Jan 19 18:42:42 vhost003 kernel:  ret_from_fork+0x35/0x40
Jan 19 18:44:43 vhost003 kernel: INFO: task btrfs-transacti:666 blocked for more than 120 seconds.
Jan 19 18:44:43 vhost003 kernel:       Tainted: G          I E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Jan 19 18:44:43 vhost003 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 19 18:44:43 vhost003 kernel: btrfs-transacti D    0   666      2 0x80000000
Jan 19 18:44:43 vhost003 kernel: Call Trace:
Jan 19 18:44:43 vhost003 kernel:  ? __schedule+0x3f5/0x880
Jan 19 18:44:43 vhost003 kernel:  schedule+0x32/0x80
Jan 19 18:44:43 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Jan 19 18:44:43 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Jan 19 18:44:43 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Jan 19 18:44:43 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Jan 19 18:44:43 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 [btrfs]
Jan 19 18:44:43 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8a0 [btrfs]
Jan 19 18:44:43 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Jan 19 18:44:43 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Jan 19 18:44:43 vhost003 kernel:  kthread+0xf8/0x130
Jan 19 18:44:43 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Jan 19 18:44:43 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Jan 19 18:44:43 vhost003 kernel:  ret_from_fork+0x35/0x40

Jan 19 18:46:43 vhost003 kernel: INFO: task btrfs-transacti:666 blocked for more than 120 seconds.
Jan 19 18:46:43 vhost003 kernel:       Tainted: G          I E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Jan 19 18:46:43 vhost003 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 19 18:46:43 vhost003 kernel: btrfs-transacti D    0   666      2 0x80000000
Jan 19 18:46:43 vhost003 kernel: Call Trace:
Jan 19 18:46:43 vhost003 kernel:  ? __schedule+0x3f5/0x880
Jan 19 18:46:43 vhost003 kernel:  schedule+0x32/0x80
Jan 19 18:46:43 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Jan 19 18:46:43 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Jan 19 18:46:43 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Jan 19 18:46:43 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Jan 19 18:46:43 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 [btrfs]
Jan 19 18:46:43 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8a0 [btrfs]
Jan 19 18:46:43 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Jan 19 18:46:43 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Jan 19 18:46:43 vhost003 kernel:  kthread+0xf8/0x130
Jan 19 18:46:43 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Jan 19 18:46:43 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Jan 19 18:46:43 vhost003 kernel:  ret_from_fork+0x35/0x40
Jan 19 18:48:44 vhost003 kernel: INFO: task btrfs-transacti:666 blocked for more than 120 seconds.
Jan 19 18:48:44 vhost003 kernel:       Tainted: G          I E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Jan 19 18:48:44 vhost003 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 19 18:48:44 vhost003 kernel: btrfs-transacti D    0   666      2 0x80000000
Jan 19 18:48:44 vhost003 kernel: Call Trace:
Jan 19 18:48:44 vhost003 kernel:  ? __schedule+0x3f5/0x880
Jan 19 18:48:44 vhost003 kernel:  schedule+0x32/0x80
Jan 19 18:48:44 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Jan 19 18:48:44 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Jan 19 18:48:44 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Jan 19 18:48:44 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Jan 19 18:48:44 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 [btrfs]
Jan 19 18:48:44 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8a0 [btrfs]
Jan 19 18:48:44 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Jan 19 18:48:44 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Jan 19 18:48:44 vhost003 kernel:  kthread+0xf8/0x130
Jan 19 18:48:44 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Jan 19 18:48:44 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Jan 19 18:48:44 vhost003 kernel:  ret_from_fork+0x35/0x40

Jan 19 18:50:45 vhost003 kernel: INFO: task btrfs-transacti:666 blocked for more than 120 seconds.
Jan 19 18:50:45 vhost003 kernel:       Tainted: G          I E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Jan 19 18:50:45 vhost003 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 19 18:50:45 vhost003 kernel: btrfs-transacti D    0   666      2 0x80000000
Jan 19 18:50:45 vhost003 kernel: Call Trace:
Jan 19 18:50:45 vhost003 kernel:  ? __schedule+0x3f5/0x880
Jan 19 18:50:45 vhost003 kernel:  schedule+0x32/0x80
Jan 19 18:50:45 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Jan 19 18:50:45 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Jan 19 18:50:45 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Jan 19 18:50:45 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Jan 19 18:50:45 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 [btrfs]
Jan 19 18:50:45 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8a0 [btrfs]
Jan 19 18:50:45 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Jan 19 18:50:45 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Jan 19 18:50:45 vhost003 kernel:  kthread+0xf8/0x130
Jan 19 18:50:45 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Jan 19 18:50:45 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Jan 19 18:50:45 vhost003 kernel:  ret_from_fork+0x35/0x40
Jan 19 18:52:25 vhost003 systemd[1]: Starting Daily apt download activities...
Jan 19 18:52:26 vhost003 systemd[1]: Started Daily apt download activities.
Jan 19 18:52:26 vhost003 systemd[1]: apt-daily.timer: Adding 8h 25min 18.233120s random time.
Jan 19 18:52:26 vhost003 systemd[1]: apt-daily.timer: Adding 8h 9min 4.194692s random time.
Jan 19 18:52:46 vhost003 kernel: INFO: task btrfs-transacti:666 blocked for more than 120 seconds.
Jan 19 18:52:46 vhost003 kernel:       Tainted: G          I E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Jan 19 18:52:46 vhost003 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 19 18:52:46 vhost003 kernel: btrfs-transacti D    0   666      2 0x80000000
Jan 19 18:52:46 vhost003 kernel: Call Trace:
Jan 19 18:52:46 vhost003 kernel:  ? __schedule+0x3f5/0x880
Jan 19 18:52:46 vhost003 kernel:  schedule+0x32/0x80
Jan 19 18:52:46 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Jan 19 18:52:46 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Jan 19 18:52:46 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Jan 19 18:52:46 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Jan 19 18:52:46 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 [btrfs]
Jan 19 18:52:46 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8a0 [btrfs]
Jan 19 18:52:46 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Jan 19 18:52:46 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Jan 19 18:52:46 vhost003 kernel:  kthread+0xf8/0x130
Jan 19 18:52:46 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Jan 19 18:52:46 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Jan 19 18:52:46 vhost003 kernel:  ret_from_fork+0x35/0x40

Jan 19 18:54:47 vhost003 kernel: INFO: task btrfs-transacti:666 blocked for more than 120 seconds.
Jan 19 18:54:47 vhost003 kernel:       Tainted: G          I E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Jan 19 18:54:47 vhost003 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 19 18:54:47 vhost003 kernel: btrfs-transacti D    0   666      2 0x80000000
Jan 19 18:54:47 vhost003 kernel: Call Trace:
Jan 19 18:54:47 vhost003 kernel:  ? __schedule+0x3f5/0x880
Jan 19 18:54:47 vhost003 kernel:  schedule+0x32/0x80
Jan 19 18:54:47 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Jan 19 18:54:47 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Jan 19 18:54:47 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Jan 19 18:54:47 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Jan 19 18:54:47 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 [btrfs]
Jan 19 18:54:47 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8a0 [btrfs]
Jan 19 18:54:47 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Jan 19 18:54:47 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Jan 19 18:54:47 vhost003 kernel:  kthread+0xf8/0x130
Jan 19 18:54:47 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Jan 19 18:54:47 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Jan 19 18:54:47 vhost003 kernel:  ret_from_fork+0x35/0x40

Jan 19 18:56:48 vhost003 kernel: INFO: task btrfs-transacti:666 blocked for more than 120 seconds.
Jan 19 18:56:48 vhost003 kernel:       Tainted: G          I E     4.19.0-0.bpo.1-amd64 #1 Debian 4.19.12-1~bpo9+1
Jan 19 18:56:48 vhost003 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 19 18:56:48 vhost003 kernel: btrfs-transacti D    0   666      2 0x80000000
Jan 19 18:56:48 vhost003 kernel: Call Trace:
Jan 19 18:56:48 vhost003 kernel:  ? __schedule+0x3f5/0x880
Jan 19 18:56:48 vhost003 kernel:  schedule+0x32/0x80
Jan 19 18:56:48 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Jan 19 18:56:48 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Jan 19 18:56:48 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Jan 19 18:56:48 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Jan 19 18:56:48 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 [btrfs]
Jan 19 18:56:48 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8a0 [btrfs]
Jan 19 18:56:48 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Jan 19 18:56:48 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Jan 19 18:56:48 vhost003 kernel:  kthread+0xf8/0x130
Jan 19 18:56:48 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 [btrfs]
Jan 19 18:56:48 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Jan 19 18:56:48 vhost003 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-01-20 02:20 +0100

csiph-web