[?25l[?1c7[ ok 8[?25h[?0c. Starting mcstransd: [....] Starting periodic command scheduler: cron[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting file context maintaining daemon: restorecond[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting OpenBSD Secure Shell server: sshd[?25l[?1c7[ ok 8[?25h[?0c. [ 18.880397] audit: type=1400 audit(1520914745.610:6): avc: denied { map } for pid=4222 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.35' (ECDSA) to the list of known hosts. syzkaller login: [ 49.902738] audit: type=1400 audit(1520914776.632:7): avc: denied { map } for pid=4239 comm="syzkaller537211" path="/root/syzkaller537211402" 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 [ 49.920180] IPVS: ftp: loaded support on port[0] = 21 net.ipv6.conf.syz_tun.accept_dad = 0 net.ipv6.conf.syz_tun.accept_dad = 0 net.ipv6.conf.syz_tun.router_solicitations = 0 net.ipv6.conf.syz_tun.router_solicitations = 0 [ 49.928675] audit: type=1400 audit(1520914776.632:8): avc: denied { sys_admin } for pid=4247 comm="syzkaller537211" capability=21 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=cap_userns permissive=1 [ 49.954040] IPVS: ftp: loaded support on port[0] = 21 [ 49.966209] audit: type=1400 audit(1520914776.695:9): avc: denied { net_admin } for pid=4249 comm="syzkaller537211" capability=12 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=cap_userns permissive=1 [ 49.991824] IPVS: ftp: loaded support on port[0] = 21 net.ipv6.conf.syz_tun.accept_dad = 0 net.ipv6.conf.syz_tun.router_solicitations = 0 [ 50.024403] IPVS: ftp: loaded support on port[0] = 21 net.ipv6.conf.syz_tun.accept_dad = 0 [ 50.058202] IPVS: ftp: loaded support on port[0] = 21 net.ipv6.conf.syz_tun.router_solicitations = 0 net.ipv6.conf.syz_tun.accept_dad = 0 net.ipv6.conf.syz_tun.router_solicitations = 0 [ 50.095654] IPVS: ftp: loaded support on port[0] = 21 net.ipv6.conf.syz_tun.accept_dad = 0 net.ipv6.conf.syz_tun.router_solicitations = 0 [ 50.155882] IPVS: ftp: loaded support on port[0] = 21 [ 50.218326] IPVS: ftp: loaded support on port[0] = 21 net.ipv6.conf.syz_tun.accept_dad = 0 net.ipv6.conf.syz_tun.router_solicitations = 0 net.ipv6.conf.syz_tun.accept_dad = 0 net.ipv6.conf.syz_tun.router_solicitations = 0 RTNETLINK answers: File exists RTNETLINK answers: File exists RTNETLINK answers: File exists RTNETLINK answers: File exists RTNETLINK answers: File exists RTNETLINK answers: File exists RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: File exists RTNETLINK answers: Operation not supported RTNETLINK answers: File exists RTNETLINK answers: Operation not supported [ 50.872629] IPv6: ADDRCONF(NETDEV_UP): bridge0: link is not ready [ 50.898778] IPv6: ADDRCONF(NETDEV_UP): bridge0: link is not ready RTNETLINK answers: Operation not supported RTNETLINK answers: No buffer space available [ 50.945161] IPv6: ADDRCONF(NETDEV_UP): bridge0: link is not ready RTNETLINK answers: No buffer space available RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: No buffer space available [ 51.000209] IPv6: ADDRCONF(NETDEV_UP): bridge0: link is not ready RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: No buffer space available [ 51.070584] IPv6: ADDRCONF(NETDEV_UP): bridge0: link is not ready RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: No buffer space available RTNETLINK answers: Operation not supported [ 51.114343] IPv6: ADDRCONF(NETDEV_UP): bridge0: link is not ready RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: No buffer space available RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported [ 51.255514] IPv6: ADDRCONF(NETDEV_UP): bridge0: link is not ready RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: No buffer space available RTNETLINK answers: Operation not supported [ 51.316294] IPv6: ADDRCONF(NETDEV_UP): bridge0: link is not ready RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: No buffer space available RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Operation not supported RTNETLINK answers: Invalid argument RTNETLINK answers: Operation not supported RTNETLINK answers: Operation not supported RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Operation not supported RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Operation not supported RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument RTNETLINK answers: Invalid argument [ 52.700074] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 52.706489] 8021q: adding VLAN 0 to HW filter on device bond0 [ 52.741303] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 52.747471] 8021q: adding VLAN 0 to HW filter on device bond0 [ 52.824487] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 52.830628] 8021q: adding VLAN 0 to HW filter on device bond0 [ 52.914600] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 52.920954] 8021q: adding VLAN 0 to HW filter on device bond0 [ 52.935982] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 52.942393] 8021q: adding VLAN 0 to HW filter on device bond0 [ 52.974782] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 52.985379] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 53.101565] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 53.107798] 8021q: adding VLAN 0 to HW filter on device bond0 [ 53.122825] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 53.130909] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 53.146920] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 53.251423] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready [ 53.261912] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 53.274398] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 53.290541] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready executing program executing program [ 53.297402] audit: type=1400 audit(1520914780.027:10): avc: denied { sys_chroot } for pid=4248 comm="syzkaller537211" capability=18 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=cap_userns permissive=1 [ 53.304097] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready executing program executing program executing program executing program executing program [ 53.357588] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 53.377565] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready executing program executing program executing program executing program executing program executing program executing program [ 53.417476] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready [ 53.423699] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 53.435381] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 53.448642] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready [ 53.458988] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready executing program executing program executing program executing program [ 53.485263] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 53.494781] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready [ 53.501636] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 53.514369] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready executing program executing program executing program executing program executing program executing program executing program executing program [ 53.537476] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 53.543592] 8021q: adding VLAN 0 to HW filter on device bond0 executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program [ 53.597771] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 53.603962] 8021q: adding VLAN 0 to HW filter on device bond0 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 [ 53.712841] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 53.750762] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready executing program executing program executing program executing program executing program executing program executing program executing program [ 53.757355] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 53.766327] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready 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 [ 53.881946] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 53.914481] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready [ 53.921937] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready executing program executing program executing program executing program executing program executing program executing program [ 53.929373] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready 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 [ 54.086996] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready [ 54.094119] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 54.102702] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready 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 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 executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program [ 54.768362] ------------[ cut here ]------------ [ 54.774013] ODEBUG: free active (active state 0) object type: work_struct hint: process_one_req+0x0/0x6c0 [ 54.783781] WARNING: CPU: 1 PID: 5 at lib/debugobjects.c:291 debug_print_object+0x166/0x220 [ 54.792239] Kernel panic - not syncing: panic_on_warn set ... [ 54.792239] [ 54.799581] CPU: 1 PID: 5 Comm: kworker/u4:0 Not tainted 4.16.0-rc5+ #352 [ 54.806478] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 54.815809] Workqueue: ib_addr process_one_req [ 54.820361] Call Trace: [ 54.822921] dump_stack+0x194/0x24d [ 54.826520] ? arch_local_irq_restore+0x53/0x53 [ 54.831163] ? vsnprintf+0x1ed/0x1900 [ 54.834943] panic+0x1e4/0x41c [ 54.838109] ? refcount_error_report+0x214/0x214 [ 54.842840] ? show_regs_print_info+0x18/0x18 [ 54.847315] ? __warn+0x1c1/0x200 [ 54.850744] ? debug_print_object+0x166/0x220 [ 54.855211] __warn+0x1dc/0x200 [ 54.858462] ? debug_print_object+0x166/0x220 [ 54.862935] report_bug+0x1f4/0x2b0 [ 54.866542] fixup_bug.part.11+0x37/0x80 [ 54.870576] do_error_trap+0x2d7/0x3e0 [ 54.874440] ? vprintk_default+0x28/0x30 [ 54.878476] ? math_error+0x400/0x400 [ 54.882336] ? printk+0xaa/0xca [ 54.885590] ? show_regs_print_info+0x18/0x18 [ 54.890064] ? __usermodehelper_disable+0x2f0/0x2f0 [ 54.895059] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 54.899878] do_invalid_op+0x1b/0x20 [ 54.903564] invalid_op+0x1b/0x40 [ 54.906992] RIP: 0010:debug_print_object+0x166/0x220 [ 54.912068] RSP: 0018:ffff8801d9acf210 EFLAGS: 00010086 [ 54.917426] RAX: dffffc0000000008 RBX: 0000000000000003 RCX: ffffffff815acd3e [ 54.924670] RDX: 0000000000000000 RSI: 1ffff1003b359df2 RDI: 1ffff1003b359dc7 [ 54.931917] RBP: ffff8801d9acf250 R08: 0000000000000000 R09: 1ffff1003b359d99 [ 54.939162] R10: ffffed003b359e71 R11: ffffffff86f39b78 R12: 0000000000000001 [ 54.946588] R13: ffffffff86f15440 R14: ffffffff86408680 R15: ffffffff8147c020 [ 54.953836] ? __usermodehelper_disable+0x2f0/0x2f0 [ 54.958838] ? vprintk_func+0x5e/0xc0 [ 54.962620] debug_check_no_obj_freed+0x662/0xf1f [ 54.967438] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 54.972609] ? free_obj_work+0x690/0x690 [ 54.976645] ? trace_hardirqs_on+0xd/0x10 [ 54.980772] ? cma_deref_id+0x2c/0x30 [ 54.984547] ? __lock_is_held+0xb6/0x140 [ 54.988586] ? debug_check_no_locks_freed+0x264/0x3c0 [ 54.993751] ? cma_work_handler+0x1d0/0x1d0 [ 54.998046] kfree+0xc7/0x260 [ 55.001130] process_one_req+0x2e7/0x6c0 [ 55.005166] ? addr_resolve+0xbc0/0xbc0 [ 55.009115] ? __lock_is_held+0xb6/0x140 [ 55.013159] process_one_work+0xc47/0x1bb0 [ 55.017365] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 55.022524] ? trace_hardirqs_on+0xd/0x10 [ 55.026652] ? pwq_dec_nr_in_flight+0x450/0x450 [ 55.031307] ? __schedule+0x903/0x1ec0 [ 55.035176] ? trace_hardirqs_off+0x10/0x10 [ 55.039477] ? lock_downgrade+0x980/0x980 [ 55.043603] ? do_wait_intr_irq+0x3e0/0x3e0 [ 55.047907] ? lock_acquire+0x1d5/0x580 [ 55.051854] ? lock_acquire+0x1d5/0x580 [ 55.055799] ? worker_thread+0x4a3/0x1990 [ 55.059920] ? lock_downgrade+0x980/0x980 [ 55.064045] ? lock_release+0xa40/0xa40 [ 55.067991] ? try_to_del_timer_sync+0xe0/0x130 [ 55.072635] ? do_raw_spin_trylock+0x190/0x190 [ 55.077200] worker_thread+0x223/0x1990 [ 55.081153] ? finish_task_switch+0x1c1/0x7e0 [ 55.085638] ? process_one_work+0x1bb0/0x1bb0 [ 55.090105] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 55.095096] ? trace_hardirqs_on+0xd/0x10 [ 55.099216] ? _raw_spin_unlock_irq+0x27/0x70 [ 55.103683] ? finish_task_switch+0x1c1/0x7e0 [ 55.108148] ? finish_task_switch+0x182/0x7e0 [ 55.112616] ? copy_overflow+0x20/0x20 [ 55.116492] ? __schedule+0x903/0x1ec0 [ 55.120362] ? trace_hardirqs_off+0x10/0x10 [ 55.124662] ? find_held_lock+0x35/0x1d0 [ 55.128702] ? find_held_lock+0x35/0x1d0 [ 55.132738] ? complete+0x62/0x80 [ 55.136169] ? __schedule+0x1ec0/0x1ec0 [ 55.140111] ? do_wait_intr_irq+0x3e0/0x3e0 [ 55.144403] ? __lockdep_init_map+0xe4/0x650 [ 55.148783] ? do_raw_spin_trylock+0x190/0x190 [ 55.153337] ? lockdep_init_map+0x9/0x10 [ 55.157369] ? _raw_spin_unlock_irqrestore+0x31/0xc0 [ 55.162447] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 55.167436] ? trace_hardirqs_on+0xd/0x10 [ 55.171556] ? __kthread_parkme+0x176/0x240 [ 55.175851] kthread+0x33c/0x400 [ 55.179193] ? process_one_work+0x1bb0/0x1bb0 [ 55.183658] ? kthread_stop+0x7a0/0x7a0 [ 55.187620] ret_from_fork+0x3a/0x50 [ 55.191323] [ 55.191326] ====================================================== [ 55.191328] WARNING: possible circular locking dependency detected [ 55.191330] 4.16.0-rc5+ #352 Not tainted [ 55.191332] ------------------------------------------------------ [ 55.191334] kworker/u4:0/5 is trying to acquire lock: [ 55.191335] ((console_sem).lock){..-.}, at: [<00000000880c0be9>] down_trylock+0x13/0x70 [ 55.191341] [ 55.191343] but task is already holding lock: [ 55.191344] (&obj_hash[i].lock){-.-.}, at: [<00000000ca7c32c0>] debug_check_no_obj_freed+0x1e9/0xf1f [ 55.191349] [ 55.191351] which lock already depends on the new lock. [ 55.191352] [ 55.191353] [ 55.191356] the existing dependency chain (in reverse order) is: [ 55.191356] [ 55.191357] -> #3 (&obj_hash[i].lock){-.-.}: [ 55.191363] _raw_spin_lock_irqsave+0x96/0xc0 [ 55.191365] __debug_object_init+0x109/0x1040 [ 55.191367] debug_object_init+0x17/0x20 [ 55.191368] hrtimer_init+0x8c/0x410 [ 55.191370] init_dl_task_timer+0x1b/0x50 [ 55.191372] __sched_fork+0x2bb/0xb60 [ 55.191373] init_idle+0x75/0x820 [ 55.191375] sched_init+0xb19/0xc43 [ 55.191376] start_kernel+0x452/0x819 [ 55.191378] x86_64_start_reservations+0x2a/0x2c [ 55.191380] x86_64_start_kernel+0x77/0x7a [ 55.191382] secondary_startup_64+0xa5/0xb0 [ 55.191383] [ 55.191384] -> #2 (&rq->lock){-.-.}: [ 55.191389] _raw_spin_lock+0x2a/0x40 [ 55.191391] task_fork_fair+0x7a/0x690 [ 55.191392] sched_fork+0x450/0xc10 [ 55.191394] copy_process.part.38+0x1758/0x4b60 [ 55.191396] _do_fork+0x1f7/0xf70 [ 55.191397] kernel_thread+0x34/0x40 [ 55.191399] rest_init+0x22/0xf0 [ 55.191400] start_kernel+0x7f1/0x819 [ 55.191402] x86_64_start_reservations+0x2a/0x2c [ 55.191404] x86_64_start_kernel+0x77/0x7a [ 55.191406] secondary_startup_64+0xa5/0xb0 [ 55.191407] [ 55.191408] -> #1 (&p->pi_lock){-.-.}: [ 55.191413] _raw_spin_lock_irqsave+0x96/0xc0 [ 55.191415] try_to_wake_up+0xbc/0x15f0 [ 55.191417] wake_up_process+0x10/0x20 [ 55.191418] __up.isra.0+0x1cc/0x2c0 [ 55.191420] up+0x13b/0x1d0 [ 55.191421] __up_console_sem+0xb2/0x1a0 [ 55.191423] console_unlock+0x5af/0xfb0 [ 55.191425] vprintk_emit+0x5c3/0xb90 [ 55.191426] vprintk_default+0x28/0x30 [ 55.191428] vprintk_func+0x57/0xc0 [ 55.191429] printk+0xaa/0xca [ 55.191431] kauditd_hold_skb+0x163/0x180 [ 55.191433] kauditd_send_queue+0xfa/0x140 [ 55.191434] kauditd_thread+0x660/0x940 [ 55.191436] kthread+0x33c/0x400 [ 55.191438] ret_from_fork+0x3a/0x50 [ 55.191438] [ 55.191439] -> #0 ((console_sem).lock){..-.}: [ 55.191445] lock_acquire+0x1d5/0x580 [ 55.191447] _raw_spin_lock_irqsave+0x96/0xc0 [ 55.191448] down_trylock+0x13/0x70 [ 55.191450] __down_trylock_console_sem+0xa2/0x1e0 [ 55.191452] console_trylock+0x15/0x70 [ 55.191454] vprintk_emit+0x5b5/0xb90 [ 55.191455] vprintk_default+0x28/0x30 [ 55.191457] vprintk_func+0x57/0xc0 [ 55.191458] printk+0xaa/0xca [ 55.191460] __warn_printk+0x90/0xf0 [ 55.191462] debug_print_object+0x166/0x220 [ 55.191464] debug_check_no_obj_freed+0x662/0xf1f [ 55.191465] kfree+0xc7/0x260 [ 55.191467] process_one_req+0x2e7/0x6c0 [ 55.191468] process_one_work+0xc47/0x1bb0 [ 55.191470] worker_thread+0x223/0x1990 [ 55.191472] kthread+0x33c/0x400 [ 55.191473] ret_from_fork+0x3a/0x50 [ 55.191474] [ 55.191476] other info that might help us debug this: [ 55.191477] [ 55.191478] Chain exists of: [ 55.191479] (console_sem).lock --> &rq->lock --> &obj_hash[i].lock [ 55.191486] [ 55.191488] Possible unsafe locking scenario: [ 55.191489] [ 55.191490] CPU0 CPU1 [ 55.191492] ---- ---- [ 55.191493] lock(&obj_hash[i].lock); [ 55.191497] lock(&rq->lock); [ 55.191500] lock(&obj_hash[i].lock); [ 55.191504] lock((console_sem).lock); [ 55.191507] [ 55.191508] *** DEADLOCK *** [ 55.191509] [ 55.191511] 3 locks held by kworker/u4:0/5: [ 55.191511] #0: ((wq_completion)"ib_addr"){+.+.}, at: [<0000000060401cfa>] process_one_work+0xb12/0x1bb0 [ 55.191518] #1: ((work_completion)(&(&req->work)->work)){+.+.}, at: [<00000000dc7fd1e9>] process_one_work+0xb89/0x1bb0 [ 55.191524] #2: (&obj_hash[i].lock){-.-.}, at: [<00000000ca7c32c0>] debug_check_no_obj_freed+0x1e9/0xf1f [ 55.191531] [ 55.191532] stack backtrace: [ 55.191534] CPU: 1 PID: 5 Comm: kworker/u4:0 Not tainted 4.16.0-rc5+ #352 [ 55.191538] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 55.191539] Workqueue: ib_addr process_one_req [ 55.191542] Call Trace: [ 55.191543] dump_stack+0x194/0x24d [ 55.191545] ? arch_local_irq_restore+0x53/0x53 [ 55.191547] print_circular_bug.isra.38+0x2cd/0x2dc [ 55.191548] ? save_trace+0xe0/0x2b0 [ 55.191550] __lock_acquire+0x30a8/0x3e00 [ 55.191552] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 55.191554] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 55.191556] ? __lock_acquire+0x664/0x3e00 [ 55.191557] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 55.191559] ? trace_hardirqs_off+0x10/0x10 [ 55.191561] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 55.191563] ? __lock_acquire+0x664/0x3e00 [ 55.191564] ? trace_hardirqs_off+0x10/0x10 [ 55.191566] ? find_held_lock+0x35/0x1d0 [ 55.191568] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 55.191570] ? trace_hardirqs_off+0x10/0x10 [ 55.191572] ? print_irqtrace_events+0x270/0x270 [ 55.191573] lock_acquire+0x1d5/0x580 [ 55.191575] ? lock_acquire+0x1d5/0x580 [ 55.191576] ? down_trylock+0x13/0x70 [ 55.191578] ? lock_release+0xa40/0xa40 [ 55.191579] ? vprintk_emit+0x43b/0xb90 [ 55.191581] ? lock_downgrade+0x980/0x980 [ 55.191583] ? kvm_sched_clock_read+0x25/0x40 [ 55.191584] ? sched_clock+0x31/0x40 [ 55.191586] ? sched_clock_cpu+0x1b/0x180 [ 55.191588] ? vprintk_emit+0x5b5/0xb90 [ 55.191589] _raw_spin_lock_irqsave+0x96/0xc0 [ 55.191591] ? down_trylock+0x13/0x70 [ 55.191592] down_trylock+0x13/0x70 [ 55.191594] ? vprintk_emit+0x5b5/0xb90 [ 55.191596] __down_trylock_console_sem+0xa2/0x1e0 [ 55.191597] console_trylock+0x15/0x70 [ 55.191599] vprintk_emit+0x5b5/0xb90 [ 55.191601] ? console_unlock+0xfb0/0xfb0 [ 55.191602] ? __might_sleep+0x95/0x190 [ 55.191604] ? addr_handler+0xa3/0x380 [ 55.191605] ? __mutex_lock+0x16f/0x1a80 [ 55.191607] ? addr_handler+0xa3/0x380 [ 55.191609] ? trace_hardirqs_off+0x10/0x10 [ 55.191610] ? rcu_note_context_switch+0x710/0x710 [ 55.191612] ? mutex_lock_io_nested+0x1900/0x1900 [ 55.191614] ? __usermodehelper_disable+0x2f0/0x2f0 [ 55.191616] vprintk_default+0x28/0x30 [ 55.191617] vprintk_func+0x57/0xc0 [ 55.191619] printk+0xaa/0xca [ 55.191620] ? show_regs_print_info+0x18/0x18 [ 55.191622] ? __warn_printk+0x84/0xf0 [ 55.191624] ? addr_resolve+0xbc0/0xbc0 [ 55.191625] __warn_printk+0x90/0xf0 [ 55.191630] ? test_taint+0x20/0x20 [ 55.191631] ? lock_release+0xa40/0xa40 [ 55.191633] ? print_irqtrace_events+0x270/0x270 [ 55.191635] ? addr_resolve+0xbc0/0xbc0 [ 55.191636] debug_print_object+0x166/0x220 [ 55.191638] debug_check_no_obj_freed+0x662/0xf1f [ 55.191640] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 55.191642] ? free_obj_work+0x690/0x690 [ 55.191643] ? trace_hardirqs_on+0xd/0x10 [ 55.191645] ? cma_deref_id+0x2c/0x30 [ 55.191646] ? __lock_is_held+0xb6/0x140 [ 55.191648] ? debug_check_no_locks_freed+0x264/0x3c0 [ 55.191650] ? cma_work_handler+0x1d0/0x1d0 [ 55.191651] kfree+0xc7/0x260 [ 55.191653] process_one_req+0x2e7/0x6c0 [ 55.191655] ? addr_resolve+0xbc0/0xbc0 [ 55.191656] ? __lock_is_held+0xb6/0x140 [ 55.191658] process_one_work+0xc47/0x1bb0 [ 55.191660] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 55.191662] ? trace_hardirqs_on+0xd/0x10 [ 55.191663] ? pwq_dec_nr_in_flight+0x450/0x450 [ 55.191665] ? __schedule+0x903/0x1ec0 [ 55.191667] ? trace_hardirqs_off+0x10/0x10 [ 55.191668] ? lock_downgrade+0x980/0x980 [ 55.191670] ? do_wait_intr_irq+0x3e0/0x3e0 [ 55.191672] ? lock_acquire+0x1d5/0x580 [ 55.191673] ? lock_acquire+0x1d5/0x580 [ 55.191675] ? worker_thread+0x4a3/0x1990 [ 55.191677] ? lock_downgrade+0x980/0x980 [ 55.191678] ? lock_release+0xa40/0xa40 [ 55.191680] ? try_to_del_timer_sync+0xe0/0x130 [ 55.191682] ? do_raw_spin_trylock+0x190/0x190 [ 55.191683] worker_thread+0x223/0x1990 [ 55.191685] ? finish_task_switch+0x1c1/0x7e0 [ 55.191687] ? process_one_work+0x1bb0/0x1bb0 [ 55.191689] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 55.191690] ? trace_hardirqs_on+0xd/0x10 [ 55.191692] ? _raw_spin_unlock_irq+0x27/0x70 [ 55.191694] ? finish_task_switch+0x1c1/0x7e0 [ 55.191696] ? finish_task_switch+0x182/0x7e0 [ 55.191697] ? copy_overflow+0x20/0x20 [ 55.191699] ? __schedule+0x903/0x1ec0 [ 55.191701] ? trace_hardirqs_off+0x10/0x10 [ 55.191702] ? find_held_lock+0x35/0x1d0 [ 55.191704] ? find_held_lock+0x35/0x1d0 [ 55.191705] ? complete+0x62/0x80 [ 55.191707] ? __schedule+0x1ec0/0x1ec0 [ 55.191709] ? do_wait_intr_irq+0x3e0/0x3e0 [ 55.191710] ? __lockdep_init_map+0xe4/0x650 [ 55.191712] ? do_raw_spin_trylock+0x190/0x190 [ 55.191714] ? lockdep_init_map+0x9/0x10 [ 55.191716] ? _raw_spin_unlock_irqrestore+0x31/0xc0 [ 55.191717] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 55.191719] ? trace_hardirqs_on+0xd/0x10 [ 55.191721] ? __kthread_parkme+0x176/0x240 [ 55.191722] kthread+0x33c/0x400 [ 55.191724] ? process_one_work+0x1bb0/0x1bb0 [ 55.191726] ? kthread_stop+0x7a0/0x7a0 [ 55.191727] ret_from_fork+0x3a/0x50 [ 56.239295] Shutting down cpus with NMI [ 57.170325] Dumping ftrace buffer: [ 57.173837] (ftrace buffer empty) [ 57.177523] Kernel Offset: disabled [ 57.181123] Rebooting in 86400 seconds..