From b9916736fd789fceaf82afc80cbb561e67eeac67 Mon Sep 17 00:00:00 2001 From: Dmitry Vyukov Date: Mon, 24 Oct 2022 09:18:10 +0200 Subject: pkg/report: add some "inconsistent lock state" samples --- pkg/report/testdata/linux/report/665 | 97 +++++++++++++++++++ pkg/report/testdata/linux/report/666 | 68 ++++++++++++++ pkg/report/testdata/linux/report/667 | 81 ++++++++++++++++ pkg/report/testdata/linux/report/668 | 79 ++++++++++++++++ pkg/report/testdata/linux/report/669 | 174 +++++++++++++++++++++++++++++++++++ 5 files changed, 499 insertions(+) create mode 100644 pkg/report/testdata/linux/report/665 create mode 100644 pkg/report/testdata/linux/report/666 create mode 100644 pkg/report/testdata/linux/report/667 create mode 100644 pkg/report/testdata/linux/report/668 create mode 100644 pkg/report/testdata/linux/report/669 (limited to 'pkg/report/testdata/linux') diff --git a/pkg/report/testdata/linux/report/665 b/pkg/report/testdata/linux/report/665 new file mode 100644 index 000000000..37d13f56f --- /dev/null +++ b/pkg/report/testdata/linux/report/665 @@ -0,0 +1,97 @@ +TITLE: inconsistent lock state in _atomic_dec_and_lock + +[ 1336.868665][ T9712] ================================ +[ 1336.869886][ T9712] WARNING: inconsistent lock state +[ 1336.871156][ T9712] 6.0.0-rc7-syzkaller-18095-gbbed346d5a96 #0 Not tainted +[ 1336.872860][ T9712] -------------------------------- +[ 1336.873490][ T9714] ntfs3: loop4: Mark volume as dirty due to NTFS errors +[ 1336.874094][ T9712] inconsistent {IN-HARDIRQ-W} -> {HARDIRQ-ON-W} usage. +[ 1336.877457][ T9712] syz-executor.3/9712 [HC0[0]:SC0[0]:HE1:SE1] takes: +[ 1336.879185][ T9712] ffff0000d10c2577 (&folio_wait_table[i]){?.-.}-{2:2}, at: _atomic_dec_and_lock+0xc8/0x130 +[ 1336.881722][ T9712] {IN-HARDIRQ-W} state was registered at: +[ 1336.883125][ T9712] lock_acquire+0x100/0x1f8 +[ 1336.884272][ T9712] _raw_spin_lock_irqsave+0x6c/0xb4 +[ 1336.885620][ T9712] folio_wake_bit+0x88/0x254 +[ 1336.886766][ T9712] folio_unlock+0xb4/0x210 +[ 1336.887868][ T9712] unlock_page+0x38/0xb8 +[ 1336.888929][ T9712] end_buffer_async_read+0x2a8/0x5f8 +[ 1336.890277][ T9712] end_buffer_async_read_io+0x118/0x12c +[ 1336.891694][ T9712] end_bio_bh_io_sync+0x5c/0xac +[ 1336.892944][ T9712] bio_endio+0x28c/0x2d8 +[ 1336.894030][ T9712] blk_mq_end_request_batch+0x18c/0x5ec +[ 1336.895397][ T9712] nvme_pci_complete_batch+0x130/0x14c +[ 1336.895719][ T9716] loop5: detected capacity change from 0 to 4096 +[ 1336.896791][ T9712] nvme_irq+0x64/0xa8 +[ 1336.896802][ T9712] __handle_irq_event_percpu+0xa8/0x294 +[ 1336.900793][ T9712] handle_irq_event+0x4c/0xe8 +[ 1336.901983][ T9712] handle_fasteoi_irq+0x1b4/0x324 +[ 1336.902059][ T9716] ntfs3: loop5: Different NTFS' sector size (1024) and media sector size (512) +[ 1336.903236][ T9712] generic_handle_domain_irq+0x4c/0x6c +[ 1336.903247][ T9712] gic_handle_irq+0x78/0x1b4 +[ 1336.906182][ T9717] loop1: detected capacity change from 0 to 4096 +[ 1336.906854][ T9712] call_on_irq_stack+0x2c/0x54 +[ 1336.906866][ T9712] do_interrupt_handler+0x7c/0xc0 +[ 1336.906873][ T9712] el1_interrupt+0x34/0x68 +[ 1336.913339][ T9712] el1h_64_irq_handler+0x18/0x24 +[ 1336.914550][ T9712] el1h_64_irq+0x64/0x68 +[ 1336.915531][ T9712] _raw_spin_unlock_irqrestore+0x58/0x8c +[ 1336.916831][ T9712] debug_object_activate+0xb0/0x300 +[ 1336.918177][ T9712] call_rcu+0x40/0x484 +[ 1336.919171][ T9712] tlb_flush_mmu_free+0x298/0x3bc +[ 1336.920433][ T9712] tlb_flush_mmu+0x274/0x2f0 +[ 1336.921629][ T9712] tlb_finish_mmu+0x64/0xe4 +[ 1336.922718][ T9712] exit_mmap+0xe4/0x2e4 +[ 1336.923765][ T9712] __mmput+0x90/0x204 +[ 1336.924796][ T9712] mmput+0x64/0xa0 +[ 1336.925734][ T9712] free_bprm+0xac/0x19c +[ 1336.926851][ T9712] kernel_execve+0x4ec/0x540 +[ 1336.928029][ T9712] call_usermodehelper_exec_async+0x10c/0x214 +[ 1336.929586][ T9712] ret_from_fork+0x10/0x20 +[ 1336.930736][ T9712] irq event stamp: 8353 +[ 1336.931785][ T9712] hardirqs last enabled at (8353): [] finish_lock_switch+0x94/0xe8 +[ 1336.934167][ T9712] hardirqs last disabled at (8352): [] __schedule+0x84/0x5a0 +[ 1336.936452][ T9712] softirqs last enabled at (7378): [] _stext+0x2e4/0x37c +[ 1336.938648][ T9712] softirqs last disabled at (7245): [] ____do_softirq+0x14/0x20 +[ 1336.940993][ T9712] +[ 1336.940993][ T9712] other info that might help us debug this: +[ 1336.943073][ T9712] Possible unsafe locking scenario: +[ 1336.943073][ T9712] +[ 1336.944957][ T9712] CPU0 +[ 1336.945828][ T9712] ---- +[ 1336.946627][ T9712] lock(&folio_wait_table[i]); +[ 1336.947864][ T9712] +[ 1336.948763][ T9712] lock(&folio_wait_table[i]); +[ 1336.950014][ T9712] +[ 1336.950014][ T9712] *** DEADLOCK *** +[ 1336.950014][ T9712] +[ 1336.952066][ T9712] 1 lock held by syz-executor.3/9712: +[ 1336.953400][ T9712] #0: ffff0000d1a700e0 (&type->s_umount_key#46/1){+.+.}-{3:3}, at: alloc_super+0xf8/0x430 +[ 1336.955899][ T9712] +[ 1336.955899][ T9712] stack backtrace: +[ 1336.957367][ T9712] CPU: 1 PID: 9712 Comm: syz-executor.3 Not tainted 6.0.0-rc7-syzkaller-18095-gbbed346d5a96 #0 +[ 1336.959932][ T9712] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/30/2022 +[ 1336.962530][ T9712] Call trace: +[ 1336.963329][ T9712] dump_backtrace+0x1c4/0x1f0 +[ 1336.964525][ T9712] show_stack+0x2c/0x54 +[ 1336.965522][ T9712] dump_stack_lvl+0x104/0x16c +[ 1336.966763][ T9712] dump_stack+0x1c/0x58 +[ 1336.967790][ T9712] print_usage_bug+0x39c/0x3cc +[ 1336.968966][ T9712] mark_lock_irq+0x4a8/0x4b4 +[ 1336.970152][ T9712] mark_lock+0x154/0x1b4 +[ 1336.971226][ T9712] __lock_acquire+0x5f8/0x30a4 +[ 1336.972429][ T9712] lock_acquire+0x100/0x1f8 +[ 1336.973530][ T9712] _raw_spin_lock+0x54/0x6c +[ 1336.974643][ T9712] _atomic_dec_and_lock+0xc8/0x130 +[ 1336.975938][ T9712] iput+0x50/0x324 +[ 1336.976859][ T9712] ntfs_fill_super+0x1254/0x14a4 +[ 1336.978134][ T9712] get_tree_bdev+0x1e8/0x2a0 +[ 1336.979340][ T9712] ntfs_fs_get_tree+0x28/0x38 +[ 1336.980531][ T9712] vfs_get_tree+0x40/0x140 +[ 1336.981668][ T9712] do_new_mount+0x1dc/0x4e4 +[ 1336.982779][ T9712] path_mount+0x358/0x914 +[ 1336.983850][ T9712] __arm64_sys_mount+0x2c4/0x3c4 +[ 1336.985070][ T9712] el0_svc_common+0x138/0x220 +[ 1336.986208][ T9712] do_el0_svc+0x48/0x164 +[ 1336.987271][ T9712] el0_svc+0x58/0x150 +[ 1336.988276][ T9712] el0t_64_sync_handler+0x84/0xf0 +[ 1336.989559][ T9712] el0t_64_sync+0x18c/0x190 diff --git a/pkg/report/testdata/linux/report/666 b/pkg/report/testdata/linux/report/666 new file mode 100644 index 000000000..b82c0da8c --- /dev/null +++ b/pkg/report/testdata/linux/report/666 @@ -0,0 +1,68 @@ +TITLE: inconsistent lock state in io_dismantle_req + +[ 32.237087][ C0] ================================ +[ 32.242183][ C0] WARNING: inconsistent lock state +[ 32.247268][ C0] 4.15.0-syzkaller #0 Not tainted +[ 32.252346][ C0] -------------------------------- +[ 32.257429][ C0] inconsistent {HARDIRQ-ON-W} -> {IN-HARDIRQ-W} usage. +[ 32.264296][ C0] io_wq_manager/4648 [HC1[1]:SC0[0]:HE0:SE1] takes: +[ 32.270851][ C0] ffff8801e85ebf20 (&(&fs->lock)->rlock){?.+.}, at: io_dismantle_req+0x411/0xae0 +[ 32.280025][ C0] {HARDIRQ-ON-W} state was registered at: +[ 32.285719][ C0] lock_acquire+0x1da/0xb70 +[ 32.290293][ C0] _raw_spin_lock+0x2b/0x40 +[ 32.308008][ C0] set_fs_pwd+0x89/0x1e0 +[ 32.312309][ C0] ksys_chdir+0x131/0x1f0 +[ 32.316696][ C0] devtmpfsd+0x1b1/0x4c0 +[ 32.320999][ C0] kthread+0x450/0x6e0 +[ 32.325139][ C0] ret_from_fork+0x3f/0x50 +[ 32.329628][ C0] 0xffffffffffffffff +[ 32.333676][ C0] irq event stamp: 60 +[ 32.337631][ C0] hardirqs last enabled at (59): [] _raw_spin_unlock_irq+0x28/0x80 +[ 32.347167][ C0] hardirqs last disabled at (60): [] trace_hardirqs_off_thunk+0x1a/0x1c +[ 32.357038][ C0] softirqs last enabled at (0): [] copy_process.part.0+0x176a/0x6820 +[ 32.357054][ C0] softirqs last disabled at (0): [<0000000000000000>] 0x0 +[ 32.387108][ C0] +[ 32.387108][ C0] other info that might help us debug this: +[ 32.395209][ C0] Possible unsafe locking scenario: +[ 32.395209][ C0] +[ 32.402633][ C0] CPU0 +[ 32.405887][ C0] ---- +[ 32.409141][ C0] lock(&(&fs->lock)->rlock); +[ 32.413877][ C0] +[ 32.417304][ C0] lock(&(&fs->lock)->rlock); +[ 32.422209][ C0] +[ 32.422209][ C0] *** DEADLOCK *** +[ 32.422209][ C0] +[ 32.430332][ C0] no locks held by io_wq_manager/4648. +[ 32.430335][ C0] +[ 32.430335][ C0] stack backtrace: +[ 32.430399][ C0] CPU: 0 PID: 4648 Comm: io_wq_manager Not tainted 4.15.0-syzkaller #0 +[ 32.463247][ C0] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 +[ 32.473275][ C0] Call Trace: +[ 32.476531][ C0] +[ 32.479364][ C0] [] dump_stack+0x175/0x1dd +[ 32.485622][ C0] [] print_usage_bug.part.0+0x34a/0x359 +[ 32.492788][ C0] [] mark_lock.cold+0x11/0x92 +[ 32.499097][ C0] [] __lock_acquire+0x13d1/0x5ef0 +[ 32.532487][ C0] [] lock_acquire+0x1da/0xb70 +[ 32.566303][ C0] [] _raw_spin_lock+0x2b/0x40 +[ 32.593033][ C0] [] io_dismantle_req+0x411/0xae0 +[ 32.599686][ C0] [] __io_free_req+0x19/0x2b0 +[ 32.605989][ C0] [] io_put_req+0x78/0xb0 +[ 32.611950][ C0] [] io_link_timeout_fn+0x375/0x510 +[ 32.618801][ C0] [] __hrtimer_run_queues+0x45e/0xe40 +[ 32.638842][ C0] [] hrtimer_interrupt+0x30d/0x900 +[ 32.638858][ C0] [] smp_apic_timer_interrupt+0x159/0x580 +[ 32.638877][ C0] [] apic_timer_interrupt+0x9f/0xb0 +[ 32.672924][ C0] +[ 32.675841][ C0] RIP: 0010:_raw_spin_unlock_irq+0x54/0x80 +[ 32.681613][ C0] RSP: 0018:ffff8801e893bd68 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff12 +[ 32.690009][ C0] RAX: 1ffffffff0bed873 RBX: 00000000ffffff83 RCX: 0000000000000000 +[ 32.697953][ C0] RDX: dffffc0000000000 RSI: 0000000000000006 RDI: ffff8801e85c8a94 +[ 32.705903][ C0] RBP: ffff8801e893bd70 R08: 0000000000000000 R09: 0000000000000000 +[ 32.705910][ C0] R10: 0000000000000000 R11: 0000000000000000 R12: ffff8801e8f40d00 +[ 32.705925][ C0] R13: ffff8801e8f40880 R14: ffff8801e8f40d00 R15: ffff8801e85c8080 +[ 32.750035][ C0] [] __io_req_task_cancel+0x66/0x180 +[ 32.756959][ C0] [] io_async_task_func+0x22b/0x950 +[ 32.763798][ C0] [] task_work_run+0xf4/0x1b0 +[ 32.770188][ C0] [] io_wq_manager+0x2be/0x980 diff --git a/pkg/report/testdata/linux/report/667 b/pkg/report/testdata/linux/report/667 new file mode 100644 index 000000000..1eaf62a59 --- /dev/null +++ b/pkg/report/testdata/linux/report/667 @@ -0,0 +1,81 @@ +TITLE: inconsistent lock state in __io_req_aux_free + +[ 28.141875] ================================ +[ 28.146298] WARNING: inconsistent lock state +[ 28.150699] 4.15.0-syzkaller #0 Not tainted +[ 28.154995] -------------------------------- +[ 28.159376] inconsistent {HARDIRQ-ON-W} -> {IN-HARDIRQ-W} usage. +[ 28.165515] syz-executor644/3715 [HC1[1]:SC0[0]:HE0:SE1] takes: +[ 28.171568] ffff8801da554020 (&(&fs->lock)->rlock){?.+.}, at: __io_req_aux_free+0x402/0xf30 +[ 28.180068] {HARDIRQ-ON-W} state was registered at: +[ 28.185074] lock_acquire+0x18f/0x770 +[ 28.188967] _raw_spin_lock+0x2b/0x40 +[ 28.192844] set_fs_pwd+0x89/0x1e0 +[ 28.196557] ksys_chdir+0x131/0x1f0 +[ 28.200342] devtmpfsd+0x1b1/0x4c0 +[ 28.203950] kthread+0x450/0x6e0 +[ 28.207403] ret_from_fork+0x3f/0x50 +[ 28.211178] 0xffffffffffffffff +[ 28.214515] irq event stamp: 93704 +[ 28.218055] hardirqs last enabled at (93703): [] _raw_spin_unlock_irq+0x28/0x40 +[ 28.227400] hardirqs last disabled at (93704): [] trace_hardirqs_off_thunk+0x1a/0x1c +[ 28.236940] softirqs last enabled at (93576): [] unix_create1+0x432/0x500 +[ 28.245525] softirqs last disabled at (93574): [] unix_create1+0x378/0x500 +[ 28.254089] +[ 28.254089] other info that might help us debug this: +[ 28.260831] Possible unsafe locking scenario: +[ 28.260831] +[ 28.269054] CPU0 +[ 28.271628] ---- +[ 28.274197] lock(&(&fs->lock)->rlock); +[ 28.278247] +[ 28.280988] lock(&(&fs->lock)->rlock); +[ 28.285197] +[ 28.285197] *** DEADLOCK *** +[ 28.285197] +[ 28.291245] 1 lock held by syz-executor644/3715: +[ 28.295972] #0: ffff8801ee8775a0 (&ctx->uring_lock){+.+.}, at: SyS_io_uring_enter+0x8bf/0x17a0 +[ 28.304819] +[ 28.304819] stack backtrace: +[ 28.309301] CPU: 0 PID: 3715 Comm: syz-executor644 Not tainted 4.15.0-syzkaller #0 +[ 28.317094] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 +[ 28.326427] Call Trace: +[ 28.328990] +[ 28.331128] [] dump_stack+0xd8/0x147 +[ 28.336481] [] print_usage_bug.part.0+0x34a/0x359 +[ 28.342970] [] mark_lock.cold+0x27/0x92 +[ 28.348670] [] __lock_acquire+0x1477/0x5ed0 +[ 28.384342] [] lock_acquire+0x18f/0x770 +[ 28.420340] [] _raw_spin_lock+0x2b/0x40 +[ 28.432194] [] __io_req_aux_free+0x402/0xf30 +[ 28.438247] [] __io_free_req+0x21/0x5b0 +[ 28.449825] [] io_free_req+0xba/0x1e0 +[ 28.474067] [] io_put_req+0x70/0xa0 +[ 28.479341] [] io_link_timeout_fn+0x387/0x530 +[ 28.485506] [] __hrtimer_run_queues+0x45e/0xe40 +[ 28.504953] [] hrtimer_interrupt+0x30d/0x900 +[ 28.511020] [] smp_apic_timer_interrupt+0x159/0x580 +[ 28.517691] [] apic_timer_interrupt+0x93/0xa0 +[ 28.523845] +[ 28.526076] RIP: 0010:_raw_spin_unlock_irq+0x2f/0x40 +[ 28.531172] RSP: 0018:ffff8801f3bff940 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff12 +[ 28.538863] RAX: 0000000000000007 RBX: 0000000000000000 RCX: 0000000000000006 +[ 28.546116] RDX: 0000000000000000 RSI: ffff8801f3d88b38 RDI: ffff8801f3d88b34 +[ 28.553427] RBP: ffff8801f3bff948 R08: ffff8801f3d88180 R09: 0000000000000000 +[ 28.560695] R10: ffff8801f3d88b58 R11: 0000000000000000 R12: ffff8801ee877640 +[ 28.567952] R13: ffff8801ee877640 R14: 0000000000000001 R15: ffff8801efb05080 +[ 28.575331] [] io_queue_linked_timeout+0x1ac/0x210 +[ 28.581919] [] io_queue_async_work+0x3b2/0xbb0 +[ 28.588153] [] __io_queue_sqe+0x522/0x1160 +[ 28.618773] [] io_queue_sqe+0x3a8/0xd80 +[ 28.624398] [] io_submit_sqes+0xf74/0x27f0 +[ 28.661561] [] SyS_io_uring_enter+0x8d3/0x17a0 +[ 28.737974] [] do_syscall_64+0x1ff/0x310 +[ 28.743675] [] entry_SYSCALL_64_after_hwframe+0x42/0xb7 +[ 28.750668] RIP: 0033:0x44bb09 +[ 28.753834] RSP: 002b:00007ffce56c6c58 EFLAGS: 00000212 ORIG_RAX: 00000000000001aa +[ 28.761521] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000044bb09 +[ 28.768773] RDX: 0000000000000000 RSI: 00000000000045f5 RDI: 0000000000000003 +[ 28.776025] RBP: 0000000000000003 R08: 0000000000000000 R09: 0000000000000000 +[ 28.783278] R10: 0000000000000000 R11: 0000000000000212 R12: 00007ffce56c6c90 +[ 28.790530] R13: 00007ffce56 diff --git a/pkg/report/testdata/linux/report/668 b/pkg/report/testdata/linux/report/668 new file mode 100644 index 000000000..b5ff70dff --- /dev/null +++ b/pkg/report/testdata/linux/report/668 @@ -0,0 +1,79 @@ +TITLE: inconsistent lock state in io_file_data_ref_zero + +[ 21.087943][ C0] ================================ +[ 21.093011][ C0] WARNING: inconsistent lock state +[ 21.098078][ C0] 5.10.0-syzkaller #0 Not tainted +[ 21.103056][ C0] -------------------------------- +[ 21.108120][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. +[ 21.114931][ C0] swapper/0/0 [HC0[0]:SC1[1]:HE1:SE0] takes: +[ 21.120870][ C0] ffff888114bf86a8 (&file_data->lock){+.?.}-{2:2}, at: io_file_data_ref_zero+0x79/0x480 +[ 21.130557][ C0] {SOFTIRQ-ON-W} state was registered at: +[ 21.136236][ C0] lock_acquire+0x2a6/0x770 +[ 21.140786][ C0] _raw_spin_lock+0x2b/0x40 +[ 21.145339][ C0] __do_sys_io_uring_register+0x3684/0x4460 +[ 21.151276][ C0] __x64_sys_io_uring_register+0x97/0xf0 +[ 21.156952][ C0] do_syscall_64+0x32/0x50 +[ 21.161418][ C0] entry_SYSCALL_64_after_hwframe+0x44/0xa9 +[ 21.167353][ C0] irq event stamp: 73760 +[ 21.171556][ C0] hardirqs last enabled at (73760): [] sysvec_call_function_single+0x64/0xb0 +[ 21.181920][ C0] hardirqs last disabled at (73759): [] sysvec_call_function_single+0x10/0xb0 +[ 21.192289][ C0] softirqs last enabled at (73678): [] irq_enter_rcu+0xcc/0xe0 +[ 21.201439][ C0] softirqs last disabled at (73679): [] asm_call_irq_on_stack+0xf/0x20 +[ 21.211191][ C0] +[ 21.211191][ C0] other info that might help us debug this: +[ 21.219207][ C0] Possible unsafe locking scenario: +[ 21.219207][ C0] +[ 21.226616][ C0] CPU0 +[ 21.229856][ C0] ---- +[ 21.233099][ C0] lock(&file_data->lock); +[ 21.237561][ C0] +[ 21.240977][ C0] lock(&file_data->lock); +[ 21.245612][ C0] +[ 21.245612][ C0] *** DEADLOCK *** +[ 21.245612][ C0] +[ 21.253721][ C0] 2 locks held by swapper/0/0: +[ 21.258439][ C0] #0: ffffffff86187a40 (rcu_callback){....}-{0:0}, at: rcu_core+0x4ad/0xf30 +[ 21.267169][ C0] #1: ffffffff86187b60 (rcu_read_lock){....}-{1:2}, at: percpu_ref_put_many.constprop.0+0x0/0x270 +[ 21.277811][ C0] +[ 21.277811][ C0] stack backtrace: +[ 21.283663][ C0] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 5.10.0-syzkaller #0 +[ 21.291247][ C0] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 +[ 21.301259][ C0] Call Trace: +[ 21.304500][ C0] +[ 21.307318][ C0] dump_stack+0x111/0x171 +[ 21.311606][ C0] print_usage_bug.part.0+0x349/0x358 +[ 21.323142][ C0] mark_lock.cold+0x3c/0x88 +[ 21.361361][ C0] __lock_acquire+0x1270/0x5bf0 +[ 21.372112][ C0] lock_acquire+0x2a6/0x770 +[ 21.400711][ C0] _raw_spin_lock+0x2b/0x40 +[ 21.410500][ C0] io_file_data_ref_zero+0x79/0x480 +[ 21.415659][ C0] percpu_ref_put_many.constprop.0+0x231/0x270 +[ 21.421773][ C0] percpu_ref_switch_to_atomic_rcu+0x291/0x470 +[ 21.427888][ C0] rcu_core+0x51a/0xf30 +[ 21.437682][ C0] rcu_core_si+0x9/0x10 +[ 21.441798][ C0] __do_softirq+0x1b9/0x932 +[ 21.446260][ C0] asm_call_irq_on_stack+0xf/0x20 +[ 21.451239][ C0] +[ 21.454144][ C0] do_softirq_own_stack+0x86/0xa0 +[ 21.459126][ C0] irq_exit_rcu+0x127/0x1c0 +[ 21.463588][ C0] sysvec_apic_timer_interrupt+0x54/0xb0 +[ 21.469176][ C0] asm_sysvec_apic_timer_interrupt+0x12/0x20 +[ 21.475115][ C0] RIP: 0010:acpi_idle_do_entry+0x1d7/0x270 +[ 21.480880][ C0] Code: 4f 8b f6 fc 84 db 75 a9 e8 66 93 f6 fc e8 d1 7a fc fc e9 0c 00 00 00 e8 57 93 f6 fc 0f 00 2d 30 9d 77 00 e8 4b 93 f6 fc fb f4 <9c> 5b 81 e3 00 02 00 00 fa 31 ff 48 89 de e8 76 8b f6 fc 48 85 db +[ 21.500445][ C0] RSP: 0018:ffffffff85c07cd0 EFLAGS: 00000293 +[ 21.506470][ C0] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 1ffffffff0d758f9 +[ 21.514401][ C0] RDX: ffffffff85c35d00 RSI: ffffffff8474f315 RDI: ffffffff8474f2ff +[ 21.522333][ C0] RBP: ffffffff85c07ce0 R08: 0000000000000001 R09: 0000000000000001 +[ 21.530265][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ffff888102572864 +[ 21.538197][ C0] R13: ffff888102572800 R14: ffff888102572864 R15: ffff8881056bd004 +[ 21.556450][ C0] acpi_idle_enter+0x35f/0x4f0 +[ 21.561172][ C0] cpuidle_enter_state+0x1c8/0xce0 +[ 21.577075][ C0] cpuidle_enter+0x4f/0xa0 +[ 21.581454][ C0] do_idle+0x479/0x630 +[ 21.595291][ C0] cpu_startup_entry+0x1b/0x20 +[ 21.600012][ C0] rest_init+0x25a/0x3a6 +[ 21.610423][ C0] arch_call_rest_init+0xe/0x1b +[ 21.615232][ C0] start_kernel+0x477/0x497 +[ 21.619697][ C0] x86_64_start_reservations+0x29/0x2b +[ 21.625113][ C0] x86_64_start_kernel+0x77/0x7b +[ 21.630009][ C0] secondary_startup_64_no_verify+0xb0/0xbb diff --git a/pkg/report/testdata/linux/report/669 b/pkg/report/testdata/linux/report/669 new file mode 100644 index 000000000..cd24cad68 --- /dev/null +++ b/pkg/report/testdata/linux/report/669 @@ -0,0 +1,174 @@ +TITLE: inconsistent lock state in kmem_cache_alloc_node + +[ 860.112634][ C1] ================================ +[ 860.117823][ C1] WARNING: inconsistent lock state +[ 860.122922][ C1] 5.15.0-rc2-syzkaller #0 Not tainted +[ 860.128290][ C1] -------------------------------- +[ 860.133390][ C1] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. +[ 860.140237][ C1] ksoftirqd/1/19 [HC0[0]:SC1[3]:HE1:SE0] takes: +[ 860.146481][ C1] ffffffff8c9dd960 (fs_reclaim){+.?.}-{0:0}, at: kmem_cache_alloc_node+0x46/0x340 +[ 860.155717][ C1] {SOFTIRQ-ON-W} state was registered at: +[ 860.161435][ C1] lock_acquire+0x19f/0x4d0 +[ 860.166029][ C1] fs_reclaim_acquire+0x77/0x110 +[ 860.171066][ C1] kmem_cache_alloc_trace+0x42/0x310 +[ 860.176455][ C1] wq_numa_init+0x122/0x49f +[ 860.176550][ C1] workqueue_init+0x18/0x7c7 +[ 860.176568][ C1] kernel_init_freeable+0x408/0x5c6 +[ 860.176585][ C1] kernel_init+0x19/0x2a0 +[ 860.176602][ C1] ret_from_fork+0x1f/0x30 +[ 860.176626][ C1] irq event stamp: 4852124 +[ 860.176634][ C1] hardirqs last enabled at (4852124): [] kasan_quarantine_put+0xd4/0x230 +[ 860.176657][ C1] hardirqs last disabled at (4852123): [] kasan_quarantine_put+0x39/0x230 +[ 860.176680][ C1] softirqs last enabled at (4851912): [] run_ksoftirqd+0xc1/0x120 +[ 860.176746][ C1] softirqs last disabled at (4851919): [] run_ksoftirqd+0xc1/0x120 +[ 860.176766][ C1] +[ 860.176766][ C1] other info that might help us debug this: +[ 860.176772][ C1] Possible unsafe locking scenario: +[ 860.176772][ C1] +[ 860.176777][ C1] CPU0 +[ 860.176780][ C1] ---- +[ 860.176783][ C1] lock(fs_reclaim); +[ 860.176797][ C1] +[ 860.176801][ C1] lock(fs_reclaim); +[ 860.176811][ C1] +[ 860.176811][ C1] *** DEADLOCK *** +[ 860.176811][ C1] +[ 860.176815][ C1] 6 locks held by ksoftirqd/1/19: +[ 860.176825][ C1] #0: ffffffff8c91c200 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x9/0x30 +[ 860.176901][ C1] #1: ffffffff8c91c200 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x9/0x30 +[ 860.176941][ C1] #2: ffffffff8c91c200 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x5/0x30 +[ 860.177006][ C1] #3: ffffffff8c91c200 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x5/0x30 +[ 860.177047][ C1] #4: ffff88806f912620 (k-slock-AF_INET6/1){+.-.}-{2:2}, at: tcp_v6_rcv+0x3317/0x4470 +[ 860.177095][ C1] #5: ffff888074db2fa0 (slock-AF_INET6){+.-.}-{2:2}, at: mptcp_incoming_options+0x3a8/0x1fb0 +[ 860.177167][ C1] +[ 860.177167][ C1] stack backtrace: +[ 860.177174][ C1] CPU: 1 PID: 19 Comm: ksoftirqd/1 Not tainted 5.15.0-rc2-syzkaller #0 +[ 860.177192][ C1] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 +[ 860.177202][ C1] Call Trace: +[ 860.177210][ C1] dump_stack_lvl+0x1dc/0x2d8 +[ 860.177269][ C1] print_usage_bug+0xa6d/0xea0 +[ 860.177323][ C1] mark_lock+0x168c/0x1e00 +[ 860.177415][ C1] __lock_acquire+0xc36/0x2b00 +[ 860.177497][ C1] lock_acquire+0x19f/0x4d0 +[ 860.177650][ C1] fs_reclaim_acquire+0x77/0x110 +[ 860.177716][ C1] kmem_cache_alloc_node+0x46/0x340 +[ 860.177753][ C1] __alloc_skb+0xd8/0x580 +[ 860.177770][ C1] sk_stream_alloc_skb+0x1f4/0xb40 +[ 860.177790][ C1] tcp_build_frag+0x268/0x1010 +[ 860.177911][ C1] mptcp_sendmsg_frag+0x6a1/0x1570 +[ 860.177972][ C1] __mptcp_subflow_push_pending+0x289/0xa10 +[ 860.178017][ C1] mptcp_incoming_options+0x497/0x1fb0 +[ 860.178121][ C1] tcp_data_queue+0xf4/0x8430 +[ 860.178272][ C1] tcp_rcv_established+0x9f7/0x22b0 +[ 860.178332][ C1] tcp_v6_do_rcv+0x4a9/0x12d0 +[ 860.178354][ C1] tcp_v6_rcv+0x36dc/0x4470 +[ 860.178430][ C1] ip6_protocol_deliver_rcu+0x9a5/0x1310 +[ 860.178472][ C1] ip6_input+0x10b/0x190 +[ 860.178488][ C1] ip_sabotage_in+0x1a1/0x220 +[ 860.178520][ C1] nf_hook_slow+0xb2/0x1f0 +[ 860.178539][ C1] nf_hook+0x235/0x3a0 +[ 860.178621][ C1] ipv6_rcv+0x87/0xd0 +[ 860.178638][ C1] __netif_receive_skb+0x1d1/0x500 +[ 860.178711][ C1] netif_receive_skb_internal+0x108/0x360 +[ 860.178781][ C1] netif_receive_skb+0x19/0x30 +[ 860.178796][ C1] br_handle_frame_finish+0x139b/0x1940 +[ 860.178945][ C1] br_nf_hook_thresh+0x3db/0x4f0 +[ 860.179004][ C1] br_nf_pre_routing_finish_ipv6+0x942/0xbc0 +[ 860.179042][ C1] br_nf_pre_routing_ipv6+0x294/0x340 +[ 860.179074][ C1] br_handle_frame+0x875/0x1050 +[ 860.179163][ C1] __netif_receive_skb_core+0x14d8/0x3d10 +[ 860.179255][ C1] __netif_receive_skb+0x120/0x500 +[ 860.179350][ C1] process_backlog+0x518/0x9a0 +[ 860.179436][ C1] __napi_poll+0xba/0x4f0 +[ 860.179453][ C1] net_rx_action+0x61c/0xf30 +[ 860.179512][ C1] __do_softirq+0x392/0x7a3 +[ 860.179563][ C1] run_ksoftirqd+0xc1/0x120 +[ 860.179620][ C1] smpboot_thread_fn+0x533/0x9d0 +[ 860.179691][ C1] kthread+0x453/0x480 +[ 860.179734][ C1] ret_from_fork+0x1f/0x30 +[ 860.112634][ C1] ================================ +[ 860.117823][ C1] WARNING: inconsistent lock state +[ 860.122922][ C1] 5.15.0-rc2-syzkaller #0 Not tainted +[ 860.128290][ C1] -------------------------------- +[ 860.133390][ C1] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. +[ 860.140237][ C1] ksoftirqd/1/19 [HC0[0]:SC1[3]:HE1:SE0] takes: +[ 860.146481][ C1] ffffffff8c9dd960 (fs_reclaim){+.?.}-{0:0}, at: kmem_cache_alloc_node+0x46/0x340 +[ 860.155717][ C1] {SOFTIRQ-ON-W} state was registered at: +[ 860.161435][ C1] lock_acquire+0x19f/0x4d0 +[ 860.166029][ C1] fs_reclaim_acquire+0x77/0x110 +[ 860.171066][ C1] kmem_cache_alloc_trace+0x42/0x310 +[ 860.176455][ C1] wq_numa_init+0x122/0x49f +[ 860.176550][ C1] workqueue_init+0x18/0x7c7 +[ 860.176568][ C1] kernel_init_freeable+0x408/0x5c6 +[ 860.176585][ C1] kernel_init+0x19/0x2a0 +[ 860.176602][ C1] ret_from_fork+0x1f/0x30 +[ 860.176626][ C1] irq event stamp: 4852124 +[ 860.176634][ C1] hardirqs last enabled at (4852124): [] kasan_quarantine_put+0xd4/0x230 +[ 860.176657][ C1] hardirqs last disabled at (4852123): [] kasan_quarantine_put+0x39/0x230 +[ 860.176680][ C1] softirqs last enabled at (4851912): [] run_ksoftirqd+0xc1/0x120 +[ 860.176746][ C1] softirqs last disabled at (4851919): [] run_ksoftirqd+0xc1/0x120 +[ 860.176766][ C1] +[ 860.176766][ C1] other info that might help us debug this: +[ 860.176772][ C1] Possible unsafe locking scenario: +[ 860.176772][ C1] +[ 860.176777][ C1] CPU0 +[ 860.176780][ C1] ---- +[ 860.176783][ C1] lock(fs_reclaim); +[ 860.176797][ C1] +[ 860.176801][ C1] lock(fs_reclaim); +[ 860.176811][ C1] +[ 860.176811][ C1] *** DEADLOCK *** +[ 860.176811][ C1] +[ 860.176815][ C1] 6 locks held by ksoftirqd/1/19: +[ 860.176825][ C1] #0: ffffffff8c91c200 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x9/0x30 +[ 860.176901][ C1] #1: ffffffff8c91c200 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x9/0x30 +[ 860.176941][ C1] #2: ffffffff8c91c200 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x5/0x30 +[ 860.177006][ C1] #3: ffffffff8c91c200 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x5/0x30 +[ 860.177047][ C1] #4: ffff88806f912620 (k-slock-AF_INET6/1){+.-.}-{2:2}, at: tcp_v6_rcv+0x3317/0x4470 +[ 860.177095][ C1] #5: ffff888074db2fa0 (slock-AF_INET6){+.-.}-{2:2}, at: mptcp_incoming_options+0x3a8/0x1fb0 +[ 860.177167][ C1] +[ 860.177167][ C1] stack backtrace: +[ 860.177174][ C1] CPU: 1 PID: 19 Comm: ksoftirqd/1 Not tainted 5.15.0-rc2-syzkaller #0 +[ 860.177192][ C1] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 +[ 860.177202][ C1] Call Trace: +[ 860.177210][ C1] dump_stack_lvl+0x1dc/0x2d8 +[ 860.177269][ C1] print_usage_bug+0xa6d/0xea0 +[ 860.177323][ C1] mark_lock+0x168c/0x1e00 +[ 860.177415][ C1] __lock_acquire+0xc36/0x2b00 +[ 860.177497][ C1] lock_acquire+0x19f/0x4d0 +[ 860.177650][ C1] fs_reclaim_acquire+0x77/0x110 +[ 860.177716][ C1] kmem_cache_alloc_node+0x46/0x340 +[ 860.177753][ C1] __alloc_skb+0xd8/0x580 +[ 860.177770][ C1] sk_stream_alloc_skb+0x1f4/0xb40 +[ 860.177790][ C1] tcp_build_frag+0x268/0x1010 +[ 860.177911][ C1] mptcp_sendmsg_frag+0x6a1/0x1570 +[ 860.177972][ C1] __mptcp_subflow_push_pending+0x289/0xa10 +[ 860.178017][ C1] mptcp_incoming_options+0x497/0x1fb0 +[ 860.178121][ C1] tcp_data_queue+0xf4/0x8430 +[ 860.178272][ C1] tcp_rcv_established+0x9f7/0x22b0 +[ 860.178332][ C1] tcp_v6_do_rcv+0x4a9/0x12d0 +[ 860.178354][ C1] tcp_v6_rcv+0x36dc/0x4470 +[ 860.178430][ C1] ip6_protocol_deliver_rcu+0x9a5/0x1310 +[ 860.178472][ C1] ip6_input+0x10b/0x190 +[ 860.178488][ C1] ip_sabotage_in+0x1a1/0x220 +[ 860.178520][ C1] nf_hook_slow+0xb2/0x1f0 +[ 860.178539][ C1] nf_hook+0x235/0x3a0 +[ 860.178621][ C1] ipv6_rcv+0x87/0xd0 +[ 860.178638][ C1] __netif_receive_skb+0x1d1/0x500 +[ 860.178711][ C1] netif_receive_skb_internal+0x108/0x360 +[ 860.178781][ C1] netif_receive_skb+0x19/0x30 +[ 860.178796][ C1] br_handle_frame_finish+0x139b/0x1940 +[ 860.178945][ C1] br_nf_hook_thresh+0x3db/0x4f0 +[ 860.179004][ C1] br_nf_pre_routing_finish_ipv6+0x942/0xbc0 +[ 860.179042][ C1] br_nf_pre_routing_ipv6+0x294/0x340 +[ 860.179074][ C1] br_handle_frame+0x875/0x1050 +[ 860.179163][ C1] __netif_receive_skb_core+0x14d8/0x3d10 +[ 860.179255][ C1] __netif_receive_skb+0x120/0x500 +[ 860.179350][ C1] process_backlog+0x518/0x9a0 +[ 860.179436][ C1] __napi_poll+0xba/0x4f0 +[ 860.179453][ C1] net_rx_action+0x61c/0xf30 +[ 860.179512][ C1] __do_softirq+0x392/0x7a3 +[ 860.179563][ C1] run_ksoftirqd+0xc1/0x120 +[ 860.179620][ C1] smpboot_thread_fn+0x533/0x9d0 +[ 860.179691][ C1] kthread+0x453/0x480 +[ 860.179734][ C1] ret_from_fork+0x1f/0x30 -- cgit mrf-deployment