Bug#907859: task btrfs-transacti:663 blocked for more than 120 seconds

2018-09-03 Thread Russell Mosemann

Package: linux-image-4.17.0-0.bpo.3-amd6
Severity: important

 
Sep 03 08:18:04 vhost004 kernel: INFO: task btrfs-transacti:692 blocked for 
more than 120 seconds.
Sep 03 08:18:06 vhost004 kernel:   Tainted: G  I   
4.17.0-0.bpo.3-amd64 #1 Debian 4.17.17-1~bpo9+1
Sep 03 08:18:07 vhost004 kernel: "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 03 08:18:07 vhost004 kernel: btrfs-transacti D0   692  2 0x8000
Sep 03 08:18:08 vhost004 kernel: Call Trace:
Sep 03 08:18:09 vhost004 kernel:  ? __schedule+0x3dc/0x860
Sep 03 08:18:09 vhost004 kernel:  schedule+0x32/0x80
Sep 03 08:18:09 vhost004 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Sep 03 08:18:10 vhost004 kernel:  ? remove_wait_queue+0x60/0x60
Sep 03 08:18:10 vhost004 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Sep 03 08:18:10 vhost004 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Sep 03 08:18:11 vhost004 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 
[btrfs]
Sep 03 08:18:11 vhost004 kernel:  btrfs_commit_transaction+0xc8/0x8e0 [btrfs]
Sep 03 08:18:12 vhost004 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Sep 03 08:18:12 vhost004 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Sep 03 08:18:13 vhost004 kernel:  kthread+0xf8/0x130
Sep 03 08:18:13 vhost004 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 
[btrfs]
Sep 03 08:18:13 vhost004 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Sep 03 08:18:14 vhost004 kernel:  ret_from_fork+0x35/0x40
Sep 03 08:18:14 vhost004 systemd[1]: systemd-journald.service: Main process 
exited, code=killed, status=6/ABRT
Sep 03 08:18:15 vhost004 systemd[1]: systemd-journald.service: Unit entered 
failed state.
Sep 03 08:18:16 vhost004 systemd[1]: systemd-journald.service: Failed with 
result 'watchdog'.

 

Bug#907859: task btrfs-transacti:663 blocked for more than 120 seconds

2018-09-03 Thread Russell Mosemann

Package: linux-image-4.17.0-0.bpo.3-amd6
Severity: important

Dear Maintainer,

   * What led up to the situation?
 
Normal system operation, as far as I am aware, and then btrfs hung. The file 
system became inaccessible. See log lines below.

   * What exactly did you do (or not do) that was effective (or
 ineffective)?
 
Had to reboot.

   * What was the outcome of this action?
 
The system was accessible, again.

   * What are the log entries?
 
