Bug#908764: marked as done (I/O stuck in jbd2_journal_commit_transaction)
Your message dated Fri, 28 May 2021 12:23:53 -0700 (PDT)
with message-id <60b14349.1c69fb81.6952a.f8e7@mx.google.com>
and subject line Closing this bug (BTS maintenance for src:linux bugs)
has caused the Debian Bug report #908764,
regarding I/O stuck in jbd2_journal_commit_transaction
to be marked as done.
This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.
(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact owner@bugs.debian.org
immediately.)
--
908764: https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=908764
Debian Bug Tracking System
Contact owner@bugs.debian.org with problems
--- Begin Message ---
- To: submit@bugs.debian.org
- Subject: I/O stuck in jbd2_journal_commit_transaction
- From: Sergey Aleynikov <sergey.aleynikov@gmail.com>
- Date: Thu, 13 Sep 2018 17:44:46 +0300
- Message-id: <CAKNj8S0OGpqnNKASJHz6jBmyKrWT9eh65i8j5TfkYJDbRRNZYg@mail.gmail.com>
Package: linux-image
Version: 4.9.0-8
After each reboot, all I/O on the machine gets stuck for a couple of
minutes with the following output in dmesg:
[94491.612134] INFO: task jbd2/dm-3-8:2335 blocked for more than 120 seconds.
[94491.612136] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94491.612136] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94491.612137] jbd2/dm-3-8 D 0 2335 2 0x00000000
[94491.612141] ffff92e66d7ab000 0000000000000000 ffff92f6acb85100
ffff92e69f758980
[94491.612143] ffff92e69262e140 ffffab4cdb017ca0 ffffffff83610ee9
ffff92e68b979088
[94491.612145] 0000000000000246 ffff92e69f758980 ffffab4cdb017d80
ffff92f6acb85100
[94491.612147] Call Trace:
[94491.612154] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94491.612157] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94491.612159] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94491.612169] [<ffffffffc03a22ef>] ?
jbd2_journal_commit_transaction+0x25f/0x17b0 [jbd2]
[94491.612172] [<ffffffff830aef01>] ? update_curr+0xe1/0x160
[94491.612174] [<ffffffff830add04>] ? account_entity_dequeue+0xa4/0xc0
[94491.612176] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94491.612179] [<ffffffff830a07c2>] ? finish_task_switch+0x152/0x200
[94491.612182] [<ffffffffc03a7c62>] ? kjournald2+0xc2/0x260 [jbd2]
[94491.612184] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94491.612187] [<ffffffffc03a7ba0>] ? commit_timeout+0x10/0x10 [jbd2]
[94491.612189] [<ffffffff83099329>] ? kthread+0xd9/0xf0
[94491.612190] [<ffffffff83099250>] ? kthread_park+0x60/0x60
[94491.612192] [<ffffffff83615df7>] ? ret_from_fork+0x57/0x70
[94491.612219] INFO: task kworker/u113:1:13607 blocked for more than
120 seconds.
[94491.612220] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94491.612220] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94491.612221] kworker/u113:1 D 0 13607 2 0x00000000
[94491.612227] Workqueue: writeback wb_workfn (flush-253:3)
[94491.612228] ffff92e66d7ab000 0000000000000000 ffff92e5a29a1040
ffff92e69f658980
[94491.612230] ffff92e692613040 ffffab4d1b9c78b0 ffffffff83610ee9
0000000000000000
[94491.612231] 0000000000000001 ffff92e69f658980 ffffab4d1b9c78d0
ffff92e5a29a1040
[94491.612233] Call Trace:
[94491.612235] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94491.612237] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94491.612240] [<ffffffffc039f086>] ? wait_transaction_locked+0x86/0xc0 [jbd2]
[94491.612241] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94491.612244] [<ffffffffc039f2a8>] ?
add_transaction_credits+0x1b8/0x290 [jbd2]
[94491.612246] [<ffffffffc039f4d5>] ? start_this_handle+0x105/0x400 [jbd2]
[94491.612250] [<ffffffff831e6c7c>] ? kmem_cache_alloc+0xbc/0x530
[94491.612252] [<ffffffffc039f9f9>] ? jbd2__journal_start+0xd9/0x1e0 [jbd2]
[94491.612278] [<ffffffffc046c39b>] ? ext4_writepages+0x45b/0xd60 [ext4]
[94491.612280] [<ffffffff830b4ec1>] ? update_group_capacity+0x21/0x1c0
[94491.612283] [<ffffffff83331c16>] ? cpumask_next_and+0x26/0x40
[94491.612285] [<ffffffff8323832d>] ? __writeback_single_inode+0x3d/0x320
[94491.612286] [<ffffffff83238ad1>] ? writeback_sb_inodes+0x221/0x4f0
[94491.612288] [<ffffffff83238e27>] ? __writeback_inodes_wb+0x87/0xb0
[94491.612290] [<ffffffff8323919e>] ? wb_writeback+0x27e/0x310
[94491.612292] [<ffffffff83224cdc>] ? get_nr_inodes+0x3c/0x60
[94491.612293] [<ffffffff83239b04>] ? wb_workfn+0x2b4/0x380
[94491.612295] [<ffffffff83092fea>] ? process_one_work+0x18a/0x420
[94491.612296] [<ffffffff830932cd>] ? worker_thread+0x4d/0x490
[94491.612297] [<ffffffff83093280>] ? process_one_work+0x420/0x420
[94491.612299] [<ffffffff83099329>] ? kthread+0xd9/0xf0
[94491.612301] [<ffffffff83099250>] ? kthread_park+0x60/0x60
[94491.612302] [<ffffffff83615df7>] ? ret_from_fork+0x57/0x70
[94491.612303] INFO: task qemu-system-x86:13636 blocked for more than
120 seconds.
[94491.612304] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94491.612304] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94491.612305] qemu-system-x86 D 0 13636 1 0x00000000
[94491.612307] ffff92e66d7ab000 0000000000000000 ffff92e5a20f9040
ffff92e69f798980
[94491.612308] ffff92e692633000 ffffab4d1ba37a40 ffffffff83610ee9
ffffffff836113d2
[94491.612310] 00ffab4d1ba37c10 ffff92e69f798980 ffffab4d1ba37a60
ffff92e5a20f9040
[94491.612311] Call Trace:
[94491.612313] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94491.612315] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94491.612316] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94491.612319] [<ffffffffc039f086>] ? wait_transaction_locked+0x86/0xc0 [jbd2]
[94491.612320] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94491.612323] [<ffffffffc039f2a8>] ?
add_transaction_credits+0x1b8/0x290 [jbd2]
[94491.612325] [<ffffffffc039f4d5>] ? start_this_handle+0x105/0x400 [jbd2]
[94491.612328] [<ffffffffc039f9f9>] ? jbd2__journal_start+0xd9/0x1e0 [jbd2]
[94491.612335] [<ffffffffc04702dd>] ? ext4_dirty_inode+0x2d/0x60 [ext4]
[94491.612337] [<ffffffff832380fb>] ? __mark_inode_dirty+0x16b/0x360
[94491.612339] [<ffffffff83225829>] ? generic_update_time+0x79/0xd0
[94491.612340] [<ffffffff83225a36>] ? current_time+0x36/0x70
[94491.612342] [<ffffffff83225b2f>] ? file_update_time+0xbf/0x110
[94491.612343] [<ffffffff8321e7c0>] ? poll_select_copy_remaining+0x150/0x150
[94491.612345] [<ffffffff831830f9>] ? __generic_file_write_iter+0x99/0x1b0
[94491.612352] [<ffffffffc0462220>] ? ext4_file_write_iter+0x90/0x370 [ext4]
[94491.612354] [<ffffffff83344767>] ? import_iovec+0x37/0xd0
[94491.612357] [<ffffffff8325972b>] ? aio_write+0xfb/0x150
[94491.612359] [<ffffffff8321e7c0>] ? poll_select_copy_remaining+0x150/0x150
[94491.612360] [<ffffffff831e6c7c>] ? kmem_cache_alloc+0xbc/0x530
[94491.612362] [<ffffffff8321e7c0>] ? poll_select_copy_remaining+0x150/0x150
[94491.612364] [<ffffffff832285f1>] ? __fget_light+0x21/0x60
[94491.612365] [<ffffffff8325a759>] ? do_io_submit+0x2b9/0x620
[94491.612368] [<ffffffff83003b7d>] ? do_syscall_64+0x8d/0xf0
[94491.612369] [<ffffffff83615c4e>] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6
[94612.444130] INFO: task jbd2/dm-3-8:2335 blocked for more than 120 seconds.
[94612.444132] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94612.444132] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94612.444133] jbd2/dm-3-8 D 0 2335 2 0x00000000
[94612.444137] ffff92e66d7ab000 0000000000000000 ffff92f6acb85100
ffff92e69f758980
[94612.444139] ffff92e69262e140 ffffab4cdb017ca0 ffffffff83610ee9
ffff92e68b979088
[94612.444141] 0000000000000246 ffff92e69f758980 ffffab4cdb017d80
ffff92f6acb85100
[94612.444143] Call Trace:
[94612.444150] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94612.444153] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94612.444155] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94612.444164] [<ffffffffc03a22ef>] ?
jbd2_journal_commit_transaction+0x25f/0x17b0 [jbd2]
[94612.444167] [<ffffffff830aef01>] ? update_curr+0xe1/0x160
[94612.444169] [<ffffffff830add04>] ? account_entity_dequeue+0xa4/0xc0
[94612.444171] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94612.444174] [<ffffffff830a07c2>] ? finish_task_switch+0x152/0x200
[94612.444178] [<ffffffffc03a7c62>] ? kjournald2+0xc2/0x260 [jbd2]
[94612.444179] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94612.444182] [<ffffffffc03a7ba0>] ? commit_timeout+0x10/0x10 [jbd2]
[94612.444184] [<ffffffff83099329>] ? kthread+0xd9/0xf0
[94612.444186] [<ffffffff83099250>] ? kthread_park+0x60/0x60
[94612.444188] [<ffffffff83615df7>] ? ret_from_fork+0x57/0x70
[94612.444212] INFO: task kworker/u113:1:13607 blocked for more than
120 seconds.
[94612.444213] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94612.444213] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94612.444214] kworker/u113:1 D 0 13607 2 0x00000000
[94612.444219] Workqueue: writeback wb_workfn (flush-253:3)
[94612.444221] ffff92e66d7ab000 0000000000000000 ffff92e5a29a1040
ffff92e69f658980
[94612.444223] ffff92e692613040 ffffab4d1b9c78b0 ffffffff83610ee9
0000000000000000
[94612.444224] 0000000000000001 ffff92e69f658980 ffffab4d1b9c78d0
ffff92e5a29a1040
[94612.444226] Call Trace:
[94612.444228] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94612.444230] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94612.444232] [<ffffffffc039f086>] ? wait_transaction_locked+0x86/0xc0 [jbd2]
[94612.444234] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94612.444237] [<ffffffffc039f2a8>] ?
add_transaction_credits+0x1b8/0x290 [jbd2]
[94612.444239] [<ffffffffc039f4d5>] ? start_this_handle+0x105/0x400 [jbd2]
[94612.444242] [<ffffffff831e6c7c>] ? kmem_cache_alloc+0xbc/0x530
[94612.444245] [<ffffffffc039f9f9>] ? jbd2__journal_start+0xd9/0x1e0 [jbd2]
[94612.444269] [<ffffffffc046c39b>] ? ext4_writepages+0x45b/0xd60 [ext4]
[94612.444270] [<ffffffff830b4ec1>] ? update_group_capacity+0x21/0x1c0
[94612.444272] [<ffffffff83331c16>] ? cpumask_next_and+0x26/0x40
[94612.444274] [<ffffffff8323832d>] ? __writeback_single_inode+0x3d/0x320
[94612.444276] [<ffffffff83238ad1>] ? writeback_sb_inodes+0x221/0x4f0
[94612.444278] [<ffffffff83238e27>] ? __writeback_inodes_wb+0x87/0xb0
[94612.444279] [<ffffffff8323919e>] ? wb_writeback+0x27e/0x310
[94612.444281] [<ffffffff83224cdc>] ? get_nr_inodes+0x3c/0x60
[94612.444283] [<ffffffff83239b04>] ? wb_workfn+0x2b4/0x380
[94612.444284] [<ffffffff83092fea>] ? process_one_work+0x18a/0x420
[94612.444286] [<ffffffff830932cd>] ? worker_thread+0x4d/0x490
[94612.444287] [<ffffffff83093280>] ? process_one_work+0x420/0x420
[94612.444289] [<ffffffff83099329>] ? kthread+0xd9/0xf0
[94612.444290] [<ffffffff83099250>] ? kthread_park+0x60/0x60
[94612.444292] [<ffffffff83615df7>] ? ret_from_fork+0x57/0x70
[94612.444293] INFO: task qemu-system-x86:13636 blocked for more than
120 seconds.
[94612.444294] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94612.444294] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94612.444295] qemu-system-x86 D 0 13636 1 0x00000000
[94612.444296] ffff92e66d7ab000 0000000000000000 ffff92e5a20f9040
ffff92e69f798980
[94612.444298] ffff92e692633000 ffffab4d1ba37a40 ffffffff83610ee9
ffffffff836113d2
[94612.444299] 00ffab4d1ba37c10 ffff92e69f798980 ffffab4d1ba37a60
ffff92e5a20f9040
[94612.444301] Call Trace:
[94612.444303] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94612.444304] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94612.444306] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94612.444308] [<ffffffffc039f086>] ? wait_transaction_locked+0x86/0xc0 [jbd2]
[94612.444310] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94612.444312] [<ffffffffc039f2a8>] ?
add_transaction_credits+0x1b8/0x290 [jbd2]
[94612.444315] [<ffffffffc039f4d5>] ? start_this_handle+0x105/0x400 [jbd2]
[94612.444317] [<ffffffffc039f9f9>] ? jbd2__journal_start+0xd9/0x1e0 [jbd2]
[94612.444325] [<ffffffffc04702dd>] ? ext4_dirty_inode+0x2d/0x60 [ext4]
[94612.444326] [<ffffffff832380fb>] ? __mark_inode_dirty+0x16b/0x360
[94612.444328] [<ffffffff83225829>] ? generic_update_time+0x79/0xd0
[94612.444330] [<ffffffff83225a36>] ? current_time+0x36/0x70
[94612.444331] [<ffffffff83225b2f>] ? file_update_time+0xbf/0x110
[94612.444333] [<ffffffff8321e7c0>] ? poll_select_copy_remaining+0x150/0x150
[94612.444335] [<ffffffff831830f9>] ? __generic_file_write_iter+0x99/0x1b0
[94612.444341] [<ffffffffc0462220>] ? ext4_file_write_iter+0x90/0x370 [ext4]
[94612.444343] [<ffffffff83344767>] ? import_iovec+0x37/0xd0
[94612.444345] [<ffffffff8325972b>] ? aio_write+0xfb/0x150
[94612.444347] [<ffffffff8321e7c0>] ? poll_select_copy_remaining+0x150/0x150
[94612.444348] [<ffffffff831e6c7c>] ? kmem_cache_alloc+0xbc/0x530
[94612.444350] [<ffffffff8321e7c0>] ? poll_select_copy_remaining+0x150/0x150
[94612.444351] [<ffffffff832285f1>] ? __fget_light+0x21/0x60
[94612.444353] [<ffffffff8325a759>] ? do_io_submit+0x2b9/0x620
[94612.444355] [<ffffffff83003b7d>] ? do_syscall_64+0x8d/0xf0
[94612.444357] [<ffffffff83615c4e>] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6
[94733.276160] INFO: task jbd2/dm-3-8:2335 blocked for more than 120 seconds.
[94733.276162] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94733.276163] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94733.276164] jbd2/dm-3-8 D 0 2335 2 0x00000000
[94733.276167] ffff92e66d7ab000 0000000000000000 ffff92f6acb85100
ffff92e69f758980
[94733.276170] ffff92e69262e140 ffffab4cdb017ca0 ffffffff83610ee9
ffff92e68b979088
[94733.276171] 0000000000000246 ffff92e69f758980 ffffab4cdb017d80
ffff92f6acb85100
[94733.276173] Call Trace:
[94733.276180] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94733.276183] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94733.276185] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94733.276194] [<ffffffffc03a22ef>] ?
jbd2_journal_commit_transaction+0x25f/0x17b0 [jbd2]
[94733.276197] [<ffffffff830aef01>] ? update_curr+0xe1/0x160
[94733.276199] [<ffffffff830add04>] ? account_entity_dequeue+0xa4/0xc0
[94733.276201] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94733.276204] [<ffffffff830a07c2>] ? finish_task_switch+0x152/0x200
[94733.276208] [<ffffffffc03a7c62>] ? kjournald2+0xc2/0x260 [jbd2]
[94733.276209] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94733.276212] [<ffffffffc03a7ba0>] ? commit_timeout+0x10/0x10 [jbd2]
[94733.276214] [<ffffffff83099329>] ? kthread+0xd9/0xf0
[94733.276216] [<ffffffff83099250>] ? kthread_park+0x60/0x60
[94733.276218] [<ffffffff83615df7>] ? ret_from_fork+0x57/0x70
[94733.276242] INFO: task qemu-system-x86:13896 blocked for more than
120 seconds.
[94733.276242] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94733.276243] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94733.276243] qemu-system-x86 D 0 13896 1 0x00000000
[94733.276245] ffff92f6af41b800 0000000000000000 ffff92f5a8b23000
ffff92f6bf058980
[94733.276247] ffff92f6b1c2b100 ffffab4d1add7a90 ffffffff83610ee9
0000000000000001
[94733.276249] 0000000000000001 ffff92f6bf058980 ffffab4d1add7ab0
ffff92f5a8b23000
[94733.276250] Call Trace:
[94733.276252] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94733.276254] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94733.276257] [<ffffffffc039f086>] ? wait_transaction_locked+0x86/0xc0 [jbd2]
[94733.276258] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94733.276261] [<ffffffffc039f2a8>] ?
add_transaction_credits+0x1b8/0x290 [jbd2]
[94733.276264] [<ffffffff8318a1ee>] ? get_page_from_freelist+0x88e/0xb20
[94733.276266] [<ffffffffc039f4d5>] ? start_this_handle+0x105/0x400 [jbd2]
[94733.276269] [<ffffffffc039f9f9>] ? jbd2__journal_start+0xd9/0x1e0 [jbd2]
[94733.276293] [<ffffffffc04702dd>] ? ext4_dirty_inode+0x2d/0x60 [ext4]
[94733.276295] [<ffffffff832380fb>] ? __mark_inode_dirty+0x16b/0x360
[94733.276298] [<ffffffff83225829>] ? generic_update_time+0x79/0xd0
[94733.276299] [<ffffffff83225a36>] ? current_time+0x36/0x70
[94733.276301] [<ffffffff83225b2f>] ? file_update_time+0xbf/0x110
[94733.276302] [<ffffffff831830f9>] ? __generic_file_write_iter+0x99/0x1b0
[94733.276309] [<ffffffffc0462220>] ? ext4_file_write_iter+0x90/0x370 [ext4]
[94733.276311] [<ffffffff830add04>] ? account_entity_dequeue+0xa4/0xc0
[94733.276313] [<ffffffff8320511a>] ? __check_object_size+0xfa/0x1d8
[94733.276314] [<ffffffff83207d6b>] ? do_iter_readv_writev+0xbb/0x140
[94733.276316] [<ffffffff8320928e>] ? do_readv_writev+0x19e/0x240
[94733.276322] [<ffffffffc0462190>] ? ext4_dax_fault+0x140/0x140 [ext4]
[94733.276324] [<ffffffff83058880>] ? hpet_interrupt_handler+0x30/0x30
[94733.276326] [<ffffffff832096fd>] ? do_pwritev+0xad/0xc0
[94733.276328] [<ffffffff83003b7d>] ? do_syscall_64+0x8d/0xf0
[94733.276330] [<ffffffff83615c4e>] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6
[94733.276333] INFO: task kworker/u113:1:13607 blocked for more than
120 seconds.
[94733.276333] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94733.276334] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94733.276334] kworker/u113:1 D 0 13607 2 0x00000000
[94733.276338] Workqueue: writeback wb_workfn (flush-253:3)
[94733.276339] ffff92e66d7ab000 0000000000000000 ffff92e5a29a1040
ffff92e69f658980
[94733.276341] ffff92e692613040 ffffab4d1b9c78b0 ffffffff83610ee9
0000000000000000
[94733.276342] 0000000000000001 ffff92e69f658980 ffffab4d1b9c78d0
ffff92e5a29a1040
[94733.276344] Call Trace:
[94733.276346] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94733.276348] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94733.276351] [<ffffffffc039f086>] ? wait_transaction_locked+0x86/0xc0 [jbd2]
[94733.276352] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94733.276355] [<ffffffffc039f2a8>] ?
add_transaction_credits+0x1b8/0x290 [jbd2]
[94733.276357] [<ffffffffc039f4d5>] ? start_this_handle+0x105/0x400 [jbd2]
[94733.276360] [<ffffffff831e6c7c>] ? kmem_cache_alloc+0xbc/0x530
[94733.276363] [<ffffffffc039f9f9>] ? jbd2__journal_start+0xd9/0x1e0 [jbd2]
[94733.276370] [<ffffffffc046c39b>] ? ext4_writepages+0x45b/0xd60 [ext4]
[94733.276372] [<ffffffff830b4ec1>] ? update_group_capacity+0x21/0x1c0
[94733.276376] [<ffffffff83331c16>] ? cpumask_next_and+0x26/0x40
[94733.276379] [<ffffffff8323832d>] ? __writeback_single_inode+0x3d/0x320
[94733.276380] [<ffffffff83238ad1>] ? writeback_sb_inodes+0x221/0x4f0
[94733.276383] [<ffffffff83238e27>] ? __writeback_inodes_wb+0x87/0xb0
[94733.276384] [<ffffffff8323919e>] ? wb_writeback+0x27e/0x310
[94733.276386] [<ffffffff83224cdc>] ? get_nr_inodes+0x3c/0x60
[94733.276388] [<ffffffff83239b04>] ? wb_workfn+0x2b4/0x380
[94733.276390] [<ffffffff83092fea>] ? process_one_work+0x18a/0x420
[94733.276391] [<ffffffff830932cd>] ? worker_thread+0x4d/0x490
[94733.276392] [<ffffffff83093280>] ? process_one_work+0x420/0x420
[94733.276394] [<ffffffff83099329>] ? kthread+0xd9/0xf0
[94733.276396] [<ffffffff83099250>] ? kthread_park+0x60/0x60
[94733.276397] [<ffffffff83615df7>] ? ret_from_fork+0x57/0x70
[94733.276398] INFO: task qemu-system-x86:13636 blocked for more than
120 seconds.
[94733.276399] Not tainted 4.9.0-8-amd64 #1 Debian 4.9.110-3+deb9u4
[94733.276399] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[94733.276400] qemu-system-x86 D 0 13636 1 0x00000000
[94733.276401] ffff92e66d7ab000 0000000000000000 ffff92e5a20f9040
ffff92e69f798980
[94733.276403] ffff92e692633000 ffffab4d1ba37a40 ffffffff83610ee9
ffffffff836113d2
[94733.276404] 00ffab4d1ba37c10 ffff92e69f798980 ffffab4d1ba37a60
ffff92e5a20f9040
[94733.276406] Call Trace:
[94733.276408] [<ffffffff83610ee9>] ? __schedule+0x239/0x6f0
[94733.276409] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94733.276411] [<ffffffff836113d2>] ? schedule+0x32/0x80
[94733.276414] [<ffffffffc039f086>] ? wait_transaction_locked+0x86/0xc0 [jbd2]
[94733.276415] [<ffffffff830bbfb0>] ? prepare_to_wait_event+0xf0/0xf0
[94733.276418] [<ffffffffc039f2a8>] ?
add_transaction_credits+0x1b8/0x290 [jbd2]
[94733.276420] [<ffffffffc039f4d5>] ? start_this_handle+0x105/0x400 [jbd2]
[94733.276423] [<ffffffffc039f9f9>] ? jbd2__journal_start+0xd9/0x1e0 [jbd2]
[94733.276431] [<ffffffffc04702dd>] ? ext4_dirty_inode+0x2d/0x60 [ext4]
[94733.276433] [<ffffffff832380fb>] ? __mark_inode_dirty+0x16b/0x360
[94733.276435] [<ffffffff83225829>] ? generic_update_time+0x79/0xd0
[94733.276437] [<ffffffff83225a36>] ? current_time+0x36/0x70
[94733.276438] [<ffffffff83225b2f>] ? file_update_time+0xbf/0x110
[94733.276440] [<ffffffff8321e7c0>] ? poll_select_copy_remaining+0x150/0x150
[94733.276441] [<ffffffff831830f9>] ? __generic_file_write_iter+0x99/0x1b0
[94733.276448] [<ffffffffc0462220>] ? ext4_file_write_iter+0x90/0x370 [ext4]
[94733.276450] [<ffffffff83344767>] ? import_iovec+0x37/0xd0
[94733.276453] [<ffffffff8325972b>] ? aio_write+0xfb/0x150
[94733.276455] [<ffffffff8321e7c0>] ? poll_select_copy_remaining+0x150/0x150
[94733.276456] [<ffffffff831e6c7c>] ? kmem_cache_alloc+0xbc/0x530
[94733.276458] [<ffffffff8321e7c0>] ? poll_select_copy_remaining+0x150/0x150
[94733.276460] [<ffffffff832285f1>] ? __fget_light+0x21/0x60
[94733.276462] [<ffffffff8325a759>] ? do_io_submit+0x2b9/0x620
[94733.276464] [<ffffffff83003b7d>] ? do_syscall_64+0x8d/0xf0
[94733.276465] [<ffffffff83615c4e>] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6
This happens randomly after initiating a moderate level of i/o
activity and only once after each reboot. The system has 56 active cpu
cores. The disk system layout is following:
NAME MAJ:MIN RM SIZE RO TYPE MOUNTPOINT
sda 8:0 0 2.7T 0 disk
└─sda1 8:1 0 2.7T 0 part
└─md127 9:127 0 10.9T 0 raid6
└─cryptroot 253:0 0 10.9T 0 crypt
├─cryptroot-part1 253:1 0 65G 0 part [SWAP]
├─cryptroot-part2 253:2 0 100G 0 part /
├─cryptroot-part3 253:3 0 9T 0 part /mnt/main_storage
└─cryptroot-part4 253:4 0 1.8T 0 part
sdb 8:16 0 2.7T 0 disk
└─sdb1 8:17 0 2.7T 0 part
└─md127 9:127 0 10.9T 0 raid6
└─cryptroot 253:0 0 10.9T 0 crypt
├─cryptroot-part1 253:1 0 65G 0 part [SWAP]
├─cryptroot-part2 253:2 0 100G 0 part /
├─cryptroot-part3 253:3 0 9T 0 part /mnt/main_storage
└─cryptroot-part4 253:4 0 1.8T 0 part
sdc 8:32 0 2.7T 0 disk
└─sdc1 8:33 0 2.7T 0 part
└─md127 9:127 0 10.9T 0 raid6
└─cryptroot 253:0 0 10.9T 0 crypt
├─cryptroot-part1 253:1 0 65G 0 part [SWAP]
├─cryptroot-part2 253:2 0 100G 0 part /
├─cryptroot-part3 253:3 0 9T 0 part /mnt/main_storage
└─cryptroot-part4 253:4 0 1.8T 0 part
sdd 8:48 0 2.7T 0 disk
└─sdd1 8:49 0 2.7T 0 part
└─md127 9:127 0 10.9T 0 raid6
└─cryptroot 253:0 0 10.9T 0 crypt
├─cryptroot-part1 253:1 0 65G 0 part [SWAP]
├─cryptroot-part2 253:2 0 100G 0 part /
├─cryptroot-part3 253:3 0 9T 0 part /mnt/main_storage
└─cryptroot-part4 253:4 0 1.8T 0 part
sde 8:64 0 2.7T 0 disk
└─sde1 8:65 0 2.7T 0 part
└─md127 9:127 0 10.9T 0 raid6
└─cryptroot 253:0 0 10.9T 0 crypt
├─cryptroot-part1 253:1 0 65G 0 part [SWAP]
├─cryptroot-part2 253:2 0 100G 0 part /
├─cryptroot-part3 253:3 0 9T 0 part /mnt/main_storage
└─cryptroot-part4 253:4 0 1.8T 0 part
sdf 8:80 0 2.7T 0 disk
└─sdf1 8:81 0 2.7T 0 part
└─md127 9:127 0 10.9T 0 raid6
└─cryptroot 253:0 0 10.9T 0 crypt
├─cryptroot-part1 253:1 0 65G 0 part [SWAP]
├─cryptroot-part2 253:2 0 100G 0 part /
├─cryptroot-part3 253:3 0 9T 0 part /mnt/main_storage
└─cryptroot-part4 253:4 0 1.8T 0 part
sdg 8:96 1 14.5G 0 disk
├─sdg1 8:97 1 244.9M 0 part /boot
├─sdg2 8:98 1 1G 0 part
└─sdg3 8:99 1 13.2G 0 part
--- End Message ---
--- Begin Message ---
Hi
This bug was filed for a very old kernel or the bug is old itself
without resolution.
If you can reproduce it with
- the current version in unstable/testing
- the latest kernel from backports
please reopen the bug, see https://www.debian.org/Bugs/server-control
for details.
Regards,
Salvatore
--- End Message ---
Reply to: