[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting periodic command scheduler: cron[?25l[?1c7[ ok 8[?25h[?0c. Starting mcstransd: [....] Starting file context maintaining daemon: restorecond[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting OpenBSD Secure Shell server: sshd[?25l[?1c7[ ok 8[?25h[?0c. [ 19.149829] audit: type=1400 audit(1520902378.828:6): avc: denied { map } for pid=4237 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 Debian GNU/Linux 7 syzkaller ttyS0 Warning: Permanently added '10.128.0.41' (ECDSA) to the list of known hosts. executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program syzkaller login: [ 25.752421] audit: type=1400 audit(1520902385.431:7): avc: denied { map } for pid=4251 comm="syzkaller971101" path="/root/syzkaller971101652" dev="sda1" ino=16481 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 executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program [ 25.848309] ------------[ cut here ]------------ [ 25.853832] ODEBUG: free active (active state 0) object type: work_struct hint: process_one_req+0x0/0x6c0 [ 25.863577] WARNING: CPU: 0 PID: 40 at lib/debugobjects.c:291 debug_print_object+0x166/0x220 [ 25.872117] Kernel panic - not syncing: panic_on_warn set ... [ 25.872117] [ 25.879455] CPU: 0 PID: 40 Comm: kworker/u4:2 Not tainted 4.16.0-rc5+ #352 [ 25.886435] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 25.895758] Workqueue: ib_addr process_one_req [ 25.900310] Call Trace: [ 25.902867] dump_stack+0x194/0x24d [ 25.906463] ? arch_local_irq_restore+0x53/0x53 [ 25.911101] ? vsnprintf+0x1ed/0x1900 [ 25.914874] panic+0x1e4/0x41c [ 25.918038] ? refcount_error_report+0x214/0x214 [ 25.922763] ? show_regs_print_info+0x18/0x18 [ 25.927231] ? __warn+0x1c1/0x200 [ 25.930658] ? debug_print_object+0x166/0x220 [ 25.935119] __warn+0x1dc/0x200 [ 25.938367] ? debug_print_object+0x166/0x220 [ 25.942829] report_bug+0x1f4/0x2b0 [ 25.946427] fixup_bug.part.11+0x37/0x80 [ 25.950457] do_error_trap+0x2d7/0x3e0 [ 25.954312] ? vprintk_default+0x28/0x30 [ 25.958342] ? math_error+0x400/0x400 [ 25.962110] ? printk+0xaa/0xca [ 25.965360] ? show_regs_print_info+0x18/0x18 [ 25.969827] ? __usermodehelper_disable+0x2f0/0x2f0 [ 25.974815] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 25.979630] do_invalid_op+0x1b/0x20 [ 25.983312] invalid_op+0x1b/0x40 [ 25.986735] RIP: 0010:debug_print_object+0x166/0x220 [ 25.991804] RSP: 0018:ffff8801d95af210 EFLAGS: 00010086 [ 25.997134] RAX: dffffc0000000008 RBX: 0000000000000003 RCX: ffffffff815acd3e [ 26.004370] RDX: 0000000000000000 RSI: 1ffff1003b2b5df2 RDI: 1ffff1003b2b5dc7 [ 26.011609] RBP: ffff8801d95af250 R08: 0000000000000000 R09: 1ffff1003b2b5d99 [ 26.018849] R10: ffffed003b2b5e71 R11: ffffffff86f39b78 R12: 0000000000000001 [ 26.026087] R13: ffffffff86f15440 R14: ffffffff86408680 R15: ffffffff8147c020 [ 26.033330] ? __usermodehelper_disable+0x2f0/0x2f0 [ 26.038316] ? vprintk_func+0x5e/0xc0 [ 26.042090] debug_check_no_obj_freed+0x662/0xf1f [ 26.046900] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 26.052067] ? free_obj_work+0x690/0x690 [ 26.056101] ? trace_hardirqs_on+0xd/0x10 [ 26.060230] ? cma_deref_id+0x2c/0x30 [ 26.064004] ? __lock_is_held+0xb6/0x140 [ 26.068045] ? debug_check_no_locks_freed+0x264/0x3c0 [ 26.073205] ? cma_work_handler+0x1d0/0x1d0 [ 26.077502] kfree+0xc7/0x260 [ 26.080581] process_one_req+0x2e7/0x6c0 [ 26.084611] ? addr_resolve+0xbc0/0xbc0 [ 26.088555] ? __lock_is_held+0xb6/0x140 [ 26.092592] process_one_work+0xc47/0x1bb0 [ 26.096798] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 26.101957] ? trace_hardirqs_on+0xd/0x10 [ 26.106080] ? pwq_dec_nr_in_flight+0x450/0x450 [ 26.110725] ? __schedule+0x903/0x1ec0 [ 26.114584] ? __lock_acquire+0x664/0x3e00 [ 26.118790] ? __lock_is_held+0xb6/0x140 [ 26.122821] ? trace_hardirqs_off+0x10/0x10 [ 26.127115] ? trace_hardirqs_off+0x10/0x10 [ 26.131412] ? lock_acquire+0x1d5/0x580 [ 26.135353] ? lock_acquire+0x1d5/0x580 [ 26.139295] ? worker_thread+0x4a3/0x1990 [ 26.143413] ? lock_downgrade+0x980/0x980 [ 26.147529] ? lock_release+0xa40/0xa40 [ 26.151471] ? retint_kernel+0x10/0x10 [ 26.155330] ? do_raw_spin_trylock+0x190/0x190 [ 26.159888] worker_thread+0x223/0x1990 [ 26.163832] ? finish_task_switch+0x1c1/0x7e0 [ 26.168296] ? lock_downgrade+0x980/0x980 [ 26.172423] ? process_one_work+0x1bb0/0x1bb0 [ 26.176887] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 26.181874] ? trace_hardirqs_on+0xd/0x10 [ 26.185991] ? _raw_spin_unlock_irq+0x27/0x70 [ 26.190460] ? finish_task_switch+0x1c1/0x7e0 [ 26.194931] ? finish_task_switch+0x182/0x7e0 [ 26.199396] ? copy_overflow+0x20/0x20 [ 26.203263] ? __schedule+0x903/0x1ec0 [ 26.207128] ? trace_hardirqs_off+0x10/0x10 [ 26.211422] ? find_held_lock+0x35/0x1d0 [ 26.215456] ? find_held_lock+0x35/0x1d0 [ 26.219490] ? complete+0x62/0x80 [ 26.222918] ? __schedule+0x1ec0/0x1ec0 [ 26.226860] ? do_wait_intr_irq+0x3e0/0x3e0 [ 26.231150] ? __lockdep_init_map+0xe4/0x650 [ 26.235527] ? do_raw_spin_trylock+0x190/0x190 [ 26.240081] ? lockdep_init_map+0x9/0x10 [ 26.244111] ? _raw_spin_unlock_irqrestore+0x31/0xc0 [ 26.249185] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 26.254174] ? trace_hardirqs_on+0xd/0x10 [ 26.258290] ? __kthread_parkme+0x176/0x240 [ 26.262583] kthread+0x33c/0x400 [ 26.265920] ? process_one_work+0x1bb0/0x1bb0 [ 26.270385] ? kthread_stop+0x7a0/0x7a0 [ 26.274332] ret_from_fork+0x3a/0x50 [ 26.278030] [ 26.278032] ====================================================== [ 26.278034] WARNING: possible circular locking dependency detected [ 26.278035] 4.16.0-rc5+ #352 Not tainted [ 26.278037] ------------------------------------------------------ [ 26.278038] kworker/u4:2/40 is trying to acquire lock: [ 26.278039] ((console_sem).lock){..-.}, at: [<00000000e779db6d>] down_trylock+0x13/0x70 [ 26.278044] [ 26.278045] but task is already holding lock: [ 26.278045] (&obj_hash[i].lock){-.-.}, at: [<000000006b6a0594>] debug_check_no_obj_freed+0x1e9/0xf1f [ 26.278050] [ 26.278051] which lock already depends on the new lock. [ 26.278052] [ 26.278053] [ 26.278054] the existing dependency chain (in reverse order) is: [ 26.278055] [ 26.278056] -> #3 (&obj_hash[i].lock){-.-.}: [ 26.278060] _raw_spin_lock_irqsave+0x96/0xc0 [ 26.278062] __debug_object_init+0x109/0x1040 [ 26.278063] debug_object_init+0x17/0x20 [ 26.278064] hrtimer_init+0x8c/0x410 [ 26.278066] init_dl_task_timer+0x1b/0x50 [ 26.278067] __sched_fork+0x2bb/0xb60 [ 26.278068] init_idle+0x75/0x820 [ 26.278069] sched_init+0xb19/0xc43 [ 26.278070] start_kernel+0x452/0x819 [ 26.278072] x86_64_start_reservations+0x2a/0x2c [ 26.278073] x86_64_start_kernel+0x77/0x7a [ 26.278075] secondary_startup_64+0xa5/0xb0 [ 26.278075] [ 26.278076] -> #2 (&rq->lock){-.-.}: [ 26.278080] _raw_spin_lock+0x2a/0x40 [ 26.278081] task_fork_fair+0x7a/0x690 [ 26.278083] sched_fork+0x450/0xc10 [ 26.278084] copy_process.part.38+0x1758/0x4b60 [ 26.278085] _do_fork+0x1f7/0xf70 [ 26.278086] kernel_thread+0x34/0x40 [ 26.278088] rest_init+0x22/0xf0 [ 26.278089] start_kernel+0x7f1/0x819 [ 26.278090] x86_64_start_reservations+0x2a/0x2c [ 26.278092] x86_64_start_kernel+0x77/0x7a [ 26.278093] secondary_startup_64+0xa5/0xb0 [ 26.278094] [ 26.278094] -> #1 (&p->pi_lock){-.-.}: [ 26.278099] _raw_spin_lock_irqsave+0x96/0xc0 [ 26.278100] try_to_wake_up+0xbc/0x15f0 [ 26.278101] wake_up_process+0x10/0x20 [ 26.278102] __up.isra.0+0x1cc/0x2c0 [ 26.278103] up+0x13b/0x1d0 [ 26.278105] __up_console_sem+0xb2/0x1a0 [ 26.278106] console_unlock+0x5af/0xfb0 [ 26.278107] vprintk_emit+0x5c3/0xb90 [ 26.278109] vprintk_default+0x28/0x30 [ 26.278110] vprintk_func+0x57/0xc0 [ 26.278111] printk+0xaa/0xca [ 26.278112] kauditd_hold_skb+0x163/0x180 [ 26.278114] kauditd_send_queue+0xfa/0x140 [ 26.278115] kauditd_thread+0x660/0x940 [ 26.278116] kthread+0x33c/0x400 [ 26.278117] ret_from_fork+0x3a/0x50 [ 26.278118] [ 26.278118] -> #0 ((console_sem).lock){..-.}: [ 26.278123] lock_acquire+0x1d5/0x580 [ 26.278124] _raw_spin_lock_irqsave+0x96/0xc0 [ 26.278125] down_trylock+0x13/0x70 [ 26.278127] __down_trylock_console_sem+0xa2/0x1e0 [ 26.278128] console_trylock+0x15/0x70 [ 26.278129] vprintk_emit+0x5b5/0xb90 [ 26.278131] vprintk_default+0x28/0x30 [ 26.278132] vprintk_func+0x57/0xc0 [ 26.278133] printk+0xaa/0xca [ 26.278134] __warn_printk+0x90/0xf0 [ 26.278136] debug_print_object+0x166/0x220 [ 26.278137] debug_check_no_obj_freed+0x662/0xf1f [ 26.278138] kfree+0xc7/0x260 [ 26.278139] process_one_req+0x2e7/0x6c0 [ 26.278141] process_one_work+0xc47/0x1bb0 [ 26.278142] worker_thread+0x223/0x1990 [ 26.278143] kthread+0x33c/0x400 [ 26.278144] ret_from_fork+0x3a/0x50 [ 26.278145] [ 26.278147] other info that might help us debug this: [ 26.278147] [ 26.278148] Chain exists of: [ 26.278149] (console_sem).lock --> &rq->lock --> &obj_hash[i].lock [ 26.278154] [ 26.278155] Possible unsafe locking scenario: [ 26.278156] [ 26.278157] CPU0 CPU1 [ 26.278159] ---- ---- [ 26.278159] lock(&obj_hash[i].lock); [ 26.278162] lock(&rq->lock); [ 26.278165] lock(&obj_hash[i].lock); [ 26.278167] lock((console_sem).lock); [ 26.278170] [ 26.278171] *** DEADLOCK *** [ 26.278171] [ 26.278173] 3 locks held by kworker/u4:2/40: [ 26.278173] #0: ((wq_completion)"ib_addr"){+.+.}, at: [<00000000a5302f45>] process_one_work+0xb12/0x1bb0 [ 26.278178] #1: ((work_completion)(&(&req->work)->work)){+.+.}, at: [<00000000009517d0>] process_one_work+0xb89/0x1bb0 [ 26.278183] #2: (&obj_hash[i].lock){-.-.}, at: [<000000006b6a0594>] debug_check_no_obj_freed+0x1e9/0xf1f [ 26.278188] [ 26.278189] stack backtrace: [ 26.278191] CPU: 0 PID: 40 Comm: kworker/u4:2 Not tainted 4.16.0-rc5+ #352 [ 26.278194] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 26.278195] Workqueue: ib_addr process_one_req [ 26.278197] Call Trace: [ 26.278198] dump_stack+0x194/0x24d [ 26.278199] ? arch_local_irq_restore+0x53/0x53 [ 26.278201] print_circular_bug.isra.38+0x2cd/0x2dc [ 26.278202] ? save_trace+0xe0/0x2b0 [ 26.278207] __lock_acquire+0x30a8/0x3e00 [ 26.278208] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 26.278210] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 26.278211] ? __lock_acquire+0x664/0x3e00 [ 26.278213] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 26.278214] ? trace_hardirqs_off+0x10/0x10 [ 26.278216] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 26.278217] ? __lock_acquire+0x664/0x3e00 [ 26.278218] ? trace_hardirqs_off+0x10/0x10 [ 26.278219] ? find_held_lock+0x35/0x1d0 [ 26.278221] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 26.278222] ? trace_hardirqs_off+0x10/0x10 [ 26.278224] ? print_irqtrace_events+0x270/0x270 [ 26.278225] lock_acquire+0x1d5/0x580 [ 26.278226] ? lock_acquire+0x1d5/0x580 [ 26.278227] ? down_trylock+0x13/0x70 [ 26.278228] ? lock_release+0xa40/0xa40 [ 26.278230] ? vprintk_emit+0x43b/0xb90 [ 26.278231] ? lock_downgrade+0x980/0x980 [ 26.278232] ? kvm_sched_clock_read+0x25/0x40 [ 26.278233] ? sched_clock+0x31/0x40 [ 26.278235] ? sched_clock_cpu+0x1b/0x180 [ 26.278236] ? vprintk_emit+0x5b5/0xb90 [ 26.278237] _raw_spin_lock_irqsave+0x96/0xc0 [ 26.278238] ? down_trylock+0x13/0x70 [ 26.278240] down_trylock+0x13/0x70 [ 26.278241] ? vprintk_emit+0x5b5/0xb90 [ 26.278242] __down_trylock_console_sem+0xa2/0x1e0 [ 26.278243] console_trylock+0x15/0x70 [ 26.278245] vprintk_emit+0x5b5/0xb90 [ 26.278246] ? console_unlock+0xfb0/0xfb0 [ 26.278247] ? __might_sleep+0x95/0x190 [ 26.278248] ? addr_handler+0xa3/0x380 [ 26.278249] ? __mutex_lock+0x16f/0x1a80 [ 26.278251] ? addr_handler+0xa3/0x380 [ 26.278252] ? trace_hardirqs_off+0x10/0x10 [ 26.278253] ? rcu_note_context_switch+0x710/0x710 [ 26.278255] ? mutex_lock_io_nested+0x1900/0x1900 [ 26.278256] ? __usermodehelper_disable+0x2f0/0x2f0 [ 26.278258] vprintk_default+0x28/0x30 [ 26.278259] vprintk_func+0x57/0xc0 [ 26.278260] printk+0xaa/0xca [ 26.278261] ? show_regs_print_info+0x18/0x18 [ 26.278262] ? __warn_printk+0x84/0xf0 [ 26.278264] ? addr_resolve+0xbc0/0xbc0 [ 26.278265] __warn_printk+0x90/0xf0 [ 26.278266] ? test_taint+0x20/0x20 [ 26.278267] ? lock_release+0xa40/0xa40 [ 26.278268] ? print_irqtrace_events+0x270/0x270 [ 26.278270] ? addr_resolve+0xbc0/0xbc0 [ 26.278271] debug_print_object+0x166/0x220 [ 26.278272] debug_check_no_obj_freed+0x662/0xf1f [ 26.278274] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 26.278275] ? free_obj_work+0x690/0x690 [ 26.278276] ? trace_hardirqs_on+0xd/0x10 [ 26.278278] ? cma_deref_id+0x2c/0x30 [ 26.278279] ? __lock_is_held+0xb6/0x140 [ 26.278280] ? debug_check_no_locks_freed+0x264/0x3c0 [ 26.278282] ? cma_work_handler+0x1d0/0x1d0 [ 26.278283] kfree+0xc7/0x260 [ 26.278284] process_one_req+0x2e7/0x6c0 [ 26.278285] ? addr_resolve+0xbc0/0xbc0 [ 26.278286] ? __lock_is_held+0xb6/0x140 [ 26.278288] process_one_work+0xc47/0x1bb0 [ 26.278289] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 26.278290] ? trace_hardirqs_on+0xd/0x10 [ 26.278292] ? pwq_dec_nr_in_flight+0x450/0x450 [ 26.278293] ? __schedule+0x903/0x1ec0 [ 26.278294] ? __lock_acquire+0x664/0x3e00 [ 26.278296] ? __lock_is_held+0xb6/0x140 [ 26.278297] ? trace_hardirqs_off+0x10/0x10 [ 26.278298] ? trace_hardirqs_off+0x10/0x10 [ 26.278299] ? lock_acquire+0x1d5/0x580 [ 26.278301] ? lock_acquire+0x1d5/0x580 [ 26.278302] ? worker_thread+0x4a3/0x1990 [ 26.278303] ? lock_downgrade+0x980/0x980 [ 26.278304] ? lock_release+0xa40/0xa40 [ 26.278306] ? retint_kernel+0x10/0x10 [ 26.278307] ? do_raw_spin_trylock+0x190/0x190 [ 26.278308] worker_thread+0x223/0x1990 [ 26.278309] ? finish_task_switch+0x1c1/0x7e0 [ 26.278311] ? lock_downgrade+0x980/0x980 [ 26.278312] ? process_one_work+0x1bb0/0x1bb0 [ 26.278314] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 26.278315] ? trace_hardirqs_on+0xd/0x10 [ 26.278316] ? _raw_spin_unlock_irq+0x27/0x70 [ 26.278317] ? finish_task_switch+0x1c1/0x7e0 [ 26.278319] ? finish_task_switch+0x182/0x7e0 [ 26.278320] ? copy_overflow+0x20/0x20 [ 26.278321] ? __schedule+0x903/0x1ec0 [ 26.278323] ? trace_hardirqs_off+0x10/0x10 [ 26.278324] ? find_held_lock+0x35/0x1d0 [ 26.278325] ? find_held_lock+0x35/0x1d0 [ 26.278326] ? complete+0x62/0x80 [ 26.278327] ? __schedule+0x1ec0/0x1ec0 [ 26.278329] ? do_wait_intr_irq+0x3e0/0x3e0 [ 26.278330] ? __lockdep_init_map+0xe4/0x650 [ 26.278331] ? do_raw_spin_trylock+0x190/0x190 [ 26.278333] ? lockdep_init_map+0x9/0x10 [ 26.278334] ? _raw_spin_unlock_irqrestore+0x31/0xc0 [ 26.278336] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 26.278337] ? trace_hardirqs_on+0xd/0x10 [ 26.278338] ? __kthread_parkme+0x176/0x240 [ 26.278339] kthread+0x33c/0x400 [ 26.278341] ? process_one_work+0x1bb0/0x1bb0 [ 26.278342] ? kthread_stop+0x7a0/0x7a0 [ 26.278343] ret_from_fork+0x3a/0x50 [ 26.278758] Dumping ftrace buffer: [ 27.215145] (ftrace buffer empty) [ 27.218826] Kernel Offset: disabled [ 27.222421] Rebooting in 86400 seconds..