Warning: Permanently added '10.128.1.172' (ED25519) to the list of known hosts. 2024/08/30 12:54:57 ignoring optional flag "sandboxArg"="0" 2024/08/30 12:54:58 parsed 1 programs [ 57.038192][ T3557] swapon: swapfile has holes [ 57.699420][ T3564] IPVS: ftp: loaded support on port[0] = 21 [ 57.788870][ T3571] IPVS: ftp: loaded support on port[0] = 21 [ 58.573143][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 58.580687][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 58.702514][ T3535] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 58.711083][ T3535] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 58.719267][ T3535] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 58.727298][ T3535] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 58.951748][ T3571] device veth0_vlan entered promiscuous mode [ 58.957901][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 58.966555][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 58.974858][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 58.982690][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 58.991432][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 58.998955][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 59.062377][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 59.070944][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 59.140389][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 59.148724][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 59.156909][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 59.165000][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 59.278228][ T3750] IPVS: ftp: loaded support on port[0] = 21 [ 59.319237][ T3755] IPVS: ftp: loaded support on port[0] = 21 [ 59.354705][ T3763] IPVS: ftp: loaded support on port[0] = 21 [ 59.400153][ T3770] IPVS: ftp: loaded support on port[0] = 21 [ 59.425802][ T3774] IPVS: ftp: loaded support on port[0] = 21 [ 59.443634][ T3777] IPVS: ftp: loaded support on port[0] = 21 2024/08/30 12:55:04 executed programs: 0 [ 59.474925][ T3784] IPVS: ftp: loaded support on port[0] = 21 [ 59.514006][ T361] device veth0_vlan left promiscuous mode [ 59.573710][ T3787] IPVS: ftp: loaded support on port[0] = 21 [ 59.903585][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 59.959423][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 59.968044][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 59.976143][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 59.984382][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 59.992433][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_hsr: link becomes ready [ 60.000306][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 60.008114][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_hsr: link becomes ready [ 60.016105][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 60.392134][ T3787] device veth0_vlan entered promiscuous mode [ 60.398370][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 60.406659][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 60.414839][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 60.422852][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 60.432686][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 60.440282][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 60.509572][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 60.518251][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 60.589025][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 60.597194][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 60.605538][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 60.613881][ T3495] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 60.986656][ T3535] usb 1-1: new high-speed USB device number 2 using dummy_hcd [ 61.262789][ T3535] usb 1-1: Using ep0 maxpacket: 16 [ 61.612830][ T3535] usb 1-1: New USB device found, idVendor=2137, idProduct=0001, bcdDevice=2a.35 [ 61.622026][ T3535] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 61.630086][ T3535] usb 1-1: Product: syz [ 61.634241][ T3535] usb 1-1: Manufacturer: syz [ 61.638815][ T3535] usb 1-1: SerialNumber: syz [ 61.673495][ T3535] usb 1-1: config 0 descriptor?? [ 61.715504][ T3535] as10x_usb: device has been detected [ 61.721092][ T3535] dvbdev: DVB: registering new adapter (Sky IT Digital Key (green led)) [ 61.746763][ T3535] usb 1-1: DVB: registering adapter 1 frontend 0 (Sky IT Digital Key (green led))... [ 61.763454][ T3535] as10x_usb: error during firmware upload part1 [ 61.769991][ T3535] Registered device Sky IT Digital Key (green led) [ 61.913938][ T3964] FAULT_INJECTION: forcing a failure. [ 61.913938][ T3964] name failslab, interval 1, probability 0, space 0, times 1 [ 61.933406][ T3964] CPU: 0 PID: 3964 Comm: syz.0.15 Not tainted 5.9.0-syzkaller #0 [ 61.941518][ T3964] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/06/2024 [ 61.951740][ T3964] Call Trace: [ 61.955001][ T3964] dump_stack+0x77/0x97 [ 61.959144][ T3964] should_fail.cold+0x32/0x37 [ 61.963792][ T3964] should_failslab+0x5/0x10 [ 61.968707][ T3964] kmem_cache_alloc_trace+0x52/0x460 [ 61.974071][ T3964] ? request_firmware_work_func+0x90/0x90 [ 61.979754][ T3964] async_schedule_node_domain+0x31/0x1e0 [ 61.985348][ T3964] dev_cache_fw_image+0x166/0x1a0 [ 61.990336][ T3964] ? dev_create_fw_entry+0x50/0x50 [ 61.995410][ T3964] dpm_for_each_dev+0x3e/0x70 [ 62.000051][ T3964] fw_pm_notify+0x5e/0xf0 [ 62.004371][ T3964] notifier_call_chain+0x5c/0x90 [ 62.009272][ T3964] blocking_notifier_call_chain_robust+0x63/0xc0 [ 62.015588][ T3964] pm_notifier_call_chain_robust+0x14/0x30 [ 62.021483][ T3964] snapshot_open+0x108/0x140 [ 62.026066][ T3964] misc_open+0x116/0x150 [ 62.030374][ T3964] chrdev_open+0xbe/0x250 [ 62.034698][ T3964] ? __unregister_chrdev+0x50/0x50 [ 62.039949][ T3964] do_dentry_open+0x15e/0x380 [ 62.044696][ T3964] path_openat+0x3a6/0xaa0 [ 62.049084][ T3964] ? __lock_acquire+0x3a0/0x19d0 [ 62.054073][ T3964] do_filp_open+0x83/0x130 [ 62.058731][ T3964] ? do_raw_spin_unlock+0x50/0xa0 [ 62.063827][ T3964] ? _raw_spin_unlock+0x1a/0x30 [ 62.068642][ T3964] ? __alloc_fd+0xe4/0x1d0 [ 62.073042][ T3964] do_sys_openat2+0x92/0x150 [ 62.077618][ T3964] __x64_sys_openat+0x4f/0x90 [ 62.082726][ T3964] do_syscall_64+0x34/0x50 [ 62.087145][ T3964] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 62.093341][ T3964] RIP: 0033:0x7f059e7c2ef9 [ 62.097731][ T3964] Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 a8 ff ff ff f7 d8 64 89 01 48 [ 62.117563][ T3964] RSP: 002b:00007f059e248038 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 [ 62.126001][ T3964] RAX: ffffffffffffffda RBX: 00007f059e95ef80 RCX: 00007f059e7c2ef9 [ 62.134223][ T3964] RDX: 0000000000000001 RSI: 00000000200000c0 RDI: ffffffffffffff9c [ 62.142170][ T3964] RBP: 00007f059e248090 R08: 0000000000000000 R09: 0000000000000000 [ 62.150120][ T3964] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000001 [ 62.158232][ T3964] R13: 0000000000000000 R14: 00007f059e95ef80 R15: 00007ffc1e3d5e98 [ 62.168339][ T3964] [ 62.170694][ T3964] ============================================ [ 62.177000][ T3964] WARNING: possible recursive locking detected [ 62.183154][ T3964] 5.9.0-syzkaller #0 Not tainted [ 62.188190][ T3964] -------------------------------------------- [ 62.194476][ T3964] syz.0.15/3964 is trying to acquire lock: [ 62.200477][ T3964] ffffffff836b9ba8 (fw_lock){+.+.}-{3:3}, at: assign_fw+0x24/0x210 [ 62.208439][ T3964] [ 62.208439][ T3964] but task is already holding lock: [ 62.215943][ T3964] ffffffff836b9ba8 (fw_lock){+.+.}-{3:3}, at: fw_pm_notify+0x46/0xf0 [ 62.223993][ T3964] [ 62.223993][ T3964] other info that might help us debug this: [ 62.232023][ T3964] Possible unsafe locking scenario: [ 62.232023][ T3964] [ 62.239431][ T3964] CPU0 [ 62.242671][ T3964] ---- [ 62.245913][ T3964] lock(fw_lock); [ 62.249611][ T3964] lock(fw_lock); [ 62.253298][ T3964] [ 62.253298][ T3964] *** DEADLOCK *** [ 62.253298][ T3964] [ 62.261401][ T3964] May be due to missing lock nesting notation [ 62.261401][ T3964] [ 62.269690][ T3964] 5 locks held by syz.0.15/3964: [ 62.274593][ T3964] #0: ffffffff836acc08 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x1f/0x150 [ 62.282984][ T3964] #1: ffffffff834e0488 (system_transition_mutex){+.+.}-{3:3}, at: snapshot_open+0x1c/0x140 [ 62.293104][ T3964] #2: ffffffff834e54f0 ((pm_chain_head).rwsem){++++}-{3:3}, at: blocking_notifier_call_chain_robust+0x43/0xc0 [ 62.304777][ T3964] #3: ffffffff836b9ba8 (fw_lock){+.+.}-{3:3}, at: fw_pm_notify+0x46/0xf0 [ 62.313329][ T3964] #4: ffffffff836b8fe8 (dpm_list_mtx){+.+.}-{3:3}, at: dpm_for_each_dev+0x1d/0x70 [ 62.322929][ T3964] [ 62.322929][ T3964] stack backtrace: [ 62.328957][ T3964] CPU: 1 PID: 3964 Comm: syz.0.15 Not tainted 5.9.0-syzkaller #0 [ 62.336650][ T3964] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/06/2024 [ 62.346676][ T3964] Call Trace: [ 62.349951][ T3964] dump_stack+0x77/0x97 [ 62.354075][ T3964] __lock_acquire.cold+0x124/0x2b2 [ 62.359232][ T3964] ? lockdep_hardirqs_on_prepare+0xcd/0xf0 [ 62.364996][ T3964] lock_acquire+0xbf/0x390 [ 62.369480][ T3964] ? assign_fw+0x24/0x210 [ 62.373784][ T3964] __mutex_lock+0x61/0x610 [ 62.378166][ T3964] ? assign_fw+0x24/0x210 [ 62.382542][ T3964] ? assign_fw+0x24/0x210 [ 62.386841][ T3964] ? rcu_read_lock_sched_held+0x3a/0x70 [ 62.392549][ T3964] ? kmem_cache_free+0x282/0x2b0 [ 62.397536][ T3964] assign_fw+0x24/0x210 [ 62.401651][ T3964] _request_firmware+0x42c/0x4f0 [ 62.406551][ T3964] __async_dev_cache_fw_image+0x44/0x150 [ 62.412228][ T3964] ? request_firmware_work_func+0x90/0x90 [ 62.417922][ T3964] async_schedule_node_domain+0x86/0x1e0 [ 62.423611][ T3964] dev_cache_fw_image+0x166/0x1a0 [ 62.428594][ T3964] ? dev_create_fw_entry+0x50/0x50 [ 62.433662][ T3964] dpm_for_each_dev+0x3e/0x70 [ 62.438303][ T3964] fw_pm_notify+0x5e/0xf0 [ 62.442592][ T3964] notifier_call_chain+0x5c/0x90 [ 62.447496][ T3964] blocking_notifier_call_chain_robust+0x63/0xc0 [ 62.453783][ T3964] pm_notifier_call_chain_robust+0x14/0x30 [ 62.459551][ T3964] snapshot_open+0x108/0x140 [ 62.464099][ T3964] misc_open+0x116/0x150 [ 62.468300][ T3964] chrdev_open+0xbe/0x250 [ 62.472662][ T3964] ? __unregister_chrdev+0x50/0x50 [ 62.477745][ T3964] do_dentry_open+0x15e/0x380 [ 62.482403][ T3964] path_openat+0x3a6/0xaa0 [ 62.486788][ T3964] ? __lock_acquire+0x3a0/0x19d0 [ 62.491698][ T3964] do_filp_open+0x83/0x130 [ 62.496162][ T3964] ? do_raw_spin_unlock+0x50/0xa0 [ 62.501152][ T3964] ? _raw_spin_unlock+0x1a/0x30 [ 62.505984][ T3964] ? __alloc_fd+0xe4/0x1d0 [ 62.510458][ T3964] do_sys_openat2+0x92/0x150 [ 62.515003][ T3964] __x64_sys_openat+0x4f/0x90 [ 62.519642][ T3964] do_syscall_64+0x34/0x50 [ 62.524022][ T3964] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 62.529882][ T3964] RIP: 0033:0x7f059e7c2ef9 [ 62.534288][ T3964] Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 a8 ff ff ff f7 d8 64 89 01 48 [ 62.554149][ T3964] RSP: 002b:00007f059e248038 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 [ 62.562878][ T3964] RAX: ffffffffffffffda RBX: 00007f059e95ef80 RCX: 00007f059e7c2ef9 [ 62.570843][ T3964] RDX: 0000000000000001 RSI: 00000000200000c0 RDI: ffffffffffffff9c [ 62.578804][ T3964] RBP: 00007f059e248090 R08: 0000000000000000 R09: 0000000000000000 [ 62.586873][ T3964] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000001 [ 62.594947][ T3964] R13: 0000000000000000 R14: 00007f059e95ef80 R15: 00007ffc1e3d5e98 [ 67.411375][ T3022] net eth1: Unexpected TXQ (0) queue failure: -12 [ 70.730334][ T3975] IPVS: ftp: loaded support on port[0] = 21