Warning: Permanently added '10.128.0.128' (ECDSA) to the list of known hosts. 2023/03/26 09:15:30 ignoring optional flag "sandboxArg"="0" 2023/03/26 09:15:30 parsed 1 programs 2023/03/26 09:15:30 executed programs: 0 [ 59.993233][ T4398] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1 [ 60.000793][ T4398] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9 [ 60.008057][ T4398] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9 [ 60.016303][ T4398] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4 [ 60.023806][ T4398] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3 [ 60.031434][ T4398] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2 [ 60.092514][ T5538] chnl_net:caif_netlink_parms(): no params data found [ 60.118569][ T5538] bridge0: port 1(bridge_slave_0) entered blocking state [ 60.125830][ T5538] bridge0: port 1(bridge_slave_0) entered disabled state [ 60.133071][ T5538] bridge_slave_0: entered allmulticast mode [ 60.139465][ T5538] bridge_slave_0: entered promiscuous mode [ 60.146543][ T5538] bridge0: port 2(bridge_slave_1) entered blocking state [ 60.153724][ T5538] bridge0: port 2(bridge_slave_1) entered disabled state [ 60.160894][ T5538] bridge_slave_1: entered allmulticast mode [ 60.167193][ T5538] bridge_slave_1: entered promiscuous mode [ 60.181485][ T5538] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 60.192556][ T5538] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 60.210813][ T5538] team0: Port device team_slave_0 added [ 60.217650][ T5538] team0: Port device team_slave_1 added [ 60.230810][ T5538] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 60.237816][ T5538] batman_adv: batadv0: The MTU of interface batadv_slave_0 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. [ 60.264012][ T5538] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 60.275627][ T5538] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 60.282705][ T5538] batman_adv: batadv0: The MTU of interface batadv_slave_1 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. [ 60.308903][ T5538] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 60.332415][ T5538] hsr_slave_0: entered promiscuous mode [ 60.338408][ T5538] hsr_slave_1: entered promiscuous mode [ 60.728103][ T5538] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 60.737557][ T5538] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 60.749492][ T5538] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 60.759224][ T5538] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 60.777979][ T5538] bridge0: port 2(bridge_slave_1) entered blocking state [ 60.785133][ T5538] bridge0: port 2(bridge_slave_1) entered forwarding state [ 60.792759][ T5538] bridge0: port 1(bridge_slave_0) entered blocking state [ 60.799868][ T5538] bridge0: port 1(bridge_slave_0) entered forwarding state [ 60.839889][ T5538] 8021q: adding VLAN 0 to HW filter on device bond0 [ 60.852878][ T5089] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 60.862585][ T5089] bridge0: port 1(bridge_slave_0) entered disabled state [ 60.870562][ T5089] bridge0: port 2(bridge_slave_1) entered disabled state [ 60.878596][ T5089] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 60.892040][ T5538] 8021q: adding VLAN 0 to HW filter on device team0 [ 60.903181][ T896] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 60.913275][ T896] bridge0: port 1(bridge_slave_0) entered blocking state [ 60.920388][ T896] bridge0: port 1(bridge_slave_0) entered forwarding state [ 60.932183][ T896] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 60.941523][ T896] bridge0: port 2(bridge_slave_1) entered blocking state [ 60.948606][ T896] bridge0: port 2(bridge_slave_1) entered forwarding state [ 60.971585][ T5089] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 60.980328][ T5089] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 60.988835][ T5089] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 61.017823][ T5089] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 61.027938][ T5089] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 61.040737][ T5538] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 61.181995][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 61.190174][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 61.202648][ T5538] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 61.219332][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 61.228909][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 61.247956][ T5538] veth0_vlan: entered promiscuous mode [ 61.255565][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 61.264624][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 61.277099][ T5538] veth1_vlan: entered promiscuous mode [ 61.285128][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 61.293915][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 61.302476][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 61.320742][ T896] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 61.329050][ T896] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 61.338572][ T896] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 61.348381][ T5538] veth0_macvtap: entered promiscuous mode [ 61.360080][ T5538] veth1_macvtap: entered promiscuous mode [ 61.376349][ T5538] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 61.385892][ T896] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 61.395426][ T896] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 61.404036][ T896] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 61.414162][ T896] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 61.426077][ T5538] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 61.435032][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 61.444191][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 61.455485][ T5538] netdevsim netdevsim0 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 61.465814][ T5538] netdevsim netdevsim0 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 61.475670][ T5538] netdevsim netdevsim0 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 61.485027][ T5538] netdevsim netdevsim0 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 61.542849][ T9] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 61.559731][ T9] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 61.570662][ T5565] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 61.581181][ T40] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 61.589074][ T40] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 61.597900][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 62.110481][ T4398] Bluetooth: hci0: command 0x0409 tx timeout [ 62.306919][ T5604] loop0: detected capacity change from 0 to 131072 [ 62.327681][ T5604] F2FS-fs (loop0): invalid crc value [ 62.338015][ T5604] F2FS-fs (loop0): Found nat_bits in checkpoint [ 62.381088][ T5604] F2FS-fs (loop0): Mounted with checkpoint version = 48b305e4 [ 64.179514][ T4398] Bluetooth: hci0: command 0x041b tx timeout [ 66.260187][ T4398] Bluetooth: hci0: command 0x040f tx timeout 2023/03/26 09:15:37 executed programs: 3 [ 68.349610][ T4398] Bluetooth: hci0: command 0x0419 tx timeout [ 70.422655][ T1210] ieee802154 phy0 wpan0: encryption failed: -22 [ 70.424099][ T7] cfg80211: failed to load regulatory.db [ 70.429490][ T1210] ieee802154 phy1 wpan1: encryption failed: -22 2023/03/26 09:15:45 executed programs: 6 2023/03/26 09:15:50 executed programs: 9 2023/03/26 09:15:55 executed programs: 13 2023/03/26 09:16:01 executed programs: 17 2023/03/26 09:16:06 executed programs: 21 2023/03/26 09:16:12 executed programs: 25 2023/03/26 09:16:17 executed programs: 29 2023/03/26 09:16:23 executed programs: 33 2023/03/26 09:16:28 executed programs: 37 2023/03/26 09:16:33 executed programs: 41 2023/03/26 09:16:39 executed programs: 45 [ 131.870452][ T1210] ieee802154 phy0 wpan0: encryption failed: -22 [ 131.876882][ T1210] ieee802154 phy1 wpan1: encryption failed: -22 2023/03/26 09:16:44 executed programs: 49 2023/03/26 09:16:49 executed programs: 53 2023/03/26 09:16:55 executed programs: 57 2023/03/26 09:17:00 executed programs: 61 2023/03/26 09:17:06 executed programs: 65 2023/03/26 09:17:11 executed programs: 69 2023/03/26 09:17:17 executed programs: 73 2023/03/26 09:17:22 executed programs: 77 2023/03/26 09:17:27 executed programs: 81 2023/03/26 09:17:33 executed programs: 85 [ 185.139526][ T4398] Bluetooth: hci0: command 0x0406 tx timeout 2023/03/26 09:17:38 executed programs: 89 2023/03/26 09:17:43 executed programs: 93 [ 193.300255][ T1210] ieee802154 phy0 wpan0: encryption failed: -22 [ 193.306588][ T1210] ieee802154 phy1 wpan1: encryption failed: -22 2023/03/26 09:17:49 executed programs: 97 2023/03/26 09:17:54 executed programs: 101 2023/03/26 09:18:00 executed programs: 105 2023/03/26 09:18:05 executed programs: 109 2023/03/26 09:18:10 executed programs: 113 2023/03/26 09:18:16 executed programs: 117 [ 227.541994][ T6678] syz-executor.0 (6678) used greatest stack depth: 22344 bytes left 2023/03/26 09:18:21 executed programs: 121 2023/03/26 09:18:26 executed programs: 125 2023/03/26 09:18:32 executed programs: 129 [ 242.579459][ T27] INFO: task kworker/u4:6:3759 blocked for more than 143 seconds. [ 242.587571][ T27] Not tainted 6.3.0-rc3-syzkaller-00322-g4bdec23f971b-dirty #0 [ 242.605230][ T27] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 242.618501][ T27] task:kworker/u4:6 state:D stack:22904 pid:3759 ppid:2 flags:0x00004000 [ 242.634623][ T27] Workqueue: writeback wb_workfn (flush-7:0) [ 242.644117][ T27] Call Trace: [ 242.647413][ T27] [ 242.654921][ T27] __schedule+0xc91/0x5770 [ 242.661730][ T27] ? io_schedule_timeout+0x150/0x150 [ 242.667045][ T27] ? rwsem_down_write_slowpath+0x3b8/0x1220 [ 242.673208][ T27] schedule+0xde/0x1a0 [ 242.677414][ T27] schedule_preempt_disabled+0x13/0x20 [ 242.683089][ T27] rwsem_down_write_slowpath+0x3e2/0x1220 [ 242.688861][ T27] ? down_timeout+0x90/0x90 [ 242.693563][ T27] ? lock_release+0x670/0x670 [ 242.698288][ T27] ? f2fs_write_inode+0x582/0xe00 [ 242.709423][ T27] ? f2fs_write_inode+0x582/0xe00 [ 242.719465][ T27] down_write+0x1d2/0x200 [ 242.723826][ T27] ? rwsem_down_write_slowpath+0x1220/0x1220 [ 242.744551][ T27] f2fs_balance_fs+0x54e/0x6c0 [ 242.749585][ T27] ? has_not_enough_free_secs.constprop.0+0x800/0x800 [ 242.756389][ T27] ? f2fs_update_inode+0xe58/0x2720 [ 242.761804][ T27] ? folio_flags.constprop.0+0x53/0x150 [ 242.767380][ T27] ? f2fs_put_page.constprop.0+0x1f9/0x690 [ 242.773455][ T27] f2fs_write_inode+0x582/0xe00 [ 242.778333][ T27] __writeback_single_inode+0x9f8/0xdc0 [ 242.784178][ T27] writeback_sb_inodes+0x54d/0xe70 [ 242.789434][ T27] ? sync_inode_metadata+0xe0/0xe0 [ 242.794583][ T27] __writeback_inodes_wb+0xc6/0x280 [ 242.800145][ T27] wb_writeback+0x7e9/0xa50 [ 242.804824][ T27] ? __writeback_inodes_wb+0x280/0x280 [ 242.810547][ T27] ? lock_downgrade+0x690/0x690 [ 242.815444][ T27] ? get_nr_inodes+0x50/0x1b0 [ 242.820785][ T27] ? get_nr_dirty_inodes+0x60/0x1d0 [ 242.826036][ T27] wb_workfn+0x765/0xfc0 [ 242.830579][ T27] ? inode_wait_for_writeback+0x40/0x40 [ 242.836190][ T27] ? lock_release+0x670/0x670 [ 242.841122][ T27] ? _raw_spin_unlock_irq+0x23/0x50 [ 242.846375][ T27] process_one_work+0x991/0x15c0 [ 242.851582][ T27] ? pwq_dec_nr_in_flight+0x2a0/0x2a0 [ 242.857010][ T27] ? spin_bug+0x1c0/0x1c0 [ 242.861598][ T27] ? _raw_spin_lock_irq+0x45/0x50 [ 242.866697][ T27] worker_thread+0x669/0x1090 [ 242.871661][ T27] ? __kthread_parkme+0x163/0x220 [ 242.876727][ T27] ? process_one_work+0x15c0/0x15c0 [ 242.882272][ T27] kthread+0x2e8/0x3a0 [ 242.886384][ T27] ? kthread_complete_and_exit+0x40/0x40 [ 242.892244][ T27] ret_from_fork+0x1f/0x30 [ 242.896690][ T27] [ 242.899930][ T27] [ 242.899930][ T27] Showing all locks held in the system: [ 242.907773][ T27] 3 locks held by kworker/u4:1/11: [ 242.913100][ T27] #0: ffff8880b983c298 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2f/0x120 [ 242.923253][ T27] #1: ffffc90000107da8 ((work_completion)(&(&kfence_timer)->work)){+.+.}-{0:0}, at: process_one_work+0x8ae/0x15c0 [ 242.936079][ T27] #2: ffffffff8c6409f0 (cpu_hotplug_lock){++++}-{0:0}, at: static_key_disable+0x12/0x20 [ 242.946215][ T27] 1 lock held by rcu_tasks_kthre/12: [ 242.959332][ T27] #0: ffffffff8c794b70 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x31/0xd80 [ 242.969982][ T27] 1 lock held by rcu_tasks_trace/13: [ 242.975269][ T27] #0: ffffffff8c794870 (rcu_tasks_trace.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x31/0xd80 [ 242.989333][ T27] 1 lock held by khungtaskd/27: [ 242.994723][ T27] #0: ffffffff8c7956c0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x55/0x340 [ 243.009358][ T27] 4 locks held by kworker/u4:6/3759: [ 243.014668][ T27] #0: ffff88801731c938 ((wq_completion)writeback){+.+.}-{0:0}, at: process_one_work+0x87a/0x15c0 [ 243.025704][ T27] #1: ffffc9000f017da8 ((work_completion)(&(&wb->dwork)->work)){+.+.}-{0:0}, at: process_one_work+0x8ae/0x15c0 [ 243.037763][ T27] #2: ffff8880758ec0e0 (&type->s_umount_key#50){.+.+}-{3:3}, at: trylock_super+0x21/0x110 [ 243.048415][ T27] #3: ffff88801ddb52a8 (&sbi->gc_lock){+.+.}-{3:3}, at: f2fs_balance_fs+0x54e/0x6c0 [ 243.058149][ T27] 1 lock held by syslogd/4423: [ 243.063152][ T27] 2 locks held by getty/4752: [ 243.067839][ T27] #0: ffff88802be94098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x26/0x80 [ 243.089341][ T27] #1: ffffc900015a02f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0xef4/0x13e0 [ 243.099696][ T27] 2 locks held by syz-executor.0/5646: [ 243.105162][ T27] 3 locks held by syz-executor.0/6726: [ 243.110779][ T27] [ 243.113242][ T27] ============================================= [ 243.113242][ T27] [ 243.129348][ T27] NMI backtrace for cpu 1 [ 243.133685][ T27] CPU: 1 PID: 27 Comm: khungtaskd Not tainted 6.3.0-rc3-syzkaller-00322-g4bdec23f971b-dirty #0 [ 243.144015][ T27] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/02/2023 [ 243.154220][ T27] Call Trace: [ 243.157505][ T27] [ 243.160437][ T27] dump_stack_lvl+0xd9/0x150 [ 243.165130][ T27] nmi_cpu_backtrace+0x29c/0x350 [ 243.170077][ T27] ? lapic_can_unplug_cpu+0xa0/0xa0 [ 243.175281][ T27] nmi_trigger_cpumask_backtrace+0x2a4/0x300 [ 243.181270][ T27] watchdog+0xe16/0x1090 [ 243.185516][ T27] ? proc_dohung_task_timeout_secs+0x80/0x80 [ 243.191501][ T27] kthread+0x2e8/0x3a0 [ 243.195563][ T27] ? kthread_complete_and_exit+0x40/0x40 [ 243.201189][ T27] ret_from_fork+0x1f/0x30 [ 243.205612][ T27] [ 243.208920][ T27] Sending NMI from CPU 1 to CPUs 0: [ 243.214166][ C0] NMI backtrace for cpu 0 [ 243.214174][ C0] CPU: 0 PID: 6728 Comm: syz-executor.0 Not tainted 6.3.0-rc3-syzkaller-00322-g4bdec23f971b-dirty #0 [ 243.214186][ C0] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/02/2023 [ 243.214192][ C0] RIP: 0033:0x7f29b98291cc [ 243.214202][ C0] Code: 01 c2 49 3b 55 08 77 56 8d 55 ff 85 ed 74 35 0f 1f 80 00 00 00 00 48 39 c1 77 1c 49 8b 75 00 49 89 c1 49 29 c9 46 0f b6 0c 0e <45> 84 c9 74 08 44 88 0c 06 49 8b 45 10 48 83 c0 01 49 89 45 10 83 [ 243.214212][ C0] RSP: 002b:00007f29ba575600 EFLAGS: 00000216 [ 243.214222][ C0] RAX: 00000000026526b0 RBX: 00007f29ba575660 RCX: 0000000000000001 [ 243.214229][ C0] RDX: 00000000000000ed RSI: 00007f29b07ff000 RDI: 00007f29ba575700 [ 243.214236][ C0] RBP: 0000000000000102 R08: 0000000000000005 R09: 0000000000000000 [ 243.214242][ C0] R10: 0000000000000000 R11: 00007f29ba575670 R12: 00007f29ba575670 [ 243.214249][ C0] R13: 00007f29ba575700 R14: 0000000000000001 R15: 0000000000000000 [ 243.214255][ C0] FS: 00007f29ba576700 GS: 0000000000000000 [ 243.404850][ T27] Kernel panic - not syncing: hung_task: blocked tasks [ 243.411725][ T27] CPU: 0 PID: 27 Comm: khungtaskd Not tainted 6.3.0-rc3-syzkaller-00322-g4bdec23f971b-dirty #0 [ 243.422133][ T27] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/02/2023 [ 243.432359][ T27] Call Trace: [ 243.435635][ T27] [ 243.438572][ T27] dump_stack_lvl+0xd9/0x150 [ 243.443170][ T27] panic+0x688/0x730 [ 243.447074][ T27] ? panic_smp_self_stop+0x90/0x90 [ 243.452194][ T27] ? lapic_can_unplug_cpu+0xa0/0xa0 [ 243.457393][ T27] ? preempt_schedule_thunk+0x1a/0x20 [ 243.462780][ T27] ? watchdog+0xbe8/0x1090 [ 243.467204][ T27] watchdog+0xbf9/0x1090 [ 243.471496][ T27] ? proc_dohung_task_timeout_secs+0x80/0x80 [ 243.477501][ T27] kthread+0x2e8/0x3a0 [ 243.481580][ T27] ? kthread_complete_and_exit+0x40/0x40 [ 243.487224][ T27] ret_from_fork+0x1f/0x30 [ 243.491666][ T27] [ 243.495645][ T27] Kernel Offset: disabled [ 243.499969][ T27] Rebooting in 86400 seconds..