Warning: Permanently added '[localhost]:37258' (ED25519) to the list of known hosts. 1970/01/01 00:08:04 ignoring optional flag "type"="qemu" 1970/01/01 00:08:11 parsed 1 programs [ 492.960991][ T30] audit: type=1400 audit(492.050:88): avc: denied { unlink } for pid=3521 comm="syz-executor" name="swap-file" dev="vda" ino=1874 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:unlabeled_t tclass=file permissive=1 trawcon="root:object_r:swapfile_t" [ 493.767820][ T3521] Adding 124996k swap on ./swap-file. Priority:0 extents:1 across:124996k 1970/01/01 00:08:13 executed programs: 0 [ 494.256761][ T30] audit: type=1400 audit(493.350:89): avc: denied { mounton } for pid=3527 comm="syz-executor.0" path="/sys/fs/fuse/connections" dev="fusectl" ino=1 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:fusefs_t tclass=dir permissive=1 [ 502.470326][ T3527] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 502.513653][ T3527] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 506.142334][ T3527] hsr_slave_0: entered promiscuous mode [ 506.162857][ T3527] hsr_slave_1: entered promiscuous mode [ 509.698749][ T3527] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 509.741716][ T3527] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 509.778540][ T3527] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 509.821225][ T3527] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 513.472411][ T3527] 8021q: adding VLAN 0 to HW filter on device bond0 [ 529.119017][ T3527] veth0_vlan: entered promiscuous mode [ 529.303857][ T3527] veth1_vlan: entered promiscuous mode [ 529.999482][ T3527] veth0_macvtap: entered promiscuous mode [ 530.128897][ T3527] veth1_macvtap: entered promiscuous mode [ 530.917048][ T26] netdevsim netdevsim0 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 530.922911][ T26] netdevsim netdevsim0 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 530.926986][ T26] netdevsim netdevsim0 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 530.931116][ T26] netdevsim netdevsim0 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 533.096848][ T3641] nbd0: detected capacity change from 0 to 262 [ 535.123503][ T51] block nbd0: Receive control failed (result -104) [ 563.956071][ T24] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 30 seconds [ 594.030365][ T24] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 60 seconds [ 594.705743][ T3136] udevd[3136]: worker [3644] /devices/virtual/block/nbd0 is taking a long time 1970/01/01 00:10:11 executed programs: 1 [ 621.900812][ T3672] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 621.960860][ T3672] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 624.097422][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 90 seconds [ 625.691697][ T3672] hsr_slave_0: entered promiscuous mode [ 625.700119][ T3672] hsr_slave_1: entered promiscuous mode [ 625.710468][ T3672] debugfs: 'hsr0' already exists in 'hsr' [ 625.712358][ T3672] Cannot create hsr debugfs directory [ 627.171756][ T3672] netdevsim netdevsim0 netdevsim3 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 627.512651][ T3672] netdevsim netdevsim0 netdevsim2 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 627.789396][ T3672] netdevsim netdevsim0 netdevsim1 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 628.178951][ T3672] netdevsim netdevsim0 netdevsim0 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 629.342970][ T3672] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 629.441258][ T3672] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 629.537398][ T3672] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 629.601840][ T3672] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 632.260781][ T3672] 8021q: adding VLAN 0 to HW filter on device bond0 [ 645.319237][ T3672] veth0_vlan: entered promiscuous mode [ 645.503555][ T3672] veth1_vlan: entered promiscuous mode [ 646.079796][ T3672] veth0_macvtap: entered promiscuous mode [ 646.147425][ T3672] veth1_macvtap: entered promiscuous mode [ 646.795367][ T99] netdevsim netdevsim0 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 646.796906][ T41] netdevsim netdevsim0 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 646.797632][ T41] netdevsim netdevsim0 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 646.798945][ T41] netdevsim netdevsim0 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 654.195733][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 120 seconds [ 684.267924][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 150 seconds [ 714.347694][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 180 seconds [ 715.707470][ T3136] udevd[3136]: worker [3644] /devices/virtual/block/nbd0 timeout; kill it [ 715.712757][ T3136] udevd[3136]: seq 6531 '/devices/virtual/block/nbd0' killed 1970/01/01 00:12:06 executed programs: 2 [ 741.102301][ T3750] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 741.161651][ T3750] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 744.447292][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 210 seconds [ 747.719563][ T3750] hsr_slave_0: entered promiscuous mode [ 747.731417][ T3750] hsr_slave_1: entered promiscuous mode [ 747.756007][ T3750] debugfs: 'hsr0' already exists in 'hsr' [ 747.757734][ T3750] Cannot create hsr debugfs directory [ 750.516417][ T3750] netdevsim netdevsim0 netdevsim3 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 750.933319][ T3750] netdevsim netdevsim0 netdevsim2 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 751.452261][ T3750] netdevsim netdevsim0 netdevsim1 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 751.951815][ T3750] netdevsim netdevsim0 netdevsim0 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 753.891506][ T3750] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 753.977957][ T3750] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 754.050706][ T3750] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 754.139855][ T3750] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 759.238004][ T3750] 8021q: adding VLAN 0 to HW filter on device bond0 [ 774.509137][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 240 seconds [ 779.003939][ T3750] veth0_vlan: entered promiscuous mode [ 779.073631][ T3750] veth1_vlan: entered promiscuous mode [ 779.507657][ T3750] veth0_macvtap: entered promiscuous mode [ 779.586968][ T3750] veth1_macvtap: entered promiscuous mode [ 780.240969][ T3743] netdevsim netdevsim0 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 780.311089][ T3743] netdevsim netdevsim0 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 780.319646][ T3743] netdevsim netdevsim0 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 780.320036][ T3743] netdevsim netdevsim0 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 804.581445][ T24] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 270 seconds [ 834.670172][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 300 seconds 1970/01/01 00:14:20 executed programs: 3 [ 864.757382][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 330 seconds [ 874.779999][ T3832] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 874.869049][ T3832] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 880.773780][ T3832] hsr_slave_0: entered promiscuous mode [ 880.791606][ T3832] hsr_slave_1: entered promiscuous mode [ 880.802695][ T3832] debugfs: 'hsr0' already exists in 'hsr' [ 880.806951][ T3832] Cannot create hsr debugfs directory [ 883.197360][ T3832] netdevsim netdevsim0 netdevsim3 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 883.656807][ T3832] netdevsim netdevsim0 netdevsim2 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 884.038112][ T3832] netdevsim netdevsim0 netdevsim1 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 884.407834][ T3832] netdevsim netdevsim0 netdevsim0 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 885.832093][ T3832] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 885.932726][ T3832] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 886.039207][ T3832] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 886.098962][ T3832] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 890.591470][ T3832] 8021q: adding VLAN 0 to HW filter on device bond0 [ 894.852389][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 360 seconds [ 909.587287][ T3832] veth0_vlan: entered promiscuous mode [ 909.706404][ T3832] veth1_vlan: entered promiscuous mode [ 910.149106][ T3832] veth0_macvtap: entered promiscuous mode [ 910.358716][ T3832] veth1_macvtap: entered promiscuous mode [ 911.321015][ T26] netdevsim netdevsim0 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 911.328387][ T26] netdevsim netdevsim0 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 911.328837][ T26] netdevsim netdevsim0 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 911.329201][ T26] netdevsim netdevsim0 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 924.914686][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 390 seconds [ 954.980704][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 420 seconds [ 967.792551][ T33] INFO: task syz-executor.0:3641 blocked for more than 430 seconds. [ 967.793968][ T33] Not tainted syzkaller #0 [ 967.797815][ T33] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 967.799308][ T33] task:syz-executor.0 state:D stack:0 pid:3641 tgid:3639 ppid:3527 task_flags:0x400140 flags:0x00000011 [ 967.800843][ T33] Call trace: [ 967.801151][ T33] __switch_to+0x208/0x4f0 (T) [ 967.805704][ T33] __schedule+0xcfc/0x2fec [ 967.805846][ T33] schedule+0xd0/0x344 [ 967.805968][ T33] schedule_preempt_disabled+0x18/0x2c [ 967.806136][ T33] __mutex_lock+0x72c/0xffc [ 967.806254][ T33] mutex_lock_nested+0x24/0x30 [ 967.806428][ T33] bdev_release+0x104/0x510 [ 967.806557][ T33] blkdev_release+0x14/0x24 [ 967.806676][ T33] __fput+0x2c8/0x954 [ 967.807979][ T33] ____fput+0x14/0x20 [ 967.808118][ T33] task_work_run+0x128/0x210 [ 967.808244][ T33] exit_to_user_mode_loop+0x194/0x1c8 [ 967.808369][ T33] el0_svc+0x26c/0x2b0 [ 967.808480][ T33] el0t_64_sync_handler+0xa0/0xe4 [ 967.808591][ T33] el0t_64_sync+0x198/0x19c [ 967.809271][ T33] [ 967.809271][ T33] Showing all locks held in the system: [ 967.809634][ T33] 1 lock held by pr/ttyAMA-1/32: [ 967.810315][ T33] 1 lock held by khungtaskd/33: [ 967.810439][ T33] #0: ffff800087562ae0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x18/0x1c4 [ 967.811522][ T33] 2 locks held by klogd/3125: [ 967.811657][ T33] 2 locks held by getty/3259: [ 967.811756][ T33] #0: ffff00001922a0a0 (&tty->ldisc_sem){++++}-{0:0}, at: ldsem_down_read+0x3c/0x4c [ 967.812293][ T33] #1: ffff80008d8fb2f0 (&ldata->atomic_read_lock){+.+.}-{4:4}, at: n_tty_read+0x3e8/0xdd0 [ 967.812737][ T33] 1 lock held by syz-executor.0/3641: [ 967.812819][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_release+0x104/0x510 [ 967.813275][ T33] 1 lock held by udevd/3644: [ 967.813355][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_open+0x2c4/0xa84 [ 967.813877][ T33] 1 lock held by syz-executor.0/3745: [ 967.814709][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_open+0x2c4/0xa84 [ 967.815206][ T33] 1 lock held by syz-executor.0/3828: [ 967.815283][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_open+0x2c4/0xa84 [ 967.815685][ T33] 1 lock held by syz-executor.0/3904: [ 967.815771][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_open+0x2c4/0xa84 [ 967.816550][ T33] [ 967.816685][ T33] ============================================= [ 967.816685][ T33] [ 985.076187][ T2044] block nbd0: Possible stuck request 0000000031d7c2aa: control (read@0,4096B). Runtime 450 seconds [ 988.263051][ T33] INFO: task syz-executor.0:3641 blocked for more than 450 seconds. [ 988.263646][ T33] Not tainted syzkaller #0 [ 988.269313][ T33] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 988.269921][ T33] task:syz-executor.0 state:D stack:0 pid:3641 tgid:3639 ppid:3527 task_flags:0x400140 flags:0x00000011 [ 988.270323][ T33] Call trace: [ 988.270392][ T33] __switch_to+0x208/0x4f0 (T) [ 988.270813][ T33] __schedule+0xcfc/0x2fec [ 988.270958][ T33] schedule+0xd0/0x344 [ 988.271218][ T33] schedule_preempt_disabled+0x18/0x2c [ 988.271409][ T33] __mutex_lock+0x72c/0xffc [ 988.271585][ T33] mutex_lock_nested+0x24/0x30 [ 988.272921][ T33] bdev_release+0x104/0x510 [ 988.275386][ T33] blkdev_release+0x14/0x24 [ 988.299162][ T33] __fput+0x2c8/0x954 [ 988.303262][ T33] ____fput+0x14/0x20 [ 988.307247][ T33] task_work_run+0x128/0x210 [ 988.308156][ T33] exit_to_user_mode_loop+0x194/0x1c8 [ 988.308461][ T33] el0_svc+0x26c/0x2b0 [ 988.308672][ T33] el0t_64_sync_handler+0xa0/0xe4 [ 988.308808][ T33] el0t_64_sync+0x198/0x19c [ 988.309527][ T33] [ 988.309527][ T33] Showing all locks held in the system: [ 988.309661][ T33] 2 locks held by pr/ttyAMA-1/32: [ 988.309770][ T33] 1 lock held by khungtaskd/33: [ 988.309882][ T33] #0: ffff800087562ae0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x18/0x1c4 [ 988.310625][ T33] 2 locks held by getty/3259: [ 988.310722][ T33] #0: ffff00001922a0a0 (&tty->ldisc_sem){++++}-{0:0}, at: ldsem_down_read+0x3c/0x4c [ 988.311221][ T33] #1: ffff80008d8fb2f0 (&ldata->atomic_read_lock){+.+.}-{4:4}, at: n_tty_read+0x3e8/0xdd0 [ 988.311731][ T33] 1 lock held by syz-executor.0/3641: [ 988.311822][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_release+0x104/0x510 [ 988.312505][ T33] 1 lock held by udevd/3644: [ 988.312592][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_open+0x2c4/0xa84 [ 988.313058][ T33] 1 lock held by syz-executor.0/3745: [ 988.313205][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_open+0x2c4/0xa84 [ 988.313737][ T33] 1 lock held by syz-executor.0/3828: [ 988.313827][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_open+0x2c4/0xa84 [ 988.341688][ T33] 1 lock held by syz-executor.0/3904: [ 988.341802][ T33] #0: ffff000015371358 (&disk->open_mutex){+.+.}-{4:4}, at: bdev_open+0x2c4/0xa84 [ 988.342422][ T33] [ 988.342570][ T33] ============================================= [ 988.342570][ T33] 1970/01/01 00:16:31 executed programs: 4