[....] Starting file context maintaining daemon: restorecond[?25l[?1c7[ ok 8[?25h[?0c. Debian GNU/Linux 7 syzkaller ttyS0 syzkaller login: [ 40.984150] IPVS: ftp: loaded support on port[0] = 21 [ 41.005881] kauditd_printk_skb: 4 callbacks suppressed [ 41.012687] audit: type=1400 audit(1555050952.782:34): avc: denied { associate } for pid=5243 comm="syz-executor0" name="syz0" scontext=unconfined_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:unlabeled_t:s0 tclass=filesystem permissive=1 [ 44.089585] audit: type=1400 audit(1555050955.866:35): avc: denied { create } for pid=5228 comm="syz-fuzzer" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_crypto_socket permissive=1 [ 44.113254] audit: type=1400 audit(1555050955.867:36): avc: denied { create } for pid=5228 comm="syz-fuzzer" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_generic_socket permissive=1 [ 44.136877] audit: type=1400 audit(1555050955.867:37): avc: denied { create } for pid=5228 comm="syz-fuzzer" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_netfilter_socket permissive=1 Warning: Permanently added '10.128.0.43' (ECDSA) to the list of known hosts. 2019/04/12 06:36:03 parsed 1 programs 2019/04/12 06:36:03 executed programs: 0 [ 51.852464] IPVS: ftp: loaded support on port[0] = 21 [ 51.889563] IPVS: ftp: loaded support on port[0] = 21 [ 51.934241] IPVS: ftp: loaded support on port[0] = 21 [ 51.984798] IPVS: ftp: loaded support on port[0] = 21 [ 52.040364] IPVS: ftp: loaded support on port[0] = 21 [ 52.120199] IPVS: ftp: loaded support on port[0] = 21 [ 52.711436] bridge0: port 1(bridge_slave_0) entered blocking state [ 52.718940] bridge0: port 1(bridge_slave_0) entered disabled state [ 52.725970] device bridge_slave_0 entered promiscuous mode [ 52.789586] bridge0: port 1(bridge_slave_0) entered blocking state [ 52.801542] bridge0: port 1(bridge_slave_0) entered disabled state [ 52.816664] device bridge_slave_0 entered promiscuous mode [ 52.850483] bridge0: port 2(bridge_slave_1) entered blocking state [ 52.865281] bridge0: port 2(bridge_slave_1) entered disabled state [ 52.872256] device bridge_slave_1 entered promiscuous mode [ 52.879174] bridge0: port 2(bridge_slave_1) entered blocking state [ 52.886307] bridge0: port 2(bridge_slave_1) entered disabled state [ 52.893511] device bridge_slave_1 entered promiscuous mode [ 52.910200] bridge0: port 1(bridge_slave_0) entered blocking state [ 52.917580] bridge0: port 1(bridge_slave_0) entered disabled state [ 52.925603] device bridge_slave_0 entered promiscuous mode [ 52.944234] IPv6: ADDRCONF(NETDEV_UP): veth0_to_bridge: link is not ready [ 52.952593] IPv6: ADDRCONF(NETDEV_UP): veth0_to_bridge: link is not ready [ 53.002253] IPv6: ADDRCONF(NETDEV_UP): veth1_to_bridge: link is not ready [ 53.010066] bridge0: port 2(bridge_slave_1) entered blocking state [ 53.018018] bridge0: port 2(bridge_slave_1) entered disabled state [ 53.025597] device bridge_slave_1 entered promiscuous mode [ 53.041584] IPv6: ADDRCONF(NETDEV_UP): veth1_to_bridge: link is not ready [ 53.052927] bridge0: port 1(bridge_slave_0) entered blocking state [ 53.059372] bridge0: port 1(bridge_slave_0) entered disabled state [ 53.066763] device bridge_slave_0 entered promiscuous mode [ 53.097623] IPv6: ADDRCONF(NETDEV_UP): veth0_to_bridge: link is not ready [ 53.128126] bridge0: port 1(bridge_slave_0) entered blocking state [ 53.134838] bridge0: port 1(bridge_slave_0) entered disabled state [ 53.143723] device bridge_slave_0 entered promiscuous mode [ 53.151301] IPv6: ADDRCONF(NETDEV_UP): veth1_to_bridge: link is not ready [ 53.159802] bridge0: port 2(bridge_slave_1) entered blocking state [ 53.174922] bridge0: port 2(bridge_slave_1) entered disabled state [ 53.182456] device bridge_slave_1 entered promiscuous mode [ 53.224785] bridge0: port 2(bridge_slave_1) entered blocking state [ 53.231176] bridge0: port 2(bridge_slave_1) entered disabled state [ 53.240339] device bridge_slave_1 entered promiscuous mode [ 53.267459] IPv6: ADDRCONF(NETDEV_UP): veth0_to_bridge: link is not ready [ 53.280307] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 53.291703] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 53.311236] IPv6: ADDRCONF(NETDEV_UP): veth0_to_bridge: link is not ready [ 53.334046] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 53.349382] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 53.360761] IPv6: ADDRCONF(NETDEV_UP): veth1_to_bridge: link is not ready [ 53.370868] bridge0: port 1(bridge_slave_0) entered blocking state [ 53.379998] bridge0: port 1(bridge_slave_0) entered disabled state [ 53.391262] device bridge_slave_0 entered promiscuous mode [ 53.400357] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 53.409394] IPv6: ADDRCONF(NETDEV_UP): veth1_to_bridge: link is not ready [ 53.420201] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 53.441073] bridge0: port 2(bridge_slave_1) entered blocking state [ 53.449301] bridge0: port 2(bridge_slave_1) entered disabled state [ 53.456471] device bridge_slave_1 entered promiscuous mode [ 53.501041] IPv6: ADDRCONF(NETDEV_UP): veth0_to_bridge: link is not ready [ 53.531751] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 53.549425] IPv6: ADDRCONF(NETDEV_UP): veth1_to_bridge: link is not ready [ 53.599810] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 53.640654] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 53.665263] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 53.673965] team0: Port device team_slave_0 added [ 53.697760] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 53.706650] team0: Port device team_slave_0 added [ 53.716731] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 53.732207] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 53.739224] team0: Port device team_slave_1 added [ 53.762994] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 53.770062] team0: Port device team_slave_0 added [ 53.778611] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 53.789896] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 53.806610] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 53.816129] team0: Port device team_slave_1 added [ 53.825919] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 53.847561] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 53.855142] team0: Port device team_slave_1 added [ 53.863627] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 53.882418] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 53.890250] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 53.925632] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 53.937714] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 53.957736] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 53.969794] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 53.986606] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 53.997356] team0: Port device team_slave_0 added [ 54.006997] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 54.037966] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 54.060483] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 54.069984] team0: Port device team_slave_0 added [ 54.076016] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 54.084275] team0: Port device team_slave_1 added [ 54.096292] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 54.125909] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 54.137156] team0: Port device team_slave_1 added [ 54.155173] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 54.176773] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 54.190739] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 54.204548] team0: Port device team_slave_0 added [ 54.214783] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 54.234112] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 54.265318] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 54.285810] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 54.293667] team0: Port device team_slave_1 added [ 54.308190] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 54.316746] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 54.342410] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 54.372469] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 54.407867] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 54.424044] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 54.488496] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 54.528526] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 54.613607] bridge0: port 2(bridge_slave_1) entered blocking state [ 54.620032] bridge0: port 2(bridge_slave_1) entered forwarding state [ 54.627140] bridge0: port 1(bridge_slave_0) entered blocking state [ 54.633529] bridge0: port 1(bridge_slave_0) entered forwarding state [ 54.644461] bridge0: port 2(bridge_slave_1) entered blocking state [ 54.650851] bridge0: port 2(bridge_slave_1) entered forwarding state [ 54.657584] bridge0: port 1(bridge_slave_0) entered blocking state [ 54.663974] bridge0: port 1(bridge_slave_0) entered forwarding state [ 54.754420] bridge0: port 2(bridge_slave_1) entered blocking state [ 54.760839] bridge0: port 2(bridge_slave_1) entered forwarding state [ 54.767837] bridge0: port 1(bridge_slave_0) entered blocking state [ 54.774351] bridge0: port 1(bridge_slave_0) entered forwarding state [ 54.867530] bridge0: port 2(bridge_slave_1) entered blocking state [ 54.874005] bridge0: port 2(bridge_slave_1) entered forwarding state [ 54.880769] bridge0: port 1(bridge_slave_0) entered blocking state [ 54.887746] bridge0: port 1(bridge_slave_0) entered forwarding state [ 55.026527] bridge0: port 2(bridge_slave_1) entered blocking state [ 55.032973] bridge0: port 2(bridge_slave_1) entered forwarding state [ 55.039803] bridge0: port 1(bridge_slave_0) entered blocking state [ 55.046238] bridge0: port 1(bridge_slave_0) entered forwarding state [ 55.107175] bridge0: port 2(bridge_slave_1) entered blocking state [ 55.113612] bridge0: port 2(bridge_slave_1) entered forwarding state [ 55.120262] bridge0: port 1(bridge_slave_0) entered blocking state [ 55.126709] bridge0: port 1(bridge_slave_0) entered forwarding state [ 56.716312] 8021q: adding VLAN 0 to HW filter on device bond0 [ 56.846835] 8021q: adding VLAN 0 to HW filter on device bond0 [ 56.900615] 8021q: adding VLAN 0 to HW filter on device bond0 [ 56.909901] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 57.011370] 8021q: adding VLAN 0 to HW filter on device bond0 [ 57.055987] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 57.072506] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 57.103332] 8021q: adding VLAN 0 to HW filter on device bond0 [ 57.123393] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 57.162216] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 57.238584] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 57.257475] 8021q: adding VLAN 0 to HW filter on device team0 [ 57.273554] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 57.305705] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 57.325083] 8021q: adding VLAN 0 to HW filter on device bond0 [ 57.373197] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 57.410782] 8021q: adding VLAN 0 to HW filter on device team0 [ 57.435400] 8021q: adding VLAN 0 to HW filter on device team0 [ 57.495383] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 57.508858] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 57.577375] 8021q: adding VLAN 0 to HW filter on device team0 [ 57.662270] 8021q: adding VLAN 0 to HW filter on device team0 [ 57.691099] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 57.828554] 8021q: adding VLAN 0 to HW filter on device team0 2019/04/12 06:36:10 executed programs: 6 2019/04/12 06:36:15 executed programs: 436 2019/04/12 06:36:20 executed programs: 879 2019/04/12 06:36:25 executed programs: 1328 2019/04/12 06:36:30 executed programs: 1780 2019/04/12 06:36:35 executed programs: 2228 2019/04/12 06:36:40 executed programs: 2662 2019/04/12 06:36:45 executed programs: 3090 2019/04/12 06:36:50 executed programs: 3559 2019/04/12 06:36:55 executed programs: 3995 2019/04/12 06:37:00 executed programs: 4439 2019/04/12 06:37:05 executed programs: 4878 2019/04/12 06:37:10 executed programs: 5296 2019/04/12 06:37:15 executed programs: 5723 2019/04/12 06:37:20 executed programs: 6155 2019/04/12 06:37:25 executed programs: 6613 2019/04/12 06:37:30 executed programs: 7065 2019/04/12 06:37:35 executed programs: 7508 2019/04/12 06:37:40 executed programs: 7968 2019/04/12 06:37:45 executed programs: 8393 2019/04/12 06:37:50 executed programs: 8823 2019/04/12 06:37:55 executed programs: 9260 2019/04/12 06:38:00 executed programs: 9703 2019/04/12 06:38:05 executed programs: 10139 2019/04/12 06:38:10 executed programs: 10573 2019/04/12 06:38:15 executed programs: 11026 2019/04/12 06:38:20 executed programs: 11465 2019/04/12 06:38:25 executed programs: 11886 2019/04/12 06:38:30 executed programs: 12314 2019/04/12 06:38:35 executed programs: 12763 2019/04/12 06:38:40 executed programs: 13215 2019/04/12 06:38:45 executed programs: 13677 2019/04/12 06:38:50 executed programs: 14120 2019/04/12 06:38:55 executed programs: 14558 2019/04/12 06:39:00 executed programs: 14993 2019/04/12 06:39:05 executed programs: 15431 2019/04/12 06:39:10 executed programs: 15876 2019/04/12 06:39:15 executed programs: 16315 2019/04/12 06:39:20 executed programs: 16755 2019/04/12 06:39:25 executed programs: 17195 2019/04/12 06:39:30 executed programs: 17632 2019/04/12 06:39:35 executed programs: 18064 2019/04/12 06:39:40 executed programs: 18484 2019/04/12 06:39:45 executed programs: 18926 2019/04/12 06:39:50 executed programs: 19367 2019/04/12 06:39:55 executed programs: 19825 2019/04/12 06:40:00 executed programs: 20284 2019/04/12 06:40:05 executed programs: 20708 2019/04/12 06:40:10 executed programs: 21148 2019/04/12 06:40:15 executed programs: 21587 2019/04/12 06:40:20 executed programs: 22032 2019/04/12 06:40:25 executed programs: 22484 2019/04/12 06:40:30 executed programs: 22940 2019/04/12 06:40:35 executed programs: 23383 2019/04/12 06:40:40 executed programs: 23829 2019/04/12 06:40:45 executed programs: 24293 2019/04/12 06:40:50 executed programs: 24739 2019/04/12 06:40:55 executed programs: 25208 2019/04/12 06:41:00 executed programs: 25662 2019/04/12 06:41:05 executed programs: 26120 [ 356.322471] kworker/dying (358) used greatest stack depth: 16544 bytes left 2019/04/12 06:41:10 executed programs: 26554 2019/04/12 06:41:15 executed programs: 26988 2019/04/12 06:41:20 executed programs: 27440 2019/04/12 06:41:25 executed programs: 27871 2019/04/12 06:41:30 executed programs: 28305 2019/04/12 06:41:35 executed programs: 28767 2019/04/12 06:41:40 executed programs: 29215 2019/04/12 06:41:45 executed programs: 29658 2019/04/12 06:41:50 executed programs: 30100 2019/04/12 06:41:55 executed programs: 30536 2019/04/12 06:42:00 executed programs: 30967 2019/04/12 06:42:05 executed programs: 31420 2019/04/12 06:42:10 executed programs: 31879 2019/04/12 06:42:15 executed programs: 32304 2019/04/12 06:42:20 executed programs: 32738 2019/04/12 06:42:25 executed programs: 33180 [ 437.677544] ODEBUG: free active (active state 0) object type: timer_list hint: igmp_timer_expire+0x0/0x570 [ 437.688159] ------------[ cut here ]------------ [ 437.691478] kobject: 'loop0' (ffff8801d230a3e0): kobject_uevent_env [ 437.691496] kobject: 'loop0' (ffff8801d230a3e0): fill_kobj_path: path = '/devices/virtual/block/loop0' [ 437.708846] WARNING: CPU: 0 PID: 7 at lib/debugobjects.c:289 debug_print_object+0x17a/0x210 [ 437.717326] Kernel panic - not syncing: panic_on_warn set ... [ 437.717326] [ 437.724668] CPU: 0 PID: 7 Comm: ksoftirqd/0 Not tainted 4.12.0-rc1+ #1 [ 437.731313] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 437.740650] Call Trace: [ 437.743221] dump_stack+0x145/0x1f1 [ 437.746827] ? arch_local_irq_restore+0x44/0x44 [ 437.751479] ? debug_print_object+0x17a/0x210 [ 437.756041] panic+0x1b6/0x358 [ 437.759211] ? percpu_up_read_preempt_enable.constprop.37+0xb9/0xb9 [ 437.765601] ? debug_print_object+0x17a/0x210 [ 437.770075] __warn+0x18d/0x1b0 [ 437.773336] ? debug_print_object+0x17a/0x210 [ 437.777811] report_bug+0x1a4/0x250 [ 437.781419] ? fixup_exception+0x3b/0xb0 [ 437.785463] do_trap+0x1d9/0x3e0 [ 437.788811] do_error_trap+0x120/0x390 [ 437.792681] ? vprintk_emit+0x3ce/0x500 [ 437.796634] ? do_trap+0x3e0/0x3e0 [ 437.800161] ? debug_print_object+0x17a/0x210 [ 437.804637] ? vprintk_emit+0x3db/0x500 [ 437.808596] ? igmp_netdev_event+0x720/0x720 [ 437.812986] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 437.817805] ? console_unlock+0x54c/0xcf0 [ 437.821932] ? igmp_netdev_event+0x720/0x720 [ 437.826398] do_invalid_op+0x1b/0x20 [ 437.830091] invalid_op+0x1e/0x30 [ 437.833524] RIP: 0010:debug_print_object+0x17a/0x210 [ 437.838601] RSP: 0018:ffff8801d9777700 EFLAGS: 00010082 [ 437.843943] RAX: 000000000000005e RBX: 0000000000000003 RCX: 0000000000000000 [ 437.851675] RDX: 000000000000005e RSI: 1ffff1003b2eeea2 RDI: ffffed003b2eeed6 [ 437.858925] RBP: ffff8801d9777728 R08: 0000000000000000 R09: 1ffff1003b2eee74 [ 437.866178] R10: ffffed003b2eef0c R11: ffffffff81583bac R12: ffffffff870c7ca0 [ 437.873430] R13: ffffffff85f0dc70 R14: 0000000000000000 R15: dffffc0000000000 [ 437.880692] ? igmp_netdev_event+0x720/0x720 [ 437.885082] ? console_unlock+0x54c/0xcf0 [ 437.889242] debug_check_no_obj_freed+0x662/0xf20 [ 437.894069] ? lock_downgrade+0x990/0x990 [ 437.898204] ? free_obj_work+0x690/0x690 [ 437.902244] ? lock_acquire+0x1e7/0x5b0 [ 437.906199] ? lock_acquire+0x1e7/0x5b0 [ 437.910159] ? __lock_is_held+0xb6/0x140 [ 437.914196] ? rcu_dynticks_momentary_idle+0x70/0x70 [ 437.919279] ? debug_check_no_locks_freed+0x264/0x3c0 [ 437.924458] kfree+0xbd/0x270 [ 437.927543] rcu_process_callbacks+0xebd/0x1800 [ 437.932216] ? note_gp_changes+0x650/0x650 [ 437.936442] ? lock_downgrade+0x990/0x990 [ 437.940571] ? dequeue_entity+0x277/0x1500 [ 437.944874] ? lock_release+0xa70/0xa70 [ 437.948828] ? compat_start_thread+0x80/0x80 [ 437.953218] ? do_raw_spin_trylock+0x190/0x190 [ 437.957788] ? _raw_spin_unlock_irq+0x27/0x80 [ 437.962276] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 437.967282] ? preempt_notifier_dec+0x20/0x20 [ 437.971761] ? rcu_lockdep_current_cpu_online+0x18d/0x200 [ 437.977279] ? rcu_dynticks_eqs_exit+0x51/0x70 [ 437.981839] ? rcu_pm_notify+0xc0/0xc0 [ 437.985704] ? rcu_dynticks_momentary_idle+0x70/0x70 [ 437.990792] __do_softirq+0x300/0xbd3 [ 437.994575] ? __irqentry_text_end+0x6/0x6 [ 437.998793] ? retint_kernel+0x2d/0x2d [ 438.002662] ? retint_kernel+0x2d/0x2d [ 438.006528] ? schedule+0x115/0x440 [ 438.010144] ? ___might_sleep+0x376/0x500 [ 438.014287] ? rcu_sched_qs+0x120/0x120 [ 438.018242] ? run_ksoftirqd+0x8b/0x100 [ 438.022200] ? takeover_tasklets+0xaf0/0xaf0 [ 438.026585] ? takeover_tasklets+0xaf0/0xaf0 [ 438.030973] run_ksoftirqd+0x86/0x100 [ 438.034766] smpboot_thread_fn+0x574/0x900 [ 438.038982] ? smpboot_update_cpumask_percpu_thread+0x5e0/0x5e0 [ 438.045026] ? __kthread_parkme+0xd2/0x210 [ 438.049238] ? __kthread_parkme+0xd7/0x210 [ 438.053452] kthread+0x345/0x410 [ 438.056800] ? smpboot_update_cpumask_percpu_thread+0x5e0/0x5e0 [ 438.062835] ? kthread_create_on_node+0xc0/0xc0 [ 438.067502] ret_from_fork+0x31/0x40 [ 438.071203] [ 438.071204] ====================================================== [ 438.071206] WARNING: possible circular locking dependency detected [ 438.071207] 4.12.0-rc1+ #1 Not tainted [ 438.071208] ------------------------------------------------------ [ 438.071209] ksoftirqd/0/7 is trying to acquire lock: [ 438.071210] ((console_sem).lock){-.-...}, at: [] down_trylock+0x13/0x70 [ 438.071215] [ 438.071216] but task is already holding lock: [ 438.071216] (&obj_hash[i].lock){-.-.-.}, at: [] debug_check_no_obj_freed+0x1e9/0xf20 [ 438.071220] [ 438.071221] which lock already depends on the new lock. [ 438.071222] [ 438.071223] [ 438.071224] the existing dependency chain (in reverse order) is: [ 438.071225] [ 438.071226] -> #3 (&obj_hash[i].lock){-.-.-.}: [ 438.071230] lock_acquire+0x1e7/0x5b0 [ 438.071231] _raw_spin_lock_irqsave+0x99/0xd0 [ 438.071232] __debug_object_init+0x109/0x1030 [ 438.071233] debug_object_init+0x16/0x20 [ 438.071234] hrtimer_init+0x88/0x450 [ 438.071235] init_dl_task_timer+0x1b/0x50 [ 438.071236] __sched_fork+0x2ba/0xce0 [ 438.071237] init_idle+0x75/0x7a0 [ 438.071238] sched_init+0xcdc/0xe3b [ 438.071239] start_kernel+0x29a/0x5f8 [ 438.071240] x86_64_start_reservations+0x2a/0x2c [ 438.071242] x86_64_start_kernel+0x13c/0x149 [ 438.071243] verify_cpu+0x0/0xf1 [ 438.071243] [ 438.071244] -> #2 (&rq->lock){-.-.-.}: [ 438.071248] lock_acquire+0x1e7/0x5b0 [ 438.071249] _raw_spin_lock+0x2d/0x40 [ 438.071250] task_fork_fair+0xa0/0x8a0 [ 438.071251] sched_fork+0x430/0xeb0 [ 438.071252] copy_process.part.32+0x17ee/0x4a20 [ 438.071253] _do_fork+0x1b5/0xe20 [ 438.071254] kernel_thread+0x24/0x30 [ 438.071255] rest_init+0x1d/0x160 [ 438.071256] start_kernel+0x5d2/0x5f8 [ 438.071257] x86_64_start_reservations+0x2a/0x2c [ 438.071258] x86_64_start_kernel+0x13c/0x149 [ 438.071259] verify_cpu+0x0/0xf1 [ 438.071260] [ 438.071261] -> #1 (&p->pi_lock){-.-.-.}: [ 438.071264] lock_acquire+0x1e7/0x5b0 [ 438.071265] _raw_spin_lock_irqsave+0x99/0xd0 [ 438.071266] try_to_wake_up+0xa4/0x1080 [ 438.071267] wake_up_process+0x10/0x20 [ 438.071268] __up.isra.0+0x1cc/0x2c0 [ 438.071269] up+0x13b/0x1d0 [ 438.071270] __up_console_sem+0xa0/0x150 [ 438.071272] console_unlock+0x540/0xcf0 [ 438.071273] vprintk_emit+0x3db/0x500 [ 438.071274] vprintk_default+0x1a/0x20 [ 438.071274] vprintk_func+0x2a/0xd0 [ 438.071275] printk+0x8f/0xab [ 438.071277] kauditd_hold_skb+0x10a/0x130 [ 438.071278] auditd_reset+0x11f/0x1a0 [ 438.071279] kauditd_thread+0x599/0x870 [ 438.071280] kthread+0x345/0x410 [ 438.071281] ret_from_fork+0x31/0x40 [ 438.071281] [ 438.071282] -> #0 ((console_sem).lock){-.-...}: [ 438.071286] __lock_acquire+0x2f2d/0x3e60 [ 438.071287] lock_acquire+0x1e7/0x5b0 [ 438.071288] _raw_spin_lock_irqsave+0x99/0xd0 [ 438.071289] down_trylock+0x13/0x70 [ 438.071290] __down_trylock_console_sem+0x93/0x190 [ 438.071291] console_trylock+0x12/0x110 [ 438.071292] vprintk_emit+0x3ce/0x500 [ 438.071293] vprintk_default+0x1a/0x20 [ 438.071294] vprintk_func+0x2a/0xd0 [ 438.071295] printk+0x8f/0xab [ 438.071297] debug_print_object+0x17a/0x210 [ 438.071298] debug_check_no_obj_freed+0x662/0xf20 [ 438.071299] kfree+0xbd/0x270 [ 438.071300] rcu_process_callbacks+0xebd/0x1800 [ 438.071301] __do_softirq+0x300/0xbd3 [ 438.071302] run_ksoftirqd+0x86/0x100 [ 438.071303] smpboot_thread_fn+0x574/0x900 [ 438.071304] kthread+0x345/0x410 [ 438.071305] ret_from_fork+0x31/0x40 [ 438.071306] [ 438.071307] other info that might help us debug this: [ 438.071308] [ 438.071309] Chain exists of: [ 438.071309] (console_sem).lock --> &rq->lock --> &obj_hash[i].lock [ 438.071314] [ 438.071315] Possible unsafe locking scenario: [ 438.071316] [ 438.071317] CPU0 CPU1 [ 438.071318] ---- ---- [ 438.071319] lock(&obj_hash[i].lock); [ 438.071321] lock(&rq->lock); [ 438.071324] lock(&obj_hash[i].lock); [ 438.071326] lock((console_sem).lock); [ 438.071328] [ 438.071329] *** DEADLOCK *** [ 438.071330] [ 438.071331] 2 locks held by ksoftirqd/0/7: [ 438.071332] #0: (rcu_callback){......}, at: [] rcu_process_callbacks+0xe71/0x1800 [ 438.071335] #1: (&obj_hash[i].lock){-.-.-.}, at: [] debug_check_no_obj_freed+0x1e9/0xf20 [ 438.071339] [ 438.071340] stack backtrace: [ 438.071342] CPU: 0 PID: 7 Comm: ksoftirqd/0 Not tainted 4.12.0-rc1+ #1 [ 438.071343] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 438.071344] Call Trace: [ 438.071345] dump_stack+0x145/0x1f1 [ 438.071346] ? arch_local_irq_restore+0x44/0x44 [ 438.071347] ? print_lock+0x9f/0xa2 [ 438.071348] print_circular_bug+0x2a0/0x350 [ 438.071349] __lock_acquire+0x2f2d/0x3e60 [ 438.071350] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 438.071351] ? string+0x14c/0x220 [ 438.071352] ? symbol_string+0xea/0x190 [ 438.071353] ? print_usage_bug+0x3f0/0x3f0 [ 438.071354] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 438.071355] ? check_noncircular+0x20/0x20 [ 438.071357] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 438.071358] lock_acquire+0x1e7/0x5b0 [ 438.071359] ? lock_acquire+0x1e7/0x5b0 [ 438.071360] ? down_trylock+0x13/0x70 [ 438.071361] ? find_held_lock+0x35/0x1d0 [ 438.071362] ? lock_release+0xa70/0xa70 [ 438.071363] ? vprintk_emit+0x2d3/0x500 [ 438.071364] ? lock_downgrade+0x990/0x990 [ 438.071365] ? kvm_clock_read+0x23/0x40 [ 438.071366] ? kvm_sched_clock_read+0x9/0x20 [ 438.071367] ? sched_clock+0x31/0x40 [ 438.071368] ? sched_clock_cpu+0x1b/0x1e0 [ 438.071369] ? vprintk_emit+0x3ce/0x500 [ 438.071370] _raw_spin_lock_irqsave+0x99/0xd0 [ 438.071371] ? down_trylock+0x13/0x70 [ 438.071372] down_trylock+0x13/0x70 [ 438.071373] ? vprintk_emit+0x3ce/0x500 [ 438.071374] __down_trylock_console_sem+0x93/0x190 [ 438.071375] console_trylock+0x12/0x110 [ 438.071376] vprintk_emit+0x3ce/0x500 [ 438.071377] vprintk_default+0x1a/0x20 [ 438.071378] vprintk_func+0x2a/0xd0 [ 438.071379] ? igmp_netdev_event+0x720/0x720 [ 438.071380] printk+0x8f/0xab [ 438.071381] ? pm_qos_get_value.part.4+0x6/0x6 [ 438.071382] ? igmp_netdev_event+0x720/0x720 [ 438.071383] debug_print_object+0x17a/0x210 [ 438.071384] debug_check_no_obj_freed+0x662/0xf20 [ 438.071385] ? lock_downgrade+0x990/0x990 [ 438.071386] ? free_obj_work+0x690/0x690 [ 438.071387] ? lock_acquire+0x1e7/0x5b0 [ 438.071388] ? lock_acquire+0x1e7/0x5b0 [ 438.071389] ? __lock_is_held+0xb6/0x140 [ 438.071390] ? rcu_dynticks_momentary_idle+0x70/0x70 [ 438.071392] ? debug_check_no_locks_freed+0x264/0x3c0 [ 438.071393] kfree+0xbd/0x270 [ 438.071394] rcu_process_callbacks+0xebd/0x1800 [ 438.071395] ? note_gp_changes+0x650/0x650 [ 438.071396] ? lock_downgrade+0x990/0x990 [ 438.071397] ? dequeue_entity+0x277/0x1500 [ 438.071398] ? lock_release+0xa70/0xa70 [ 438.071399] ? compat_start_thread+0x80/0x80 [ 438.071400] ? do_raw_spin_trylock+0x190/0x190 [ 438.071401] ? _raw_spin_unlock_irq+0x27/0x80 [ 438.071403] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 438.071404] ? preempt_notifier_dec+0x20/0x20 [ 438.071405] ? rcu_lockdep_current_cpu_online+0x18d/0x200 [ 438.071406] ? rcu_dynticks_eqs_exit+0x51/0x70 [ 438.071407] ? rcu_pm_notify+0xc0/0xc0 [ 438.071408] ? rcu_dynticks_momentary_idle+0x70/0x70 [ 438.071409] __do_softirq+0x300/0xbd3 [ 438.071410] ? __irqentry_text_end+0x6/0x6 [ 438.071411] ? retint_kernel+0x2d/0x2d [ 438.071412] ? retint_kernel+0x2d/0x2d [ 438.071413] ? schedule+0x115/0x440 [ 438.071414] ? ___might_sleep+0x376/0x500 [ 438.071415] ? rcu_sched_qs+0x120/0x120 [ 438.071416] ? run_ksoftirqd+0x8b/0x100 [ 438.071417] ? takeover_tasklets+0xaf0/0xaf0 [ 438.071418] ? takeover_tasklets+0xaf0/0xaf0 [ 438.071419] run_ksoftirqd+0x86/0x100 [ 438.071420] smpboot_thread_fn+0x574/0x900 [ 438.071421] ? smpboot_update_cpumask_percpu_thread+0x5e0/0x5e0 [ 438.071422] ? __kthread_parkme+0xd2/0x210 [ 438.071423] ? __kthread_parkme+0xd7/0x210 [ 438.071424] kthread+0x345/0x410 [ 438.071425] ? smpboot_update_cpumask_percpu_thread+0x5e0/0x5e0 [ 438.071427] ? kthread_create_on_node+0xc0/0xc0 [ 438.071427] ret_from_fork+0x31/0x40 [ 439.169257] Shutting down cpus with NMI [ 439.983041] Kernel Offset: disabled [ 439.986671] Rebooting in 86400 seconds..