BUG: sleeping function called from invalid context at drivers/tty/vt/vt.c:2245 in_atomic(): 1, irqs_disabled(): 1, pid: 10596, name: syz-executor.2 3 locks held by syz-executor.2/10596: #0: (&tty->ldisc_sem){++++}, at: [] tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284 #1: (&(&gsm->control_lock)->rlock){....}, at: [] gsm_control_send+0xf6/0x480 drivers/tty/n_gsm.c:1434 #2: (&(&gsm->tx_lock)->rlock){....}, at: [] gsm_data_queue drivers/tty/n_gsm.c:845 [inline] #2: (&(&gsm->tx_lock)->rlock){....}, at: [] gsm_control_transmit+0x1f1/0x2d0 drivers/tty/n_gsm.c:1375 irq event stamp: 28 hardirqs last enabled at (27): [] __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline] hardirqs last enabled at (27): [] _raw_spin_unlock_irqrestore+0x79/0xe0 kernel/locking/spinlock.c:192 hardirqs last disabled at (28): [] __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:108 [inline] hardirqs last disabled at (28): [] _raw_spin_lock_irqsave+0x66/0xc0 kernel/locking/spinlock.c:160 softirqs last enabled at (0): [] copy_process.part.0+0x12d0/0x71c0 kernel/fork.c:1734 softirqs last disabled at (0): [< (null)>] (null) Preemption disabled at: [< (null)>] (null) CPU: 0 PID: 10596 Comm: syz-executor.2 Not tainted 4.14.306-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 lib/dump_stack.c:58 ___might_sleep.cold+0x235/0x250 kernel/sched/core.c:6040 do_con_write+0xd0/0x19b0 drivers/tty/vt/vt.c:2245 con_write+0x21/0xa0 drivers/tty/vt/vt.c:2822 gsmld_output+0xc3/0x190 drivers/tty/n_gsm.c:2312 gsm_data_kick+0x266/0x9b0 drivers/tty/n_gsm.c:761 gsm_data_queue drivers/tty/n_gsm.c:846 [inline] gsm_control_transmit+0x1ff/0x2d0 drivers/tty/n_gsm.c:1375 gsm_control_send+0x38a/0x480 drivers/tty/n_gsm.c:1451 gsm_disconnect drivers/tty/n_gsm.c:2110 [inline] gsmld_config.constprop.0+0x568/0xf90 drivers/tty/n_gsm.c:2636 gsmld_ioctl+0x375/0x410 drivers/tty/n_gsm.c:2700 tty_ioctl+0x5af/0x1430 drivers/tty/tty_io.c:2670 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7f16c45e80f9 RSP: 002b:00007f16c2b39168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f16c4708050 RCX: 00007f16c45e80f9 RDX: 0000000020000040 RSI: 00000000404c4701 RDI: 0000000000000003 RBP: 00007f16c4643ae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffda4c3509f R14: 00007f16c2b39300 R15: 0000000000022000 ======================================================== WARNING: possible irq lock inversion dependency detected 4.14.306-syzkaller #0 Tainted: G W IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready -------------------------------------------------------- systemd-journal/4623 just changed the state of lock: (&(&gsm->control_lock)->rlock){..-.}, at: [] gsm_control_retransmit+0x25/0x2c0 drivers/tty/n_gsm.c:1394 but this lock took another, SOFTIRQ-unsafe lock in the past: (console_lock){+.+.} and interrupts could create inverse lock ordering between them. other info that might help us debug this: Chain exists of: &(&gsm->control_lock)->rlock --> &(&gsm->tx_lock)->rlock --> console_lock Possible interrupt unsafe locking scenario: CPU0 CPU1 ---- ---- lock(console_lock); local_irq_disable(); lock(&(&gsm->control_lock)->rlock); lock(&(&gsm->tx_lock)->rlock); lock(&(&gsm->control_lock)->rlock); *** DEADLOCK *** 1 lock held by systemd-journal/4623: #0: (((&gsm->t2_timer))){+.-.}, at: [] lockdep_copy_map include/linux/lockdep.h:174 [inline] #0: (((&gsm->t2_timer))){+.-.}, at: [] call_timer_fn+0xb8/0x650 kernel/time/timer.c:1270 the shortest dependencies between 2nd lock and 1st lock: IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready -> (console_lock){+.+.} ops: 5052 { HARDIRQ-ON-W at: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 console_lock+0x42/0x70 kernel/printk/printk.c:2228 con_init+0x12/0x5d6 drivers/tty/vt/vt.c:3022 console_init+0x46/0x53 kernel/printk/printk.c:2809 start_kernel+0x521/0x763 init/main.c:638 secondary_startup_64+0xa5/0xb0 arch/x86/kernel/head_64.S:240 SOFTIRQ-ON-W at: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 console_lock+0x42/0x70 kernel/printk/printk.c:2228 con_init+0x12/0x5d6 drivers/tty/vt/vt.c:3022 console_init+0x46/0x53 kernel/printk/printk.c:2809 start_kernel+0x521/0x763 init/main.c:638 secondary_startup_64+0xa5/0xb0 arch/x86/kernel/head_64.S:240 INITIAL USE at: } ... key at: [] console_lock_dep_map+0x0/0x40 ... acquired at: console_lock+0x42/0x70 kernel/printk/printk.c:2228 do_con_write+0xd5/0x19b0 drivers/tty/vt/vt.c:2247 con_write+0x21/0xa0 drivers/tty/vt/vt.c:2822 gsmld_output+0xc3/0x190 drivers/tty/n_gsm.c:2312 gsm_data_kick+0x266/0x9b0 drivers/tty/n_gsm.c:761 gsm_data_queue drivers/tty/n_gsm.c:846 [inline] gsm_control_transmit+0x1ff/0x2d0 drivers/tty/n_gsm.c:1375 gsm_control_send+0x38a/0x480 drivers/tty/n_gsm.c:1451 gsm_disconnect drivers/tty/n_gsm.c:2110 [inline] gsmld_config.constprop.0+0x568/0xf90 drivers/tty/n_gsm.c:2636 gsmld_ioctl+0x375/0x410 drivers/tty/n_gsm.c:2700 tty_ioctl+0x5af/0x1430 drivers/tty/tty_io.c:2670 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 -> (&(&gsm->tx_lock)->rlock){....} ops: 1 { INITIAL USE at: 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 gsm_data_queue drivers/tty/n_gsm.c:845 [inline] gsm_control_transmit+0x1f1/0x2d0 drivers/tty/n_gsm.c:1375 gsm_control_send+0x38a/0x480 drivers/tty/n_gsm.c:1451 gsm_disconnect drivers/tty/n_gsm.c:2110 [inline] gsmld_config.constprop.0+0x568/0xf90 drivers/tty/n_gsm.c:2636 gsmld_ioctl+0x375/0x410 drivers/tty/n_gsm.c:2700 tty_ioctl+0x5af/0x1430 drivers/tty/tty_io.c:2670 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 } ... key at: [] __key.4+0x0/0x40 ... acquired at: __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline] _raw_spin_lock_irqsave+0x8c/0xc0 kernel/locking/spinlock.c:160 gsm_data_queue drivers/tty/n_gsm.c:845 [inline] gsm_control_transmit+0x1f1/0x2d0 drivers/tty/n_gsm.c:1375 gsm_control_send+0x38a/0x480 drivers/tty/n_gsm.c:1451 gsm_disconnect drivers/tty/n_gsm.c:2110 [inline] gsmld_config.constprop.0+0x568/0xf90 drivers/tty/n_gsm.c:2636 gsmld_ioctl+0x375/0x410 drivers/tty/n_gsm.c:2700 tty_ioctl+0x5af/0x1430 drivers/tty/tty_io.c:2670 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 -> (&(&gsm->control_lock)->rlock){..-.} ops: 2 { IN-SOFTIRQ-W at: 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 gsm_control_retransmit+0x25/0x2c0 drivers/tty/n_gsm.c:1394 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:796 __save_stack_trace+0xe5/0x160 arch/x86/kernel/stacktrace.c:51 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 slab_post_alloc_hook mm/slab.h:442 [inline] slab_alloc mm/slab.c:3390 [inline] kmem_cache_alloc+0x111/0x3c0 mm/slab.c:3550 getname_flags+0xc8/0x550 fs/namei.c:138 do_sys_open+0x1ce/0x410 fs/open.c:1075 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 INITIAL USE at: 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 gsm_control_send+0xf6/0x480 drivers/tty/n_gsm.c:1434 gsm_disconnect drivers/tty/n_gsm.c:2110 [inline] gsmld_config.constprop.0+0x568/0xf90 drivers/tty/n_gsm.c:2636 gsmld_ioctl+0x375/0x410 drivers/tty/n_gsm.c:2700 tty_ioctl+0x5af/0x1430 drivers/tty/tty_io.c:2670 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 } ... key at: [] __key.5+0x0/0x40 ... acquired at: mark_lock_irq kernel/locking/lockdep.c:2804 [inline] mark_lock+0x3c7/0x1050 kernel/locking/lockdep.c:3194 mark_irqflags kernel/locking/lockdep.c:3072 [inline] __lock_acquire+0xc81/0x3f20 kernel/locking/lockdep.c:3448 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 gsm_control_retransmit+0x25/0x2c0 drivers/tty/n_gsm.c:1394 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:796 __save_stack_trace+0xe5/0x160 arch/x86/kernel/stacktrace.c:51 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 slab_post_alloc_hook mm/slab.h:442 [inline] slab_alloc mm/slab.c:3390 [inline] kmem_cache_alloc+0x111/0x3c0 mm/slab.c:3550 getname_flags+0xc8/0x550 fs/namei.c:138 do_sys_open+0x1ce/0x410 fs/open.c:1075 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 stack backtrace: CPU: 0 PID: 4623 Comm: systemd-journal Tainted: G W 4.14.306-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 lib/dump_stack.c:58 print_irq_inversion_bug.cold+0x313/0x346 kernel/locking/lockdep.c:2670 check_usage_forwards+0x18f/0x2d0 kernel/locking/lockdep.c:2695 mark_lock_irq kernel/locking/lockdep.c:2804 [inline] mark_lock+0x3c7/0x1050 kernel/locking/lockdep.c:3194 mark_irqflags kernel/locking/lockdep.c:3072 [inline] __lock_acquire+0xc81/0x3f20 kernel/locking/lockdep.c:3448 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 gsm_control_retransmit+0x25/0x2c0 drivers/tty/n_gsm.c:1394 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:796 RIP: 0010:__save_stack_trace+0xe5/0x160 arch/x86/kernel/stacktrace.c:51 RSP: 0018:ffff8880a177fae8 EFLAGS: 00000287 ORIG_RAX: ffffffffffffff10 RAX: 0000000000000006 RBX: 0000000000000000 RCX: 0000000000000007 RDX: ffff8880a177fb98 RSI: 0000000000000001 RDI: 0000000000000282 RBP: ffff8880a177fb68 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: ffff8880a1776100 R12: ffff8880a177fb80 R13: 0000000000000000 R14: ffff88823f8c1200 R15: 0000000000001000 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 slab_post_alloc_hook mm/slab.h:442 [inline] slab_alloc mm/slab.c:3390 [inline] kmem_cache_alloc+0x111/0x3c0 mm/slab.c:3550 getname_flags+0xc8/0x550 fs/namei.c:138 do_sys_open+0x1ce/0x410 fs/open.c:1075 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7f95c2f3b840 RSP: 002b:00007ffda70dd0d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 00007ffda70dd3e0 RCX: 00007f95c2f3b840 RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 000055fa1d1bbea0 RBP: 000000000000000d R08: 000000000000c0ff R09: 00000000ffffffff R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff R13: 000055fa1d1b6040 R14: 00007ffda70dd3a0 R15: 000055fa1d1bbef0 F2FS-fs (loop4): Mismatch start address, segment0(512) cp_blkaddr(605) chnl_net:chnl_net_open(): err: Unable to register and open device, Err:-19 BTRFS info (device loop3): using free space tree caif:caif_disconnect_client(): nothing to disconnect BTRFS info (device loop3): has skinny extents chnl_net:chnl_flowctrl_cb(): NET flowctrl func called flow: CLOSE/DEINIT F2FS-fs (loop4): Can't find valid F2FS filesystem in 1th superblock chnl_net:chnl_net_open(): state disconnected F2FS-fs (loop4): invalid crc value A link change request failed with some changes committed already. Interface caif0 may have been left with an inconsistent configuration, please check. F2FS-fs (loop4): Found nat_bits in checkpoint libceph: resolve 'd !Šþ þE@ ùð¶' (ret=-3): failed F2FS-fs (loop4): Mounted with checkpoint version = 753bd00b libceph: parse_ips bad ip '[d !Šþ þE@ ùð¶' libceph: resolve 'd !Šþ þE@ ùð¶' (ret=-3): failed libceph: parse_ips bad ip '[d !Šþ þE@ ùð¶' libceph: resolve 'd !Šþ þE@ ùð¶' (ret=-3): failed libceph: parse_ips bad ip '[d !Šþ þE@ ùð¶' IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready libceph: resolve 'd !Šþ þE@ ùð¶' (ret=-3): failed chnl_net:chnl_net_open(): err: Unable to register and open device, Err:-19 libceph: parse_ips bad ip '[d !Šþ þE@ ùð¶' caif:caif_disconnect_client(): nothing to disconnect chnl_net:chnl_flowctrl_cb(): NET flowctrl func called flow: CLOSE/DEINIT chnl_net:chnl_net_open(): state disconnected A link change request failed with some changes committed already. Interface caif0 may have been left with an inconsistent configuration, please check. IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready chnl_net:chnl_net_open(): err: Unable to register and open device, Err:-19 BTRFS info (device loop3): using free space tree BTRFS info (device loop3): has skinny extents caif:caif_disconnect_client(): nothing to disconnect chnl_net:chnl_flowctrl_cb(): NET flowctrl func called flow: CLOSE/DEINIT chnl_net:chnl_net_open(): state disconnected A link change request failed with some changes committed already. Interface caif0 may have been left with an inconsistent configuration, please check. IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready chnl_net:chnl_net_open(): err: Unable to register and open device, Err:-19 caif:caif_disconnect_client(): nothing to disconnect chnl_net:chnl_flowctrl_cb(): NET flowctrl func called flow: CLOSE/DEINIT chnl_net:chnl_net_open(): state disconnected A link change request failed with some changes committed already. Interface caif0 may have been left with an inconsistent configuration, please check. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.1'. IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready chnl_net:chnl_net_open(): err: Unable to register and open device, Err:-19 caif:caif_disconnect_client(): nothing to disconnect chnl_net:chnl_flowctrl_cb(): NET flowctrl func called flow: CLOSE/DEINIT chnl_net:chnl_net_open(): state disconnected A link change request failed with some changes committed already. Interface caif0 may have been left with an inconsistent configuration, please check. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.1'. IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready ====================================================== WARNING: the mand mount option is being deprecated and will be removed in v5.15! ====================================================== chnl_net:chnl_net_open(): err: Unable to register and open device, Err:-19 caif:caif_disconnect_client(): nothing to disconnect chnl_net:chnl_flowctrl_cb(): NET flowctrl func called flow: CLOSE/DEINIT chnl_net:chnl_net_open(): state disconnected A link change request failed with some changes committed already. Interface caif0 may have been left with an inconsistent configuration, please check. unregister_netdevice: waiting for ip6gre0 to become free. Usage count = -1