watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [syz-executor.5:9493] Modules linked in: irq event stamp: 3843489 hardirqs last enabled at (3843488): [] restore_regs_and_return_to_kernel+0x0/0x2a hardirqs last disabled at (3843489): [] apic_timer_interrupt+0x8e/0xa0 arch/x86/entry/entry_64.S:793 softirqs last enabled at (8658): [] __do_softirq+0x68b/0x9ff kernel/softirq.c:314 softirqs last disabled at (12245): [] invoke_softirq kernel/softirq.c:368 [inline] softirqs last disabled at (12245): [] irq_exit+0x193/0x240 kernel/softirq.c:409 CPU: 0 PID: 9493 Comm: syz-executor.5 Not tainted 4.14.272-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 task: ffff8880a943a140 task.stack: ffff8880613b0000 RIP: 0010:__kernel_text_address+0x0/0x30 kernel/extable.c:104 RSP: 0018:ffff8880ba4078e8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10 RAX: dffffc0000000000 RBX: ffff8880ba407918 RCX: 0000000000000000 RDX: 1ffff11017480f2c RSI: ffff8880ba407808 RDI: ffffffff81486672 RBP: ffff8880ba407960 R08: 0000000000000001 R09: 0000000000000001 R10: ffff8880ba407e08 R11: 0000000000000001 R12: ffff8880ba4079a0 R13: 0000000000000000 R14: ffff88813fe74940 R15: 00000000000001c0 FS: 00007f0d49246700(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f2eca67b058 CR3: 00000000a8e78000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: unwind_get_return_address arch/x86/kernel/unwind_orc.c:252 [inline] unwind_get_return_address+0x51/0x90 arch/x86/kernel/unwind_orc.c:247 __save_stack_trace+0xa0/0x160 arch/x86/kernel/stacktrace.c:45 save_stack mm/kasan/kasan.c:447 [inline] set_track mm/kasan/kasan.c:459 [inline] kasan_kmalloc+0xeb/0x160 mm/kasan/kasan.c:551 __do_kmalloc_node mm/slab.c:3682 [inline] __kmalloc_node_track_caller+0x4c/0x70 mm/slab.c:3696 __kmalloc_reserve net/core/skbuff.c:137 [inline] __alloc_skb+0x96/0x510 net/core/skbuff.c:205 alloc_skb include/linux/skbuff.h:980 [inline] ndisc_alloc_skb+0x134/0x310 net/ipv6/ndisc.c:402 ndisc_send_rs+0x2ec/0x630 net/ipv6/ndisc.c:661 audit: type=1800 audit(1647439754.565:6): pid=9514 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.3" name="bus" dev="loop3" ino=5 res=0 addrconf_rs_timer+0x2bb/0x5a0 net/ipv6/addrconf.c:3769 call_timer_fn+0x14a/0x650 kernel/time/timer.c:1280 expire_timers+0x232/0x4d0 kernel/time/timer.c:1319 __run_timers kernel/time/timer.c:1637 [inline] run_timer_softirq+0x1d5/0x5a0 kernel/time/timer.c:1650 __do_softirq+0x24d/0x9ff kernel/softirq.c:288 invoke_softirq kernel/softirq.c:368 [inline] irq_exit+0x193/0x240 kernel/softirq.c:409 exiting_irq arch/x86/include/asm/apic.h:638 [inline] smp_apic_timer_interrupt+0x141/0x5e0 arch/x86/kernel/apic/apic.c:1106 apic_timer_interrupt+0x93/0xa0 arch/x86/entry/entry_64.S:793 RIP: 0010:number+0x4cb/0x8d0 lib/vsprintf.c:527 RSP: 0018:ffff8880613b7460 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff10 RAX: 0000000000040000 RBX: ffff888060ec56b6 RCX: ffffc90009233000 RDX: 00000000000110a6 RSI: ffffffff86ffa156 RDI: ffffffff87ccf040 RBP: ffff888060ec56b9 R08: 0000000000000001 R09: ffffed100c1d8ad7 R10: ffff888060ec56b8 R11: ffff8880a943a140 R12: 0000000000000001 R13: 0000000000000020 R14: 00000000fffffffb R15: dffffc0000000000 vsnprintf+0xda1/0x1340 lib/vsprintf.c:2273 audit: type=1800 audit(1647439754.565:7): pid=9520 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="bus" dev="loop4" ino=6 res=0 audit_log_vformat+0x1de/0x800 kernel/audit.c:1838 audit_log_format+0xac/0xf0 kernel/audit.c:1872 integrity_audit_msg+0x373/0x440 security/integrity/integrity_audit.c:65 ima_collect_measurement+0x2bd/0x430 security/integrity/ima/ima_api.c:251 process_measurement+0x78b/0xb20 security/integrity/ima/ima_main.c:264 audit: type=1804 audit(1647439754.565:8): pid=9520 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.4" name="/root/syzkaller-testdir3380839021/syzkaller.Acm6hm/4/file0/bus" dev="loop4" ino=6 res=1 do_last fs/namei.c:3435 [inline] path_openat+0x10ad/0x2970 fs/namei.c:3569 do_filp_open+0x179/0x3c0 fs/namei.c:3603 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f0d4a8d1049 RSP: 002b:00007f0d49246168 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 audit: type=1800 audit(1647439754.565:9): pid=9520 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="bus" dev="loop4" ino=6 res=0 RAX: ffffffffffffffda RBX: 00007f0d4a9e3f60 RCX: 00007f0d4a8d1049 RDX: 0000000000000000 RSI: 00000000001c5b42 RDI: 0000000020000080 RBP: 00007f0d4a92b08d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffddad4981f R14: 00007f0d49246300 R15: 0000000000022000 Code: c3 48 89 ef e8 22 1b 27 00 0f b6 c0 eb cf 48 c7 c7 44 1b 09 8a e8 71 dd 47 00 e9 64 ff ff ff 66 66 2e 0f 1f 84 00 00 00 00 00 90 <53> 48 89 fb e8 07 ff ff ff 85 c0 75 17 48 81 fb 00 10 04 8b 72 Sending NMI from CPU 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 9520 Comm: syz-executor.4 Not tainted 4.14.272-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 task: ffff888060f060c0 task.stack: ffff888060f08000 RIP: 0010:mark_irqflags kernel/locking/lockdep.c:3057 [inline] RIP: 0010:__lock_acquire+0x4ff/0x3f20 kernel/locking/lockdep.c:3448 RSP: 0018:ffff8880ba507b10 EFLAGS: 00000097 RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000 RDX: 0000000000000001 RSI: 1ffff1100c1e0d37 RDI: ffff888060f069e1 RBP: 0000000000000000 R08: 0000000000000001 R09: 00000000000c4001 R10: ffff888060f069c0 R11: ffff888060f060c0 R12: ffff888060f069d8 R13: 0000000000000001 R14: 0000000000000001 R15: ffffffff8becfd40 FS: 00007f2ec8f0d700(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 0000001b2d736000 CR3: 00000000b449f000 CR4: 00000000003406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline] _raw_spin_lock_irqsave+0x8c/0xc0 kernel/locking/spinlock.c:160 debug_object_activate+0x10f/0x490 lib/debugobjects.c:434 debug_hrtimer_activate kernel/time/hrtimer.c:401 [inline] debug_activate kernel/time/hrtimer.c:447 [inline] enqueue_hrtimer+0x22/0x3b0 kernel/time/hrtimer.c:844 __run_hrtimer kernel/time/hrtimer.c:1238 [inline] __hrtimer_run_queues+0x8e2/0xc80 kernel/time/hrtimer.c:1287 hrtimer_interrupt+0x1e6/0x5e0 kernel/time/hrtimer.c:1321 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1079 [inline] smp_apic_timer_interrupt+0x117/0x5e0 arch/x86/kernel/apic/apic.c:1104 apic_timer_interrupt+0x93/0xa0 arch/x86/entry/entry_64.S:793 RIP: 0010:__read_once_size include/linux/compiler.h:185 [inline] RIP: 0010:list_empty include/linux/list.h:203 [inline] RIP: 0010:blk_mq_dispatch_rq_list+0x513/0xea0 block/blk-mq.c:1086 RSP: 0018:ffff888060f0f360 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10 RAX: ffffed100c1e1e92 RBX: ffff888060f0f490 RCX: ffffc90007223000 RDX: 0000000000040000 RSI: ffffffff830a82ba RDI: ffff888060f0f498 RBP: dffffc0000000000 R08: ffff8880aec0f138 R09: 0000000000000002 R10: 0000000000000000 R11: ffff888060f060c0 R12: ffff8880aeda8a48 R13: ffff888060f0f490 R14: ffff888060f0f490 R15: ffff8880aeda8a40 blk_mq_sched_dispatch_requests+0x469/0x690 block/blk-mq-sched.c:147 __blk_mq_run_hw_queue+0x12a/0x320 block/blk-mq.c:1195 __blk_mq_delay_run_hw_queue+0x19f/0x1f0 block/blk-mq.c:1243 blk_mq_flush_plug_list+0x606/0xa60 block/blk-mq.c:1536 blk_flush_plug_list+0x2de/0x9f0 block/blk-core.c:3372 blk_finish_plug block/blk-core.c:3436 [inline] blk_finish_plug+0x50/0xa0 block/blk-core.c:3432 mpage_writepages+0x183/0x1f0 fs/mpage.c:723 do_writepages+0xc3/0x240 mm/page-writeback.c:2361 __filemap_fdatawrite_range+0x236/0x310 mm/filemap.c:346 filemap_write_and_wait_range mm/filemap.c:581 [inline] filemap_write_and_wait_range+0x39/0xb0 mm/filemap.c:575 generic_file_read_iter+0xe68/0x21c0 mm/filemap.c:2243 call_read_iter include/linux/fs.h:1774 [inline] generic_file_splice_read+0x3a7/0x5c0 fs/splice.c:307 do_splice_to+0xfb/0x140 fs/splice.c:880 splice_direct_to_actor+0x207/0x730 fs/splice.c:952 do_splice_direct+0x164/0x210 fs/splice.c:1061 do_sendfile+0x47f/0xb30 fs/read_write.c:1441 SYSC_sendfile64 fs/read_write.c:1502 [inline] SyS_sendfile64+0xff/0x110 fs/read_write.c:1488 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f2eca598049 RSP: 002b:00007f2ec8f0d168 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 RAX: ffffffffffffffda RBX: 00007f2eca6aaf60 RCX: 00007f2eca598049 RDX: 0000000000000000 RSI: 000000000000000f RDI: 000000000000000e RBP: 00007f2eca5f208d R08: 0000000000000000 R09: 0000000000000000 R10: 000000000000de00 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffc5414a4cf R14: 00007f2ec8f0d300 R15: 0000000000022000 Code: 41 89 42 24 0f 84 04 01 00 00 49 8d 7a 21 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 0f b6 04 02 48 89 fa 83 e2 07 38 d0 <7f> 08 84 c0 0f 85 44 09 00 00 49 8d 6a 22 41 0f b6 5a 21 48 b8 ---------------- Code disassembly (best guess): 0: c3 retq 1: 48 89 ef mov %rbp,%rdi 4: e8 22 1b 27 00 callq 0x271b2b 9: 0f b6 c0 movzbl %al,%eax c: eb cf jmp 0xffffffdd e: 48 c7 c7 44 1b 09 8a mov $0xffffffff8a091b44,%rdi 15: e8 71 dd 47 00 callq 0x47dd8b 1a: e9 64 ff ff ff jmpq 0xffffff83 1f: 66 66 2e 0f 1f 84 00 data16 nopw %cs:0x0(%rax,%rax,1) 26: 00 00 00 00 2a: 90 nop * 2b: 53 push %rbx <-- trapping instruction 2c: 48 89 fb mov %rdi,%rbx 2f: e8 07 ff ff ff callq 0xffffff3b 34: 85 c0 test %eax,%eax 36: 75 17 jne 0x4f 38: 48 81 fb 00 10 04 8b cmp $0xffffffff8b041000,%rbx 3f: 72 .byte 0x72