Sep 03 00:08:02 vhost003 kernel: INFO: task btrfs-transacti:663 blocked for 
more than 120 seconds.
Sep 03 00:08:02 vhost003 kernel:   Tainted: G  I   
4.17.0-0.bpo.3-amd64 #1 Debian 4.17.17-1~bpo9+1
Sep 03 00:08:02 vhost003 kernel: "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 03 00:08:02 vhost003 kernel: btrfs-transacti D0   663  2 0x8000
Sep 03 00:08:02 vhost003 kernel: Call Trace:
Sep 03 00:08:02 vhost003 kernel:  ? __schedule+0x3dc/0x860
Sep 03 00:08:02 vhost003 kernel:  schedule+0x32/0x80
Sep 03 00:08:02 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Sep 03 00:08:02 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Sep 03 00:08:02 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Sep 03 00:08:02 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Sep 03 00:08:02 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 
[btrfs]
Sep 03 00:08:02 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8e0 [btrfs]
Sep 03 00:08:02 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Sep 03 00:08:02 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Sep 03 00:08:02 vhost003 kernel:  kthread+0xf8/0x130
Sep 03 00:08:02 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 
[btrfs]
Sep 03 00:08:02 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Sep 03 00:08:02 vhost003 kernel:  ret_from_fork+0x35/0x40
Sep 03 00:08:02 vhost003 su[12343]: Successful su for nobody by root
Sep 03 00:08:02 vhost003 su[12343]: + ??? root:nobody
Sep 03 00:08:02 vhost003 su[12343]: pam_unix(su:session): session opened for 
user nobody by (uid=0)
Sep 03 00:08:02 vhost003 systemd[1]: Created slice User Slice of nobody.
Sep 03 00:08:03 vhost003 systemd[1]: Starting User Manager for UID 65534...
Sep 03 00:08:03 vhost003 systemd-logind[794]: New session c2 of user nobody.
Sep 03 00:08:03 vhost003 systemd[1]: Started Session c2 of user nobody.
Sep 03 00:08:03 vhost003 systemd[12344]: pam_unix(systemd-user:session): 
session opened for user nobody by (uid=0)
Sep 03 00:12:58 vhost003 kernel: INFO: task btrfs-transacti:663 blocked for 
more than 120 seconds.
Sep 03 00:12:59 vhost003 kernel:   Tainted: G  I   
4.17.0-0.bpo.3-amd64 #1 Debian 4.17.17-1~bpo9+1
Sep 03 00:12:59 vhost003 kernel: "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 03 00:12:59 vhost003 kernel: btrfs-transacti D0   663  2 0x8000
Sep 03 00:12:59 vhost003 kernel: Call Trace:
Sep 03 00:12:59 vhost003 kernel:  ? __schedule+0x3dc/0x860
Sep 03 00:12:59 vhost003 kernel:  schedule+0x32/0x80
Sep 03 00:12:59 vhost003 kernel:  btrfs_start_ordered_extent+0xed/0x120 [btrfs]
Sep 03 00:12:59 vhost003 kernel:  ? remove_wait_queue+0x60/0x60
Sep 03 00:12:59 vhost003 kernel:  btrfs_wait_ordered_range+0xa0/0x100 [btrfs]
Sep 03 00:12:59 vhost003 kernel:  __btrfs_wait_cache_io+0x46/0x1c0 [btrfs]
Sep 03 00:12:59 vhost003 kernel:  btrfs_start_dirty_block_groups+0x1ad/0x4b0 
[btrfs]
Sep 03 00:12:59 vhost003 kernel:  btrfs_commit_transaction+0xc8/0x8e0 [btrfs]
Sep 03 00:12:59 vhost003 kernel:  ? start_transaction+0x8f/0x3e0 [btrfs]
Sep 03 00:12:59 vhost003 kernel:  transaction_kthread+0x157/0x180 [btrfs]
Sep 03 00:12:59 vhost003 kernel:  kthread+0xf8/0x130
Sep 03 00:12:59 vhost003 kernel:  ? btrfs_cleanup_transaction+0x500/0x500 
[btrfs]
Sep 03 00:12:59 vhost003 kernel:  ? kthread_create_worker_on_cpu+0x70/0x70
Sep 03 00:12:59 vhost003 kernel:  ret_from_fork+0x35/0x40
Sep 03 00:12:59 vhost003 systemd[1]: systemd-journald.service: Main process 
exited, code=killed, status=6/ABRT
Sep 03 00:12:59 vhost003 systemd[1]: systemd-journald.service: Unit entered 
failed state.
Sep 03 00:12:59 vhost003 systemd[1]: systemd-journald.service: Failed with 
result 'watchdog'.
Sep 03 00:12:59 vhost003 systemd[1]: systemd-journald.service: Service has no 
hold-off time, scheduling restart.
Sep 03 00:12:59 vhost003 systemd[1]: Stopped Flush Journal to Persistent 
Storage.
Sep 03 00:12:59 vhost003 systemd[1]: Stopping Flush Journal to Persistent 
Storage...
Sep 03 00:12:59 vhost003 systemd[1]: Stopped Journal Service.
Sep 03 00:12:59 vhost003 systemd[1]: Starting Journal Service...
Sep 03 00:12:59 vhost003 kernel: INFO: task btrfs-transacti:663 blocked for 
more than 120 seconds.
Sep 03 00:12:59 vhost003 kernel:   Tainted: G  I   
4.17.0-0.bpo.3-amd64 #1 Debian 4.17.17-1~bpo9+1
Sep 03 00:12:59 vhost003 kernel: "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this mes