Arch Linux

mxfm

Member

Registered: 2015-10-23

Posts: 163

Task pool blocked for more than 120 seconds

Hi!

I have dm-crypt partition on my drive. In that partition I have veracrypt volume. When I try to write data to the volume, after writing ~50 mb the speed slows dramatically. Moreover, the general responsiveness of all IO operations (unrelated to veracrypt) also slows down. Finally, the writing operation finishes, but I have to wait approx 1/2 hour for 500 MB. Does anyone knows how to fix it?

[  210.584998] EXT4-fs (dm-3): mounted filesystem with ordered data mode. Opts: (null)
[ 1228.825034] INFO: task jbd2/dm-3-8:3344 blocked for more than 120 seconds.
[ 1228.825043]       Tainted: G           O    4.8.6-1-ARCH #1
[ 1228.825044] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1228.825046] jbd2/dm-3-8     D ffff88022e683ac8     0  3344      2 0x00000000
[ 1228.825052]  ffff88022e683ac8 00ffffff815f4017 ffff88020a843900 ffff88023549b900
[ 1228.825054]  ffff88023549b980 ffff88022e684000 ffff88025f257f80 7fffffffffffffff
[ 1228.825056]  ffffffff815f4620 ffff88022e683c28 ffff88022e683ae0 ffffffff815f3d6c
[ 1228.825060] Call Trace:
[ 1228.825073]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1228.825075]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 1228.825079]  [<ffffffff815f6cba>] schedule_timeout+0x2da/0x420
[ 1228.825084]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 1228.825087]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 1228.825089]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1228.825090]  [<ffffffff815f3544>] io_schedule_timeout+0xa4/0x110
[ 1228.825092]  [<ffffffff815f463b>] bit_wait_io+0x1b/0x70
[ 1228.825093]  [<ffffffff815f4228>] __wait_on_bit+0x58/0x90
[ 1228.825094]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1228.825096]  [<ffffffff815f42e2>] out_of_line_wait_on_bit+0x82/0xb0
[ 1228.825100]  [<ffffffff810c0490>] ? autoremove_wake_function+0x40/0x40
[ 1228.825103]  [<ffffffff8123f667>] __wait_on_buffer+0x27/0x30
[ 1228.825112]  [<ffffffffa02bdab3>] jbd2_journal_commit_transaction+0x1563/0x1980 [jbd2]
[ 1228.825120]  [<ffffffff810a3277>] ? finish_task_switch+0x77/0x1e0
[ 1228.825126]  [<ffffffff810eb84e>] ? try_to_del_timer_sync+0x5e/0x90
[ 1228.825134]  [<ffffffffa02c30ca>] kjournald2+0xca/0x260 [jbd2]
[ 1228.825138]  [<ffffffff810c0450>] ? wake_atomic_t_function+0x60/0x60
[ 1228.825142]  [<ffffffffa02c3000>] ? commit_timeout+0x10/0x10 [jbd2]
[ 1228.825145]  [<ffffffff8109be38>] kthread+0xd8/0xf0
[ 1228.825151]  [<ffffffff8102c782>] ? __switch_to+0x2d2/0x630
[ 1228.825155]  [<ffffffff815f7eff>] ret_from_fork+0x1f/0x40
[ 1228.825158]  [<ffffffff8109bd60>] ? kthread_worker_fn+0x170/0x170
[ 1351.705142] INFO: task jbd2/dm-3-8:3344 blocked for more than 120 seconds.
[ 1351.705152]       Tainted: G           O    4.8.6-1-ARCH #1
[ 1351.705154] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1351.705157] jbd2/dm-3-8     D ffff88022e683ac8     0  3344      2 0x00000000
[ 1351.705165]  ffff88022e683ac8 00ffffff815f4017 ffff88020a843900 ffff88023549b900
[ 1351.705170]  ffff88023549b980 ffff88022e684000 ffff88025f257f80 7fffffffffffffff
[ 1351.705174]  ffffffff815f4620 ffff88022e683c28 ffff88022e683ae0 ffffffff815f3d6c
[ 1351.705178] Call Trace:
[ 1351.705188]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1351.705190]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 1351.705194]  [<ffffffff815f6cba>] schedule_timeout+0x2da/0x420
[ 1351.705198]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 1351.705201]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 1351.705204]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1351.705206]  [<ffffffff815f3544>] io_schedule_timeout+0xa4/0x110
[ 1351.705209]  [<ffffffff815f463b>] bit_wait_io+0x1b/0x70
[ 1351.705211]  [<ffffffff815f4228>] __wait_on_bit+0x58/0x90
[ 1351.705214]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1351.705216]  [<ffffffff815f42e2>] out_of_line_wait_on_bit+0x82/0xb0
[ 1351.705220]  [<ffffffff810c0490>] ? autoremove_wake_function+0x40/0x40
[ 1351.705225]  [<ffffffff8123f667>] __wait_on_buffer+0x27/0x30
[ 1351.705233]  [<ffffffffa02bdab3>] jbd2_journal_commit_transaction+0x1563/0x1980 [jbd2]
[ 1351.705240]  [<ffffffff810a3277>] ? finish_task_switch+0x77/0x1e0
[ 1351.705245]  [<ffffffff810eb84e>] ? try_to_del_timer_sync+0x5e/0x90
[ 1351.705251]  [<ffffffffa02c30ca>] kjournald2+0xca/0x260 [jbd2]
[ 1351.705254]  [<ffffffff810c0450>] ? wake_atomic_t_function+0x60/0x60
[ 1351.705259]  [<ffffffffa02c3000>] ? commit_timeout+0x10/0x10 [jbd2]
[ 1351.705263]  [<ffffffff8109be38>] kthread+0xd8/0xf0
[ 1351.705267]  [<ffffffff8102c782>] ? __switch_to+0x2d2/0x630
[ 1351.705271]  [<ffffffff815f7eff>] ret_from_fork+0x1f/0x40
[ 1351.705275]  [<ffffffff8109bd60>] ? kthread_worker_fn+0x170/0x170
[ 1474.585364] INFO: task jbd2/dm-3-8:3344 blocked for more than 120 seconds.
[ 1474.585374]       Tainted: G           O    4.8.6-1-ARCH #1
[ 1474.585376] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1474.585380] jbd2/dm-3-8     D ffff88022e683ac8     0  3344      2 0x00000000
[ 1474.585388]  ffff88022e683ac8 00ffffff815f4017 ffff88020a843900 ffff88023549b900
[ 1474.585392]  ffff88023549b980 ffff88022e684000 ffff88025f257f80 7fffffffffffffff
[ 1474.585396]  ffffffff815f4620 ffff88022e683c28 ffff88022e683ae0 ffffffff815f3d6c
[ 1474.585400] Call Trace:
[ 1474.585411]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1474.585414]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 1474.585417]  [<ffffffff815f6cba>] schedule_timeout+0x2da/0x420
[ 1474.585422]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 1474.585424]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 1474.585427]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1474.585429]  [<ffffffff815f3544>] io_schedule_timeout+0xa4/0x110
[ 1474.585431]  [<ffffffff815f463b>] bit_wait_io+0x1b/0x70
[ 1474.585433]  [<ffffffff815f4228>] __wait_on_bit+0x58/0x90
[ 1474.585436]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1474.585438]  [<ffffffff815f42e2>] out_of_line_wait_on_bit+0x82/0xb0
[ 1474.585442]  [<ffffffff810c0490>] ? autoremove_wake_function+0x40/0x40
[ 1474.585446]  [<ffffffff8123f667>] __wait_on_buffer+0x27/0x30
[ 1474.585455]  [<ffffffffa02bdab3>] jbd2_journal_commit_transaction+0x1563/0x1980 [jbd2]
[ 1474.585462]  [<ffffffff810a3277>] ? finish_task_switch+0x77/0x1e0
[ 1474.585467]  [<ffffffff810eb84e>] ? try_to_del_timer_sync+0x5e/0x90
[ 1474.585473]  [<ffffffffa02c30ca>] kjournald2+0xca/0x260 [jbd2]
[ 1474.585476]  [<ffffffff810c0450>] ? wake_atomic_t_function+0x60/0x60
[ 1474.585481]  [<ffffffffa02c3000>] ? commit_timeout+0x10/0x10 [jbd2]
[ 1474.585485]  [<ffffffff8109be38>] kthread+0xd8/0xf0
[ 1474.585489]  [<ffffffff8102c782>] ? __switch_to+0x2d2/0x630
[ 1474.585493]  [<ffffffff815f7eff>] ret_from_fork+0x1f/0x40
[ 1474.585497]  [<ffffffff8109bd60>] ? kthread_worker_fn+0x170/0x170
[ 1843.225784] INFO: task jbd2/dm-3-8:3344 blocked for more than 120 seconds.
[ 1843.225791]       Tainted: G           O    4.8.6-1-ARCH #1
[ 1843.225793] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1843.225795] jbd2/dm-3-8     D ffff88022e683ac8     0  3344      2 0x00000000
[ 1843.225801]  ffff88022e683ac8 00ffffff815f4017 ffff8802420f5580 ffff88023549b900
[ 1843.225804]  ffff88023549b980 ffff88022e684000 ffff88025f217f80 7fffffffffffffff
[ 1843.225807]  ffffffff815f4620 ffff88022e683c28 ffff88022e683ae0 ffffffff815f3d6c
[ 1843.225810] Call Trace:
[ 1843.225818]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1843.225820]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 1843.225823]  [<ffffffff815f6cba>] schedule_timeout+0x2da/0x420
[ 1843.225826]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 1843.225828]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1843.225830]  [<ffffffff815f3544>] io_schedule_timeout+0xa4/0x110
[ 1843.225831]  [<ffffffff815f463b>] bit_wait_io+0x1b/0x70
[ 1843.225833]  [<ffffffff815f4228>] __wait_on_bit+0x58/0x90
[ 1843.225835]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 1843.225837]  [<ffffffff815f42e2>] out_of_line_wait_on_bit+0x82/0xb0
[ 1843.225840]  [<ffffffff810c0490>] ? autoremove_wake_function+0x40/0x40
[ 1843.225843]  [<ffffffff8123f667>] __wait_on_buffer+0x27/0x30
[ 1843.225850]  [<ffffffffa02bdab3>] jbd2_journal_commit_transaction+0x1563/0x1980 [jbd2]
[ 1843.225855]  [<ffffffff810eb84e>] ? try_to_del_timer_sync+0x5e/0x90
[ 1843.225859]  [<ffffffffa02c30ca>] kjournald2+0xca/0x260 [jbd2]
[ 1843.225861]  [<ffffffff810c0450>] ? wake_atomic_t_function+0x60/0x60
[ 1843.225865]  [<ffffffffa02c3000>] ? commit_timeout+0x10/0x10 [jbd2]
[ 1843.225869]  [<ffffffff8109be38>] kthread+0xd8/0xf0
[ 1843.225872]  [<ffffffff8102c782>] ? __switch_to+0x2d2/0x630
[ 1843.225874]  [<ffffffff815f7eff>] ret_from_fork+0x1f/0x40
[ 1843.225877]  [<ffffffff8109bd60>] ? kthread_worker_fn+0x170/0x170
[ 2211.866379] INFO: task jbd2/dm-3-8:3344 blocked for more than 120 seconds.
[ 2211.866389]       Tainted: G           O    4.8.6-1-ARCH #1
[ 2211.866391] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2211.866394] jbd2/dm-3-8     D ffff88022e683ac8     0  3344      2 0x00000000
[ 2211.866404]  ffff88022e683ac8 00ffffff815f4017 ffff8802559ab900 ffff88023549b900
[ 2211.866408]  ffff88023549b980 ffff88022e684000 ffff88025f297f80 7fffffffffffffff
[ 2211.866412]  ffffffff815f4620 ffff88022e683c28 ffff88022e683ae0 ffffffff815f3d6c
[ 2211.866417] Call Trace:
[ 2211.866427]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2211.866430]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 2211.866433]  [<ffffffff815f6cba>] schedule_timeout+0x2da/0x420
[ 2211.866438]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 2211.866442]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 2211.866444]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2211.866446]  [<ffffffff815f3544>] io_schedule_timeout+0xa4/0x110
[ 2211.866449]  [<ffffffff815f463b>] bit_wait_io+0x1b/0x70
[ 2211.866452]  [<ffffffff815f4228>] __wait_on_bit+0x58/0x90
[ 2211.866454]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2211.866457]  [<ffffffff815f42e2>] out_of_line_wait_on_bit+0x82/0xb0
[ 2211.866461]  [<ffffffff810c0490>] ? autoremove_wake_function+0x40/0x40
[ 2211.866465]  [<ffffffff8123f667>] __wait_on_buffer+0x27/0x30
[ 2211.866475]  [<ffffffffa02bdab3>] jbd2_journal_commit_transaction+0x1563/0x1980 [jbd2]
[ 2211.866483]  [<ffffffff810eb84e>] ? try_to_del_timer_sync+0x5e/0x90
[ 2211.866489]  [<ffffffffa02c30ca>] kjournald2+0xca/0x260 [jbd2]
[ 2211.866493]  [<ffffffff810c0450>] ? wake_atomic_t_function+0x60/0x60
[ 2211.866498]  [<ffffffffa02c3000>] ? commit_timeout+0x10/0x10 [jbd2]
[ 2211.866504]  [<ffffffff8109be38>] kthread+0xd8/0xf0
[ 2211.866508]  [<ffffffff8102c782>] ? __switch_to+0x2d2/0x630
[ 2211.866512]  [<ffffffff815f7eff>] ret_from_fork+0x1f/0x40
[ 2211.866516]  [<ffffffff8109bd60>] ? kthread_worker_fn+0x170/0x170
[ 2334.746542] INFO: task jbd2/dm-3-8:3344 blocked for more than 120 seconds.
[ 2334.746555]       Tainted: G           O    4.8.6-1-ARCH #1
[ 2334.746557] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2334.746561] jbd2/dm-3-8     D ffff88022e683ac8     0  3344      2 0x00000000
[ 2334.746569]  ffff88022e683ac8 00ffffff815f4017 ffff8802559ab900 ffff88023549b900
[ 2334.746584]  ffff88023549b980 ffff88022e684000 ffff88025f297f80 7fffffffffffffff
[ 2334.746588]  ffffffff815f4620 ffff88022e683c28 ffff88022e683ae0 ffffffff815f3d6c
[ 2334.746592] Call Trace:
[ 2334.746602]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2334.746606]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 2334.746609]  [<ffffffff815f6cba>] schedule_timeout+0x2da/0x420
[ 2334.746614]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 2334.746616]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 2334.746619]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2334.746621]  [<ffffffff815f3544>] io_schedule_timeout+0xa4/0x110
[ 2334.746624]  [<ffffffff815f463b>] bit_wait_io+0x1b/0x70
[ 2334.746626]  [<ffffffff815f4228>] __wait_on_bit+0x58/0x90
[ 2334.746629]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2334.746631]  [<ffffffff815f42e2>] out_of_line_wait_on_bit+0x82/0xb0
[ 2334.746636]  [<ffffffff810c0490>] ? autoremove_wake_function+0x40/0x40
[ 2334.746640]  [<ffffffff8123f667>] __wait_on_buffer+0x27/0x30
[ 2334.746650]  [<ffffffffa02bdab3>] jbd2_journal_commit_transaction+0x1563/0x1980 [jbd2]
[ 2334.746657]  [<ffffffff810eb84e>] ? try_to_del_timer_sync+0x5e/0x90
[ 2334.746664]  [<ffffffffa02c30ca>] kjournald2+0xca/0x260 [jbd2]
[ 2334.746668]  [<ffffffff810c0450>] ? wake_atomic_t_function+0x60/0x60
[ 2334.746673]  [<ffffffffa02c3000>] ? commit_timeout+0x10/0x10 [jbd2]
[ 2334.746678]  [<ffffffff8109be38>] kthread+0xd8/0xf0
[ 2334.746682]  [<ffffffff8102c782>] ? __switch_to+0x2d2/0x630
[ 2334.746686]  [<ffffffff815f7eff>] ret_from_fork+0x1f/0x40
[ 2334.746690]  [<ffffffff8109bd60>] ? kthread_worker_fn+0x170/0x170
[ 2580.506766] INFO: task pool:4181 blocked for more than 120 seconds.
[ 2580.506770]       Tainted: G           O    4.8.6-1-ARCH #1
[ 2580.506771] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2580.506772] pool            D ffff88020f3b77d8     0  4181  11232 0x00000000
[ 2580.506776]  ffff88020f3b77d8 00ffffff81036e29 ffff880255a6b900 ffff88024c7c9c80
[ 2580.506778]  ffff88025f217f80 ffff88020f3b8000 ffff88025f297f80 7fffffffffffffff
[ 2580.506780]  ffffffff815f4620 ffff88020f3b7938 ffff88020f3b77f0 ffffffff815f3d6c
[ 2580.506781] Call Trace:
[ 2580.506786]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2580.506787]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 2580.506789]  [<ffffffff815f6cba>] schedule_timeout+0x2da/0x420
[ 2580.506791]  [<ffffffff810b4155>] ? put_prev_entity+0x35/0x8c0
[ 2580.506793]  [<ffffffff8102c782>] ? __switch_to+0x2d2/0x630
[ 2580.506794]  [<ffffffff810ba40e>] ? pick_next_task_fair+0x13e/0x4d0
[ 2580.506796]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 2580.506797]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2580.506798]  [<ffffffff815f3544>] io_schedule_timeout+0xa4/0x110
[ 2580.506799]  [<ffffffff815f463b>] bit_wait_io+0x1b/0x70
[ 2580.506800]  [<ffffffff815f4228>] __wait_on_bit+0x58/0x90
[ 2580.506801]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2580.506802]  [<ffffffff815f42e2>] out_of_line_wait_on_bit+0x82/0xb0
[ 2580.506804]  [<ffffffff810c0490>] ? autoremove_wake_function+0x40/0x40
[ 2580.506808]  [<ffffffffa02bad51>] do_get_write_access+0x1c1/0x510 [jbd2]
[ 2580.506810]  [<ffffffffa02bb0d1>] jbd2_journal_get_write_access+0x31/0x60 [jbd2]
[ 2580.506816]  [<ffffffffa0319721>] __ext4_journal_get_write_access+0x31/0x70 [ext4]
[ 2580.506820]  [<ffffffffa02e7da7>] ext4_reserve_inode_write+0x77/0xa0 [ext4]
[ 2580.506824]  [<ffffffffa02ec2b8>] ? ext4_dirty_inode+0x48/0x70 [ext4]
[ 2580.506827]  [<ffffffffa02e7e1e>] ext4_mark_inode_dirty+0x4e/0x230 [ext4]
[ 2580.506831]  [<ffffffffa02ec2b8>] ext4_dirty_inode+0x48/0x70 [ext4]
[ 2580.506833]  [<ffffffff812378c5>] __mark_inode_dirty+0x45/0x400
[ 2580.506834]  [<ffffffff81240ac6>] generic_write_end+0x96/0xe0
[ 2580.506838]  [<ffffffffa02eaca7>] ext4_da_write_end+0x117/0x2d0 [ext4]
[ 2580.506840]  [<ffffffff81311497>] ? iov_iter_copy_from_user_atomic+0x1e7/0x340
[ 2580.506842]  [<ffffffff81178664>] generic_perform_write+0x114/0x1c0
[ 2580.506843]  [<ffffffff81179d5f>] __generic_file_write_iter+0x14f/0x1d0
[ 2580.506845]  [<ffffffff8123a8f0>] ? page_cache_pipe_buf_release+0x70/0x70
[ 2580.506848]  [<ffffffffa02de033>] ext4_file_write_iter+0xe3/0x3c0 [ext4]
[ 2580.506850]  [<ffffffff810a6c12>] ? default_wake_function+0x12/0x20
[ 2580.506851]  [<ffffffff8121d673>] ? pollwake+0x73/0x90
[ 2580.506853]  [<ffffffff812085e1>] vfs_iter_write+0x91/0x100
[ 2580.506855]  [<ffffffff8123b7ee>] iter_file_splice_write+0x25e/0x390
[ 2580.506856]  [<ffffffff8123cd76>] SyS_splice+0x326/0x790
[ 2580.506858]  [<ffffffff81209776>] ? vfs_write+0x186/0x1a0
[ 2580.506859]  [<ffffffff815f7cf2>] entry_SYSCALL_64_fastpath+0x1a/0xa4
[ 2580.506861] INFO: task pool:7486 blocked for more than 120 seconds.
[ 2580.506862]       Tainted: G           O    4.8.6-1-ARCH #1
[ 2580.506863] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2580.506863] pool            D ffff88022e7238a8     0  7486  11232 0x00000000
[ 2580.506865]  ffff88022e7238a8 00ff880254dc9c00 ffff880255a6aac0 ffff880253a55580
[ 2580.506867]  0000000000000001 ffff88022e724000 ffff88025f2d7f80 7fffffffffffffff
[ 2580.506869]  ffffffff815f4620 ffff88022e723a00 ffff88022e7238c0 ffffffff815f3d6c
[ 2580.506870] Call Trace:
[ 2580.506871]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2580.506872]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 2580.506873]  [<ffffffff815f6cba>] schedule_timeout+0x2da/0x420
[ 2580.506875]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 2580.506876]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2580.506877]  [<ffffffff815f3544>] io_schedule_timeout+0xa4/0x110
[ 2580.506878]  [<ffffffff815f463b>] bit_wait_io+0x1b/0x70
[ 2580.506879]  [<ffffffff815f4228>] __wait_on_bit+0x58/0x90
[ 2580.506880]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2580.506881]  [<ffffffff815f42e2>] out_of_line_wait_on_bit+0x82/0xb0
[ 2580.506882]  [<ffffffff810c0490>] ? autoremove_wake_function+0x40/0x40
[ 2580.506884]  [<ffffffffa02bad51>] do_get_write_access+0x1c1/0x510 [jbd2]
[ 2580.506886]  [<ffffffffa02bb0d1>] jbd2_journal_get_write_access+0x31/0x60 [jbd2]
[ 2580.506891]  [<ffffffffa0319721>] __ext4_journal_get_write_access+0x31/0x70 [ext4]
[ 2580.506895]  [<ffffffffa02f2826>] add_dirent_to_buf+0x86/0x220 [ext4]
[ 2580.506898]  [<ffffffffa02f2b6f>] ext4_add_entry+0x1af/0xf00 [ext4]
[ 2580.506901]  [<ffffffffa010fca6>] ? fscrypt_free_filename+0x16/0x30 [fscrypto]
[ 2580.506903]  [<ffffffffa02b9652>] ? start_this_handle+0x142/0x400 [jbd2]
[ 2580.506907]  [<ffffffffa02f4f5f>] ? ext4_rename+0x45f/0x940 [ext4]
[ 2580.506911]  [<ffffffffa031945d>] ? __ext4_journal_start_sb+0x6d/0x130 [ext4]
[ 2580.506914]  [<ffffffffa02f530c>] ext4_rename+0x80c/0x940 [ext4]
[ 2580.506916]  [<ffffffff81213a01>] ? follow_mount+0x31/0x70
[ 2580.506920]  [<ffffffffa02f545d>] ext4_rename2+0x1d/0x30 [ext4]
[ 2580.506921]  [<ffffffff8121561a>] vfs_rename+0x59a/0x890
[ 2580.506922]  [<ffffffff81217300>] ? path_mountpoint+0x180/0x240
[ 2580.506924]  [<ffffffff8121ad2a>] SyS_renameat2+0x55a/0x5f0
[ 2580.506925]  [<ffffffff8121adfe>] SyS_rename+0x1e/0x20
[ 2580.506926]  [<ffffffff815f7cf2>] entry_SYSCALL_64_fastpath+0x1a/0xa4
[ 2580.506927] INFO: task pool:7488 blocked for more than 120 seconds.
[ 2580.506928]       Tainted: G           O    4.8.6-1-ARCH #1
[ 2580.506929] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2580.506930] pool            D ffff88020f07bb48     0  7488  11232 0x00000000
[ 2580.506932]  ffff88020f07bb48 0087960000796900 ffffffff8180d500 ffff880253488e40
[ 2580.506933]  0000000000000000 ffff88020f07c000 ffff88022de9aff8 ffff88020f07bb80
[ 2580.506935]  ffff88022de9b010 fffffffffffffffe ffff88020f07bb60 ffffffff815f3d6c
[ 2580.506936] Call Trace:
[ 2580.506937]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 2580.506939]  [<ffffffff815f62e9>] rwsem_down_read_failed+0xf9/0x150
[ 2580.506940]  [<ffffffff8130c238>] call_rwsem_down_read_failed+0x18/0x30
[ 2580.506941]  [<ffffffff815f5b87>] down_read+0x17/0x30
[ 2580.506942]  [<ffffffff81213db0>] lookup_slow+0x60/0x180
[ 2580.506943]  [<ffffffff812168f3>] walk_component+0x1e3/0x2a0
[ 2580.506944]  [<ffffffff812170c7>] path_lookupat+0x67/0x120
[ 2580.506945]  [<ffffffff8121913d>] filename_lookup+0xad/0x140
[ 2580.506947]  [<ffffffff81218dc7>] ? getname_flags+0x37/0x190
[ 2580.506948]  [<ffffffff812192a6>] user_path_at_empty+0x36/0x40
[ 2580.506949]  [<ffffffff8120e8d6>] vfs_fstatat+0x66/0xc0
[ 2580.506951]  [<ffffffff8120edf6>] SyS_newlstat+0x36/0x70
[ 2580.506953]  [<ffffffff81101505>] ? SyS_futex+0x85/0x170
[ 2580.506955]  [<ffffffff815f7cf2>] entry_SYSCALL_64_fastpath+0x1a/0xa4
[ 2580.506960] INFO: task jbd2/dm-3-8:3344 blocked for more than 120 seconds.
[ 2580.506960]       Tainted: G           O    4.8.6-1-ARCH #1
[ 2580.506961] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2580.506962] jbd2/dm-3-8     D ffff88022e6839f8     0  3344      2 0x00000000
[ 2580.506964]  ffff88022e6839f8 00ff8801f3923750 ffff880255a6b900 ffff88023549b900
[ 2580.506965]  ffffffff810a6b75 ffff88022e684000 ffff88025f297f80 7fffffffffffffff
[ 2580.506967]  ffffffff815f4620 ffff88022e683b50 ffff88022e683a10 ffffffff815f3d6c
[ 2580.506968] Call Trace:
[ 2580.506969]  [<ffffffff810a6b75>] ? wake_up_process+0x15/0x20
[ 2580.506970]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2580.506971]  [<ffffffff815f3d6c>] schedule+0x3c/0x90
[ 2580.506972]  [<ffffffff815f6cba>] schedule_timeout+0x2da/0x420
[ 2580.506974]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 2580.506975]  [<ffffffff810f2d01>] ? ktime_get+0x41/0xb0
[ 2580.506976]  [<ffffffff815f4620>] ? bit_wait+0x60/0x60
[ 2580.506977]  [<ffffffff815f3544>] io_schedule_timeout+0xa4/0x110
[ 2580.506978]  [<ffffffff815f463b>] bit_wait_io+0x1b/0x70
[ 2580.506979]  [<ffffffff815f4228>] __wait_on_bit+0x58/0x90
[ 2580.506980]  [<ffffffff81177c23>] ? find_get_pages_tag+0x2a3/0x2f0
[ 2580.506982]  [<ffffffff81177132>] wait_on_page_bit+0xc2/0xe0
[ 2580.506983]  [<ffffffff810c0490>] ? autoremove_wake_function+0x40/0x40
[ 2580.506985]  [<ffffffff8117724f>] __filemap_fdatawait_range+0xff/0x170
[ 2580.506987]  [<ffffffff811772d4>] filemap_fdatawait_range+0x14/0x30
[ 2580.506988]  [<ffffffff81177313>] filemap_fdatawait+0x23/0x30
[ 2580.506990]  [<ffffffffa02bcf63>] jbd2_journal_commit_transaction+0xa13/0x1980 [jbd2]
[ 2580.506993]  [<ffffffff810eb84e>] ? try_to_del_timer_sync+0x5e/0x90
[ 2580.506995]  [<ffffffffa02c30ca>] kjournald2+0xca/0x260 [jbd2]
[ 2580.506997]  [<ffffffff810c0450>] ? wake_atomic_t_function+0x60/0x60
[ 2580.506999]  [<ffffffffa02c3000>] ? commit_timeout+0x10/0x10 [jbd2]
[ 2580.507001]  [<ffffffff8109be38>] kthread+0xd8/0xf0
[ 2580.507002]  [<ffffffff8102c782>] ? __switch_to+0x2d2/0x630
[ 2580.507003]  [<ffffffff815f7eff>] ret_from_fork+0x1f/0x40
[ 2580.507005]  [<ffffffff8109bd60>] ? kthread_worker_fn+0x170/0x170
[ 2677.913724] usb 4-1: reset SuperSpeed USB device number 2 using xhci_hcd

x33a

Forum Fellow

Registered: 2009-08-15

Posts: 4,587

Re: Task pool blocked for more than 120 seconds

If I understand correctly, you are using a veracrypt file based volume on a dm-crypt partition. This amounts to double encryption. How much performance do you get when you write to just the dm-crypt partition?

mxfm

Member

Registered: 2015-10-23

Posts: 163

Re: Task pool blocked for more than 120 seconds

If I understand correctly, you are using a veracrypt file based volume on a dm-crypt partition. This amounts to double encryption. How much performance do you get when you write to just the dm-crypt partition?

Yes, I do. The performance of dm-crypt partition is fine, I only get troubles when writing to veracrypt volume (reading is also fine).