[ 35.024647] audit: type=1800 audit(1581758308.774:33): pid=7195 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op="collect_data" cause="failed(directio)" comm="startpar" name="rc.local" dev="sda1" ino=2465 res=0 [ 35.052567] audit: type=1800 audit(1581758308.774:34): pid=7195 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op="collect_data" cause="failed(directio)" comm="startpar" name="rmnologin" dev="sda1" ino=2456 res=0 Debian GNU/Linux 7 syzkaller ttyS0 syzkaller login: [ 37.952659] random: sshd: uninitialized urandom read (32 bytes read) [ 38.252370] audit: type=1400 audit(1581758312.004:35): avc: denied { map } for pid=7368 comm="bash" path="/bin/bash" dev="sda1" ino=1457 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:file_t:s0 tclass=file permissive=1 [ 38.311324] random: sshd: uninitialized urandom read (32 bytes read) [ 39.014122] random: sshd: uninitialized urandom read (32 bytes read) [ 39.203130] random: sshd: uninitialized urandom read (32 bytes read) Warning: Permanently added '10.128.0.218' (ECDSA) to the list of known hosts. [ 44.733949] random: sshd: uninitialized urandom read (32 bytes read) executing program [ 44.853773] audit: type=1400 audit(1581758318.604:36): avc: denied { map } for pid=7380 comm="syz-executor999" path="/root/syz-executor999292382" dev="sda1" ino=16483 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:user_home_t:s0 tclass=file permissive=1 executing program executing program [ 49.864378] ODEBUG: free active (active state 0) object type: timer_list hint: rfcomm_dlc_timeout+0x0/0x60 [ 49.874998] ------------[ cut here ]------------ [ 49.879778] WARNING: CPU: 1 PID: 7383 at lib/debugobjects.c:287 debug_print_object.cold+0xa7/0xdb [ 49.888774] Kernel panic - not syncing: panic_on_warn set ... [ 49.888774] [ 49.896144] CPU: 1 PID: 7383 Comm: syz-executor999 Not tainted 4.14.171-syzkaller #0 [ 49.904120] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 49.913626] Call Trace: [ 49.916245] dump_stack+0x142/0x197 [ 49.919868] panic+0x1f9/0x42d [ 49.923094] ? add_taint.cold+0x16/0x16 [ 49.927076] ? debug_print_object.cold+0xa7/0xdb [ 49.931819] ? debug_print_object.cold+0xa7/0xdb [ 49.936660] __warn.cold+0x2f/0x2f [ 49.940198] ? ist_end_non_atomic+0x10/0x10 [ 49.944521] ? debug_print_object.cold+0xa7/0xdb [ 49.949299] report_bug+0x216/0x254 [ 49.952923] do_error_trap+0x1bb/0x310 [ 49.956883] ? math_error+0x360/0x360 [ 49.960679] ? vprintk_emit+0x171/0x600 [ 49.964680] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 49.969625] do_invalid_op+0x1b/0x20 [ 49.973478] invalid_op+0x1b/0x40 [ 49.976928] RIP: 0010:debug_print_object.cold+0xa7/0xdb [ 49.982293] RSP: 0018:ffff8880971e7aa8 EFLAGS: 00010086 [ 49.987649] RAX: 000000000000005e RBX: 0000000000000003 RCX: 0000000000000000 [ 49.994956] RDX: 0000000000000000 RSI: ffffffff86cc0a60 RDI: ffffed1012e3cf4b [ 50.002213] RBP: ffff8880971e7ad0 R08: 000000000000005e R09: 0000000000000000 [ 50.009477] R10: 0000000000000000 R11: ffff888091d4e280 R12: ffffffff86cbbce0 [ 50.016738] R13: ffffffff85ccab00 R14: 0000000000000000 R15: ffff8880925fc328 [ 50.024007] ? rfcomm_dlc_link+0x160/0x160 [ 50.028238] ? debug_print_object.cold+0xa7/0xdb [ 50.033068] debug_check_no_obj_freed+0x3f5/0x7b7 [ 50.037910] ? free_obj_work+0x6d0/0x6d0 [ 50.041981] ? rcu_lockdep_current_cpu_online+0xf2/0x140 [ 50.047443] kfree+0xbd/0x270 [ 50.050540] rfcomm_dlc_free+0x20/0x30 [ 50.054439] rfcomm_dev_ioctl+0x1637/0x1920 [ 50.058766] ? mark_held_locks+0xb1/0x100 [ 50.062912] ? rfcomm_tty_install+0x180/0x180 [ 50.067395] ? __local_bh_enable_ip+0x99/0x1a0 [ 50.071969] rfcomm_sock_ioctl+0x82/0xa0 [ 50.076034] sock_do_ioctl+0x64/0xb0 [ 50.079747] sock_ioctl+0x2a6/0x470 [ 50.083376] ? dlci_ioctl_set+0x40/0x40 [ 50.087344] do_vfs_ioctl+0x7ae/0x1060 [ 50.091224] ? selinux_file_mprotect+0x5d0/0x5d0 [ 50.095971] ? ioctl_preallocate+0x1c0/0x1c0 [ 50.100363] ? fd_install+0x4d/0x60 [ 50.103973] ? security_file_ioctl+0x7d/0xb0 [ 50.108375] ? security_file_ioctl+0x89/0xb0 [ 50.113176] SyS_ioctl+0x8f/0xc0 [ 50.116563] ? do_vfs_ioctl+0x1060/0x1060 [ 50.120736] do_syscall_64+0x1e8/0x640 [ 50.124615] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 50.129449] entry_SYSCALL_64_after_hwframe+0x42/0xb7 [ 50.134620] RIP: 0033:0x4412c9 [ 50.137879] RSP: 002b:00007ffefa9936d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 [ 50.145600] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00000000004412c9 [ 50.153604] RDX: 0000000020000100 RSI: 00000000400452c8 RDI: 0000000000000005 [ 50.160869] RBP: 000000000000c2c1 R08: 00000000004002c8 R09: 00000000004002c8 [ 50.168752] R10: 00000000004002c8 R11: 0000000000000246 R12: 00000000004020f0 [ 50.176015] R13: 0000000000402180 R14: 0000000000000000 R15: 0000000000000000 [ 50.183275] [ 50.183277] ====================================================== [ 50.183279] WARNING: possible circular locking dependency detected [ 50.183280] 4.14.171-syzkaller #0 Not tainted [ 50.183282] ------------------------------------------------------ [ 50.183284] syz-executor999/7383 is trying to acquire lock: [ 50.183285] ((console_sem).lock){-...}, at: [] down_trylock+0x13/0x70 [ 50.183289] [ 50.183290] but task is already holding lock: [ 50.183291] (&obj_hash[i].lock){-.-.}, at: [] debug_check_no_obj_freed+0x12d/0x7b7 [ 50.183295] [ 50.183297] which lock already depends on the new lock. [ 50.183297] [ 50.183298] [ 50.183300] the existing dependency chain (in reverse order) is: [ 50.183300] [ 50.183301] -> #5 (&obj_hash[i].lock){-.-.}: [ 50.183305] lock_acquire+0x16f/0x430 [ 50.183307] _raw_spin_lock_irqsave+0x95/0xcd [ 50.183308] debug_object_activate+0x10b/0x450 [ 50.183309] enqueue_hrtimer+0x27/0x3b0 [ 50.183311] hrtimer_start_range_ns+0x50a/0x10d0 [ 50.183312] schedule_hrtimeout_range_clock+0x17c/0x340 [ 50.183314] schedule_hrtimeout+0x25/0x30 [ 50.183315] wait_task_inactive+0x4ac/0x580 [ 50.183316] __kthread_bind_mask+0x24/0xc0 [ 50.183317] kthread_bind_mask+0x23/0x30 [ 50.183319] create_worker+0x31b/0x530 [ 50.183320] workqueue_init+0x57b/0x68a [ 50.183321] kernel_init_freeable+0x2af/0x532 [ 50.183322] kernel_init+0x12/0x162 [ 50.183323] ret_from_fork+0x24/0x30 [ 50.183324] [ 50.183325] -> #4 (hrtimer_bases.lock){-.-.}: [ 50.183329] lock_acquire+0x16f/0x430 [ 50.183330] _raw_spin_lock_irqsave+0x95/0xcd [ 50.183332] lock_hrtimer_base.isra.0+0x75/0x130 [ 50.183333] hrtimer_start_range_ns+0x7a/0x10d0 [ 50.183334] enqueue_task_rt+0x972/0xe40 [ 50.183336] __sched_setscheduler.constprop.0+0xc59/0x2340 [ 50.183337] _sched_setscheduler+0x10e/0x180 [ 50.183339] sched_setscheduler+0xe/0x10 [ 50.183340] watchdog_enable+0x10b/0x160 [ 50.183341] smpboot_thread_fn+0x444/0x960 [ 50.183342] kthread+0x319/0x430 [ 50.183343] ret_from_fork+0x24/0x30 [ 50.183344] [ 50.183345] -> #3 (&rt_b->rt_runtime_lock){-.-.}: [ 50.183349] lock_acquire+0x16f/0x430 [ 50.183350] _raw_spin_lock+0x2f/0x40 [ 50.183351] enqueue_task_rt+0x524/0xe40 [ 50.183353] __sched_setscheduler.constprop.0+0xc59/0x2340 [ 50.183354] _sched_setscheduler+0x10e/0x180 [ 50.183356] sched_setscheduler+0xe/0x10 [ 50.183357] watchdog_enable+0x10b/0x160 [ 50.183358] smpboot_thread_fn+0x444/0x960 [ 50.183359] kthread+0x319/0x430 [ 50.183361] ret_from_fork+0x24/0x30 [ 50.183361] [ 50.183362] -> #2 (&rq->lock){-.-.}: [ 50.183366] lock_acquire+0x16f/0x430 [ 50.183367] _raw_spin_lock+0x2f/0x40 [ 50.183368] task_fork_fair+0x63/0x5b0 [ 50.183369] sched_fork+0x3a6/0xc10 [ 50.183371] copy_process.part.0+0x15b7/0x6a70 [ 50.183372] _do_fork+0x19e/0xce0 [ 50.183373] kernel_thread+0x34/0x40 [ 50.183374] rest_init+0x24/0x1e2 [ 50.183376] start_kernel+0x65f/0x67d [ 50.183377] x86_64_start_reservations+0x29/0x2b [ 50.183378] x86_64_start_kernel+0x77/0x7b [ 50.183380] secondary_startup_64+0xa5/0xb0 [ 50.183380] [ 50.183381] -> #1 (&p->pi_lock){-.-.}: [ 50.183385] lock_acquire+0x16f/0x430 [ 50.183386] _raw_spin_lock_irqsave+0x95/0xcd [ 50.183388] try_to_wake_up+0x79/0xf90 [ 50.183389] wake_up_process+0x10/0x20 [ 50.183390] __up.isra.0+0x136/0x1a0 [ 50.183391] up+0x9c/0xe0 [ 50.183392] __up_console_sem+0xad/0x1b0 [ 50.183394] console_unlock+0x59d/0xed0 [ 50.183395] vprintk_emit+0x1f9/0x600 [ 50.183396] vprintk_default+0x28/0x30 [ 50.183397] vprintk_func+0x5d/0x159 [ 50.183398] printk+0x9e/0xbc [ 50.183400] kauditd_hold_skb.cold+0x3e/0x4d [ 50.183401] kauditd_send_queue+0xfe/0x140 [ 50.183402] kauditd_thread+0x644/0x860 [ 50.183403] kthread+0x319/0x430 [ 50.183405] ret_from_fork+0x24/0x30 [ 50.183405] [ 50.183406] -> #0 ((console_sem).lock){-...}: [ 50.183410] __lock_acquire+0x2cb3/0x4620 [ 50.183411] lock_acquire+0x16f/0x430 [ 50.183413] _raw_spin_lock_irqsave+0x95/0xcd [ 50.183414] down_trylock+0x13/0x70 [ 50.183415] __down_trylock_console_sem+0x9c/0x200 [ 50.183416] console_trylock+0x17/0x80 [ 50.183418] vprintk_emit+0x1eb/0x600 [ 50.183419] vprintk_default+0x28/0x30 [ 50.183420] vprintk_func+0x5d/0x159 [ 50.183421] printk+0x9e/0xbc [ 50.183422] debug_print_object.cold+0xa7/0xdb [ 50.183424] debug_check_no_obj_freed+0x3f5/0x7b7 [ 50.183425] kfree+0xbd/0x270 [ 50.183426] rfcomm_dlc_free+0x20/0x30 [ 50.183427] rfcomm_dev_ioctl+0x1637/0x1920 [ 50.183429] rfcomm_sock_ioctl+0x82/0xa0 [ 50.183430] sock_do_ioctl+0x64/0xb0 [ 50.183431] sock_ioctl+0x2a6/0x470 [ 50.183432] do_vfs_ioctl+0x7ae/0x1060 [ 50.183433] SyS_ioctl+0x8f/0xc0 [ 50.183434] do_syscall_64+0x1e8/0x640 [ 50.183436] entry_SYSCALL_64_after_hwframe+0x42/0xb7 [ 50.183437] [ 50.183438] other info that might help us debug this: [ 50.183439] [ 50.183440] Chain exists of: [ 50.183440] (console_sem).lock --> hrtimer_bases.lock --> &obj_hash[i].lock [ 50.183446] [ 50.183447] Possible unsafe locking scenario: [ 50.183448] [ 50.183449] CPU0 CPU1 [ 50.183450] ---- ---- [ 50.183451] lock(&obj_hash[i].lock); [ 50.183454] lock(hrtimer_bases.lock); [ 50.183457] lock(&obj_hash[i].lock); [ 50.183459] lock((console_sem).lock); [ 50.183461] [ 50.183462] *** DEADLOCK *** [ 50.183463] [ 50.183464] 3 locks held by syz-executor999/7383: [ 50.183465] #0: (sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM){+.+.}, at: [] rfcomm_sock_ioctl+0x74/0xa0 [ 50.183470] #1: (rfcomm_ioctl_mutex){+.+.}, at: [] rfcomm_dev_ioctl+0x452/0x1920 [ 50.183474] #2: (&obj_hash[i].lock){-.-.}, at: [] debug_check_no_obj_freed+0x12d/0x7b7 [ 50.183479] [ 50.183480] stack backtrace: [ 50.183482] CPU: 1 PID: 7383 Comm: syz-executor999 Not tainted 4.14.171-syzkaller #0 [ 50.183484] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 50.183485] Call Trace: [ 50.183487] dump_stack+0x142/0x197 [ 50.183488] print_circular_bug.isra.0.cold+0x1cc/0x28f [ 50.183489] __lock_acquire+0x2cb3/0x4620 [ 50.183491] ? add_lock_to_list.isra.0+0x17c/0x330 [ 50.183492] ? trace_hardirqs_on+0x10/0x10 [ 50.183493] ? netdev_bits+0xb0/0xb0 [ 50.183494] ? save_trace+0x290/0x290 [ 50.183495] ? kvm_clock_read+0x23/0x40 [ 50.183497] ? kvm_sched_clock_read+0x9/0x20 [ 50.183498] lock_acquire+0x16f/0x430 [ 50.183499] ? down_trylock+0x13/0x70 [ 50.183500] ? vprintk_emit+0x109/0x600 [ 50.183501] _raw_spin_lock_irqsave+0x95/0xcd [ 50.183503] ? down_trylock+0x13/0x70 [ 50.183504] ? vprintk_emit+0x1eb/0x600 [ 50.183505] down_trylock+0x13/0x70 [ 50.183506] ? vprintk_emit+0x1eb/0x600 [ 50.183508] __down_trylock_console_sem+0x9c/0x200 [ 50.183509] console_trylock+0x17/0x80 [ 50.183510] vprintk_emit+0x1eb/0x600 [ 50.183511] vprintk_default+0x28/0x30 [ 50.183512] vprintk_func+0x5d/0x159 [ 50.183513] ? rfcomm_dlc_link+0x160/0x160 [ 50.183515] printk+0x9e/0xbc [ 50.183516] ? show_regs_print_info+0x63/0x63 [ 50.183517] ? lock_acquire+0x16f/0x430 [ 50.183518] ? debug_check_no_obj_freed+0x12d/0x7b7 [ 50.183520] ? rfcomm_dlc_link+0x160/0x160 [ 50.183521] debug_print_object.cold+0xa7/0xdb [ 50.183522] debug_check_no_obj_freed+0x3f5/0x7b7 [ 50.183524] ? free_obj_work+0x6d0/0x6d0 [ 50.183525] ? rcu_lockdep_current_cpu_online+0xf2/0x140 [ 50.183526] kfree+0xbd/0x270 [ 50.183527] rfcomm_dlc_free+0x20/0x30 [ 50.183529] rfcomm_dev_ioctl+0x1637/0x1920 [ 50.183530] ? mark_held_locks+0xb1/0x100 [ 50.183531] ? rfcomm_tty_install+0x180/0x180 [ 50.183532] ? __local_bh_enable_ip+0x99/0x1a0 [ 50.183534] rfcomm_sock_ioctl+0x82/0xa0 [ 50.183535] sock_do_ioctl+0x64/0xb0 [ 50.183536] sock_ioctl+0x2a6/0x470 [ 50.183537] ? dlci_ioctl_set+0x40/0x40 [ 50.183538] do_vfs_ioctl+0x7ae/0x1060 [ 50.183540] ? selinux_file_mprotect+0x5d0/0x5d0 [ 50.183541] ? ioctl_preallocate+0x1c0/0x1c0 [ 50.183542] ? fd_install+0x4d/0x60 [ 50.183543] ? security_file_ioctl+0x7d/0xb0 [ 50.183545] ? security_file_ioctl+0x89/0xb0 [ 50.183546] SyS_ioctl+0x8f/0xc0 [ 50.183547] ? do_vfs_ioctl+0x1060/0x1060 [ 50.183548] do_syscall_64+0x1e8/0x640 [ 50.183550] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 50.183551] entry_SYSCALL_64_after_hwframe+0x42/0xb7 [ 50.183552] RIP: 0033:0x4412c9 [ 50.183553] RSP: 002b:00007ffefa9936d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 [ 50.183557] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00000000004412c9 [ 50.183559] RDX: 0000000020000100 RSI: 00000000400452c8 RDI: 0000000000000005 [ 50.183560] RBP: 000000000000c2c1 R08: 00000000004002c8 R09: 00000000004002c8 [ 50.183562] R10: 00000000004002c8 R11: 0000000000000246 R12: 00000000004020f0 [ 50.183564] R13: 0000000000402180 R14: 0000000000000000 R15: 0000000000000000 [ 50.185142] Kernel Offset: disabled [ 51.096813] Rebooting in 86400 seconds..