bridge0: port 1(bridge_slave_0) entered disabled state device veth1_macvtap left promiscuous mode device veth0_macvtap left promiscuous mode device veth1_vlan left promiscuous mode device veth0_vlan left promiscuous mode BUG: sleeping function called from invalid context at kernel/locking/mutex.c:747 in_atomic(): 1, irqs_disabled(): 0, pid: 0, name: swapper/1 1 lock held by swapper/1/0: #0: ((&sp->resync_t)){+.-.}, at: [] lockdep_copy_map include/linux/lockdep.h:174 [inline] #0: ((&sp->resync_t)){+.-.}, at: [] call_timer_fn+0xc8/0x670 kernel/time/timer.c:1269 Preemption disabled at: [] start_secondary+0xfb/0x4b0 arch/x86/kernel/smpboot.c:236 CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.14.169-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x142/0x197 lib/dump_stack.c:58 ___might_sleep.cold+0x1bd/0x1f6 kernel/sched/core.c:6041 __might_sleep+0x93/0xb0 kernel/sched/core.c:5994 __mutex_lock_common kernel/locking/mutex.c:747 [inline] __mutex_lock+0xb9/0x1470 kernel/locking/mutex.c:893 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908 tpk_write+0x5d/0x2c0 drivers/char/ttyprintk.c:123 resync_tnc+0x1bc/0x3d0 drivers/net/hamradio/6pack.c:522 call_timer_fn+0x161/0x670 kernel/time/timer.c:1279 expire_timers kernel/time/timer.c:1318 [inline] __run_timers kernel/time/timer.c:1636 [inline] __run_timers kernel/time/timer.c:1604 [inline] run_timer_softirq+0x5b7/0x1520 kernel/time/timer.c:1649 __do_softirq+0x244/0x9a0 kernel/softirq.c:288 invoke_softirq kernel/softirq.c:368 [inline] irq_exit+0x160/0x1b0 kernel/softirq.c:409 exiting_irq arch/x86/include/asm/apic.h:648 [inline] smp_apic_timer_interrupt+0x146/0x5e0 arch/x86/kernel/apic/apic.c:1102 apic_timer_interrupt+0x96/0xa0 arch/x86/entry/entry_64.S:792 RIP: 0010:native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:61 RSP: 0018:ffff8880a9d37e70 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff10 RAX: 1ffffffff0fe2ce4 RBX: ffff8880a9d2a340 RCX: 0000000000000000 RDX: dffffc0000000000 RSI: 0000000000000001 RDI: ffff8880a9d2abbc RBP: ffff8880a9d37e98 R08: 1ffffffff1164101 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff87f16710 R13: 0000000000000000 R14: 0000000000000000 R15: ffff8880a9d2a340 arch_cpu_idle+0xa/0x10 arch/x86/kernel/process.c:557 default_idle_call+0x36/0x90 kernel/sched/idle.c:98 cpuidle_idle_call kernel/sched/idle.c:156 [inline] do_idle+0x262/0x3d0 kernel/sched/idle.c:246 cpu_startup_entry+0x1b/0x20 kernel/sched/idle.c:351 start_secondary+0x346/0x4b0 arch/x86/kernel/smpboot.c:272 secondary_startup_64+0xa5/0xb0 arch/x86/kernel/head_64.S:240 ================================ WARNING: inconsistent lock state 4.14.169-syzkaller #0 Tainted: G W -------------------------------- inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. swapper/1/0 [HC0[0]:SC1[1]:HE1:SE0] takes: (&tpk_port.port_write_mutex){+.?.}, at: [] tpk_write+0x5d/0x2c0 drivers/char/ttyprintk.c:123 {SOFTIRQ-ON-W} state was registered at: mark_irqflags kernel/locking/lockdep.c:3086 [inline] __lock_acquire+0xc33/0x4620 kernel/locking/lockdep.c:3444 lock_acquire+0x16f/0x430 kernel/locking/lockdep.c:3994 __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xe8/0x1470 kernel/locking/mutex.c:893 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908 tpk_close+0x50/0x8c drivers/char/ttyprintk.c:104 tty_release+0x373/0xd60 drivers/tty/tty_io.c:1670 __fput+0x275/0x7a0 fs/file_table.c:210 ____fput+0x16/0x20 fs/file_table.c:244 task_work_run+0x114/0x190 kernel/task_work.c:113 tracehook_notify_resume include/linux/tracehook.h:191 [inline] exit_to_usermode_loop+0x1da/0x220 arch/x86/entry/common.c:164 prepare_exit_to_usermode arch/x86/entry/common.c:199 [inline] syscall_return_slowpath arch/x86/entry/common.c:270 [inline] do_syscall_64+0x4bc/0x640 arch/x86/entry/common.c:297 entry_SYSCALL_64_after_hwframe+0x42/0xb7 irq event stamp: 17485060 hardirqs last enabled at (17485060): [] restore_regs_and_return_to_kernel+0x0/0x27 hardirqs last disabled at (17485059): [] apic_timer_interrupt+0x91/0xa0 arch/x86/entry/entry_64.S:792 softirqs last enabled at (17484966): [] _local_bh_enable+0x1c/0x30 kernel/softirq.c:159 softirqs last disabled at (17484967): [] invoke_softirq kernel/softirq.c:368 [inline] softirqs last disabled at (17484967): [] irq_exit+0x160/0x1b0 kernel/softirq.c:409 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(&tpk_port.port_write_mutex); lock(&tpk_port.port_write_mutex); *** DEADLOCK *** 1 lock held by swapper/1/0: #0: ((&sp->resync_t)){+.-.}, at: [] lockdep_copy_map include/linux/lockdep.h:174 [inline] #0: ((&sp->resync_t)){+.-.}, at: [] call_timer_fn+0xc8/0x670 kernel/time/timer.c:1269 stack backtrace: CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 4.14.169-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x142/0x197 lib/dump_stack.c:58 print_usage_bug.cold+0x330/0x42a kernel/locking/lockdep.c:2585 valid_state kernel/locking/lockdep.c:2598 [inline] mark_lock_irq kernel/locking/lockdep.c:2792 [inline] mark_lock+0xdbd/0x1240 kernel/locking/lockdep.c:3190 mark_irqflags kernel/locking/lockdep.c:3068 [inline] __lock_acquire+0xb57/0x4620 kernel/locking/lockdep.c:3444 lock_acquire+0x16f/0x430 kernel/locking/lockdep.c:3994 __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xe8/0x1470 kernel/locking/mutex.c:893 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908 tpk_write+0x5d/0x2c0 drivers/char/ttyprintk.c:123 resync_tnc+0x1bc/0x3d0 drivers/net/hamradio/6pack.c:522 call_timer_fn+0x161/0x670 kernel/time/timer.c:1279 expire_timers kernel/time/timer.c:1318 [inline] __run_timers kernel/time/timer.c:1636 [inline] __run_timers kernel/time/timer.c:1604 [inline] run_timer_softirq+0x5b7/0x1520 kernel/time/timer.c:1649 __do_softirq+0x244/0x9a0 kernel/softirq.c:288 invoke_softirq kernel/softirq.c:368 [inline] irq_exit+0x160/0x1b0 kernel/softirq.c:409 exiting_irq arch/x86/include/asm/apic.h:648 [inline] smp_apic_timer_interrupt+0x146/0x5e0 arch/x86/kernel/apic/apic.c:1102 apic_timer_interrupt+0x96/0xa0 arch/x86/entry/entry_64.S:792 RIP: 0010:native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:61 RSP: 0018:ffff8880a9d37e70 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff10 RAX: 1ffffffff0fe2ce4 RBX: ffff8880a9d2a340 RCX: 0000000000000000 RDX: dffffc0000000000 RSI: 0000000000000001 RDI: ffff8880a9d2abbc RBP: ffff8880a9d37e98 R08: 1ffffffff1164101 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff87f16710 R13: 0000000000000000 R14: 0000000000000000 R15: ffff8880a9d2a340 arch_cpu_idle+0xa/0x10 arch/x86/kernel/process.c:557 default_idle_call+0x36/0x90 kernel/sched/idle.c:98 cpuidle_idle_call kernel/sched/idle.c:156 [inline] do_idle+0x262/0x3d0 kernel/sched/idle.c:246 cpu_startup_entry+0x1b/0x20 kernel/sched/idle.c:351 start_secondary+0x346/0x4b0 arch/x86/kernel/smpboot.c:272 secondary_startup_64+0xa5/0xb0 arch/x86/kernel/head_64.S:240 audit: type=1400 audit(1580746944.077:3968): avc: denied { map } for pid=12284 comm="syz-executor.5" path="/root/syz-executor.5" dev="sda1" ino=16482 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:user_home_t:s0 tclass=file permissive=0 audit: type=1400 audit(1580746945.577:3969): avc: denied { map } for pid=12285 comm="syz-executor.5" path="/root/syz-executor.5" dev="sda1" ino=16482 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:user_home_t:s0 tclass=file permissive=0 bond1 (unregistering): Released all slaves device hsr_slave_1 left promiscuous mode device hsr_slave_0 left promiscuous mode team0 (unregistering): Port device team_slave_1 removed team0 (unregistering): Port device team_slave_0 removed bond0 (unregistering): Releasing backup interface bond_slave_0 bond0 (unregistering): Released all slaves audit: type=1400 audit(1580746946.957:3970): avc: denied { map } for pid=12286 comm="syz-executor.5" path="/root/syz-executor.5" dev="sda1" ino=16482 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:user_home_t:s0 tclass=file permissive=0 audit: type=1400 audit(1580746948.327:3971): avc: denied { map } for pid=12287 comm="syz-executor.5" path="/root/syz-executor.5" dev="sda1" ino=16482 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:user_home_t:s0 tclass=file permissive=0 BUG: sleeping function called from invalid context at kernel/locking/mutex.c:747 in_atomic(): 1, irqs_disabled(): 0, pid: 0, name: swapper/1 INFO: lockdep is turned off. Preemption disabled at: [] start_secondary+0xfb/0x4b0 arch/x86/kernel/smpboot.c:236 CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 4.14.169-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x142/0x197 lib/dump_stack.c:58 ___might_sleep.cold+0x1bd/0x1f6 kernel/sched/core.c:6041 __might_sleep+0x93/0xb0 kernel/sched/core.c:5994 __mutex_lock_common kernel/locking/mutex.c:747 [inline] __mutex_lock+0xb9/0x1470 kernel/locking/mutex.c:893 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908 tpk_write+0x5d/0x2c0 drivers/char/ttyprintk.c:123 resync_tnc+0x1bc/0x3d0 drivers/net/hamradio/6pack.c:522 call_timer_fn+0x161/0x670 kernel/time/timer.c:1279 expire_timers kernel/time/timer.c:1318 [inline] __run_timers kernel/time/timer.c:1636 [inline] __run_timers kernel/time/timer.c:1604 [inline] run_timer_softirq+0x5b7/0x1520 kernel/time/timer.c:1649 __do_softirq+0x244/0x9a0 kernel/softirq.c:288 invoke_softirq kernel/softirq.c:368 [inline] irq_exit+0x160/0x1b0 kernel/softirq.c:409 exiting_irq arch/x86/include/asm/apic.h:648 [inline] smp_apic_timer_interrupt+0x146/0x5e0 arch/x86/kernel/apic/apic.c:1102 apic_timer_interrupt+0x96/0xa0 arch/x86/entry/entry_64.S:792 RIP: 0010:native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:61 RSP: 0018:ffff8880a9d37e70 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff10 RAX: 1ffffffff0fe2ce4 RBX: ffff8880a9d2a340 RCX: 1ffffffff1067a98 RDX: dffffc0000000000 RSI: ffffffff87045280 RDI: ffffffff8669251a RBP: ffff8880a9d37e98 R08: 1ffffffff1164101 R09: 0000000000000000 R10: 0000000000000000 R11: ffff8880a9d2a340 R12: ffffffff87f16710 R13: 0000000000000000 R14: 0000000000000000 R15: ffff8880a9d2a340 arch_cpu_idle+0xa/0x10 arch/x86/kernel/process.c:557 default_idle_call+0x36/0x90 kernel/sched/idle.c:98 cpuidle_idle_call kernel/sched/idle.c:156 [inline] do_idle+0x262/0x3d0 kernel/sched/idle.c:246 cpu_startup_entry+0x1b/0x20 kernel/sched/idle.c:351 start_secondary+0x346/0x4b0 arch/x86/kernel/smpboot.c:272 secondary_startup_64+0xa5/0xb0 arch/x86/kernel/head_64.S:240 audit: type=1400 audit(1580746949.677:3972): avc: denied { map } for pid=12288 comm="syz-executor.5" path="/root/syz-executor.5" dev="sda1" ino=16482 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:user_home_t:s0 tclass=file permissive=0 audit: type=1400 audit(1580746951.087:3973): avc: denied { map } for pid=12289 comm="syz-executor.5" path="/root/syz-executor.5" dev="sda1" ino=16482 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:user_home_t:s0 tclass=file permissive=0 audit: type=1400 audit(1580746952.447:3974): avc: denied { map } for pid=12290 comm="syz-executor.5" path="/root/syz-executor.5" dev="sda1" ino=16482 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:user_home_t:s0 tclass=file permissive=0