Warning: Permanently added '[localhost]:53785' (ECDSA) to the list of known hosts.
2022/09/14 11:56:13 fuzzer started
2022/09/14 11:56:13 dialing manager at localhost:33849
syzkaller login: [ 36.181376] cgroup: Unknown subsys name 'net'
[ 36.266308] cgroup: Unknown subsys name 'rlimit'
2022/09/14 11:56:28 syscalls: 2215
2022/09/14 11:56:28 code coverage: enabled
2022/09/14 11:56:28 comparison tracing: enabled
2022/09/14 11:56:28 extra coverage: enabled
2022/09/14 11:56:28 setuid sandbox: enabled
2022/09/14 11:56:28 namespace sandbox: enabled
2022/09/14 11:56:28 Android sandbox: enabled
2022/09/14 11:56:28 fault injection: enabled
2022/09/14 11:56:28 leak checking: enabled
2022/09/14 11:56:28 net packet injection: enabled
2022/09/14 11:56:28 net device setup: enabled
2022/09/14 11:56:28 concurrency sanitizer: /sys/kernel/debug/kcsan does not exist
2022/09/14 11:56:28 devlink PCI setup: PCI device 0000:00:10.0 is not available
2022/09/14 11:56:28 USB emulation: enabled
2022/09/14 11:56:28 hci packet injection: enabled
2022/09/14 11:56:28 wifi device emulation: failed to parse kernel version (6.0.0-rc5-next-20220914 )
2022/09/14 11:56:28 802.15.4 emulation: enabled
2022/09/14 11:56:28 fetching corpus: 0, signal 0/2000 (executing program)
2022/09/14 11:56:28 fetching corpus: 50, signal 33242/36524 (executing program)
2022/09/14 11:56:29 fetching corpus: 100, signal 53508/57793 (executing program)
2022/09/14 11:56:29 fetching corpus: 150, signal 60844/66140 (executing program)
2022/09/14 11:56:29 fetching corpus: 200, signal 68505/74708 (executing program)
2022/09/14 11:56:29 fetching corpus: 250, signal 73916/81014 (executing program)
2022/09/14 11:56:29 fetching corpus: 300, signal 78264/86190 (executing program)
2022/09/14 11:56:29 fetching corpus: 350, signal 84518/92998 (executing program)
2022/09/14 11:56:30 fetching corpus: 400, signal 88884/98015 (executing program)
2022/09/14 11:56:30 fetching corpus: 450, signal 92089/101895 (executing program)
2022/09/14 11:56:30 fetching corpus: 500, signal 97486/107595 (executing program)
2022/09/14 11:56:30 fetching corpus: 550, signal 99342/110074 (executing program)
2022/09/14 11:56:30 fetching corpus: 600, signal 101884/113191 (executing program)
2022/09/14 11:56:30 fetching corpus: 650, signal 104283/116094 (executing program)
2022/09/14 11:56:30 fetching corpus: 700, signal 106408/118684 (executing program)
2022/09/14 11:56:31 fetching corpus: 750, signal 109250/121779 (executing program)
2022/09/14 11:56:31 fetching corpus: 800, signal 111436/124227 (executing program)
2022/09/14 11:56:31 fetching corpus: 850, signal 114107/127126 (executing program)
2022/09/14 11:56:31 fetching corpus: 900, signal 116634/129781 (executing program)
2022/09/14 11:56:31 fetching corpus: 950, signal 119563/132723 (executing program)
2022/09/14 11:56:31 fetching corpus: 1000, signal 121427/134814 (executing program)
2022/09/14 11:56:31 fetching corpus: 1050, signal 124080/137398 (executing program)
2022/09/14 11:56:32 fetching corpus: 1100, signal 126837/139994 (executing program)
2022/09/14 11:56:32 fetching corpus: 1150, signal 128701/141915 (executing program)
2022/09/14 11:56:32 fetching corpus: 1200, signal 131303/144274 (executing program)
2022/09/14 11:56:32 fetching corpus: 1250, signal 133252/146083 (executing program)
2022/09/14 11:56:32 fetching corpus: 1300, signal 135006/147720 (executing program)
2022/09/14 11:56:32 fetching corpus: 1350, signal 136357/149038 (executing program)
2022/09/14 11:56:33 fetching corpus: 1400, signal 138094/150664 (executing program)
2022/09/14 11:56:33 fetching corpus: 1450, signal 139190/151840 (executing program)
2022/09/14 11:56:33 fetching corpus: 1500, signal 140444/153039 (executing program)
2022/09/14 11:56:33 fetching corpus: 1550, signal 142123/154513 (executing program)
2022/09/14 11:56:33 fetching corpus: 1600, signal 144832/156524 (executing program)
2022/09/14 11:56:33 fetching corpus: 1650, signal 146316/157734 (executing program)
2022/09/14 11:56:33 fetching corpus: 1700, signal 147640/158943 (executing program)
2022/09/14 11:56:34 fetching corpus: 1750, signal 148607/159828 (executing program)
2022/09/14 11:56:34 fetching corpus: 1800, signal 149906/160939 (executing program)
2022/09/14 11:56:34 fetching corpus: 1850, signal 151898/162350 (executing program)
2022/09/14 11:56:34 fetching corpus: 1900, signal 153151/163242 (executing program)
2022/09/14 11:56:34 fetching corpus: 1950, signal 154818/164410 (executing program)
2022/09/14 11:56:34 fetching corpus: 2000, signal 156423/165448 (executing program)
2022/09/14 11:56:35 fetching corpus: 2050, signal 157302/166155 (executing program)
2022/09/14 11:56:35 fetching corpus: 2100, signal 158308/166814 (executing program)
2022/09/14 11:56:35 fetching corpus: 2150, signal 160082/167853 (executing program)
2022/09/14 11:56:35 fetching corpus: 2200, signal 161123/168684 (executing program)
2022/09/14 11:56:35 fetching corpus: 2250, signal 162637/169559 (executing program)
2022/09/14 11:56:35 fetching corpus: 2300, signal 164300/170453 (executing program)
2022/09/14 11:56:35 fetching corpus: 2350, signal 165515/171109 (executing program)
2022/09/14 11:56:36 fetching corpus: 2400, signal 166685/171827 (executing program)
2022/09/14 11:56:36 fetching corpus: 2450, signal 167602/172318 (executing program)
2022/09/14 11:56:36 fetching corpus: 2500, signal 168247/172658 (executing program)
2022/09/14 11:56:36 fetching corpus: 2550, signal 169084/173071 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/173404 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/173483 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/173552 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/173616 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/173676 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/173741 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/173801 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/173868 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/173943 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174008 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174075 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174135 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174226 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174289 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174346 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174427 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174506 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174581 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174642 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174711 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174786 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174851 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174908 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/174968 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/175036 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/175096 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/175159 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/175213 (executing program)
2022/09/14 11:56:36 fetching corpus: 2594, signal 169782/175213 (executing program)
2022/09/14 11:56:38 starting 8 fuzzer processes
11:56:38 executing program 0:
r0 = openat(0xffffffffffffff9c, &(0x7f0000000100)='./file1\x00', 0x4042, 0x0)
r1 = openat(0xffffffffffffff9c, &(0x7f0000000100)='./file1\x00', 0x101042, 0x0)
perf_event_open(&(0x7f0000000080)={0x2, 0x80, 0xc2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @perf_config_ext}, 0x0, 0xffffffffffffffff, 0xffffffffffffffff, 0x0)
pwritev(r1, &(0x7f0000000080)=[{&(0x7f0000000140)='\x00', 0x1a}], 0x1, 0x7fffffc, 0x0)
perf_event_open(&(0x7f0000000280)={0x1, 0x80, 0x0, 0x0, 0x0, 0x0, 0x0, 0x2, 0x0, 0xc, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x4, @perf_config_ext={0xfffffffffffffffe}, 0x0, 0x0, 0x2}, 0x0, 0xffffffffffffffff, 0xffffffffffffffff, 0x0)
sendfile(r0, r0, 0x0, 0x100000)
r2 = openat$bsg(0xffffffffffffff9c, &(0x7f0000000180), 0x2000, 0x0)
syz_genetlink_get_family_id$ethtool(&(0x7f0000000040), r2)
fcntl$dupfd(0xffffffffffffffff, 0x406, 0xffffffffffffffff)
setsockopt$inet6_IPV6_PKTINFO(0xffffffffffffffff, 0x29, 0x1b, &(0x7f0000000000)={@dev}, 0x14)
getsockname$packet(r2, &(0x7f00000001c0)={0x11, 0x0, 0x0, 0x1, 0x0, 0x6, @dev}, &(0x7f0000000200)=0x14)
ioctl$sock_ipv6_tunnel_SIOCCHG6RD(r1, 0x89fb, &(0x7f0000000240)={'ip6gre0\x00', &(0x7f0000000300)={'syztnl2\x00', 0x0, 0x4, 0xfe, 0x40, 0x5, 0x32, @ipv4={'\x00', '\xff\xff', @local}, @initdev={0xfe, 0x88, '\x00', 0x2, 0x0}, 0x7, 0x7800, 0x10001, 0x1}})
getsockopt$inet_pktinfo(0xffffffffffffffff, 0x0, 0x8, &(0x7f0000000380)={0x0, @private, @local}, &(0x7f00000003c0)=0xc)
socket$inet6_udp(0xa, 0x2, 0x0)
socket$nl_audit(0x10, 0x3, 0x9)
setsockopt$inet6_IPV6_PKTINFO(0xffffffffffffffff, 0x29, 0x1b, &(0x7f0000000000)={@dev={0xfe, 0x80, '\x00', 0xfd}}, 0x14)
r3 = perf_event_open(&(0x7f0000000080)={0x2, 0x80, 0xc2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @perf_config_ext={0x0, 0x2080000001}, 0x0, 0x7ff}, 0x0, 0xffffffffffffffff, 0xffffffffffffffff, 0x0)
finit_module(r3, &(0x7f0000000140)='/dev/loop-control\x00', 0x3)
perf_event_open(&(0x7f0000000400)={0x3, 0x80, 0x6, 0x5, 0x1, 0x3f, 0x0, 0x3ff, 0x50000, 0x4, 0x1, 0x0, 0x1, 0x0, 0x0, 0x0, 0x1, 0x1, 0x1, 0x0, 0x1, 0x0, 0x1, 0x0, 0x0, 0x2, 0x0, 0x1, 0x1, 0x0, 0x1, 0x0, 0x1, 0x1, 0x1, 0x1, 0x1, 0x1, 0x1, 0x1, 0x1, 0x1, 0x1, 0x0, 0x0, 0x0, 0x1, 0x0, 0x3b, 0x1, @perf_config_ext={0x1, 0x7fff}, 0xa8, 0x1, 0x20, 0x0, 0x0, 0x7fffffff, 0x9, 0x0, 0x4, 0x0, 0xdeb}, 0x0, 0x3, r3, 0x3)
[ 61.033088] audit: type=1400 audit(1663156598.601:6): avc: denied { execmem } for pid=283 comm="syz-executor.0" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=process permissive=1
11:56:38 executing program 1:
r0 = syz_init_net_socket$bt_l2cap(0x1f, 0x3, 0x0)
bind$bt_l2cap(r0, &(0x7f0000000040)={0x1f, 0x0, @none}, 0xe)
connect$bt_l2cap(r0, &(0x7f0000000000)={0x1f, 0x0, @none}, 0xe)
11:56:38 executing program 2:
r0 = openat$tun(0xffffffffffffff9c, &(0x7f0000000140), 0x0, 0x0)
readv(r0, &(0x7f00000006c0)=[{&(0x7f00000001c0)=""/56, 0x38}], 0x1)
11:56:38 executing program 3:
syz_emit_vhci(&(0x7f0000000280)=ANY=[@ANYBLOB="02c90010000c0005"], 0x15)
11:56:38 executing program 4:
r0 = socket$inet6_udplite(0xa, 0x2, 0x88)
ioctl$sock_ipv6_tunnel_SIOCADDTUNNEL(r0, 0x89f1, &(0x7f0000000080)={'sit0\x00', &(0x7f0000000000)={'sit0\x00', 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @empty, @ipv4={'\x00', '\xff\xff', @multicast2}}})
11:56:38 executing program 5:
perf_event_open(&(0x7f0000000080)={0x2, 0x80, 0xc2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @perf_config_ext}, 0x0, 0x0, 0xffffffffffffffff, 0x0)
syz_genetlink_get_family_id$ethtool(0x0, 0xffffffffffffffff)
getpgid(0x0)
r0 = perf_event_open(&(0x7f0000000280)={0x1, 0x80, 0x0, 0x0, 0x0, 0x0, 0x0, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @perf_bp={0x0}}, 0x0, 0x0, 0xffffffffffffffff, 0x0)
syz_genetlink_get_family_id$ethtool(0x0, 0xffffffffffffffff)
perf_event_open(&(0x7f0000000100)={0x4, 0x80, 0x7, 0x0, 0xc0, 0x1, 0x0, 0x9, 0x10020, 0xa, 0x0, 0x1, 0x1, 0x1, 0x0, 0x0, 0x0, 0x1, 0x1, 0x1, 0x1, 0x1, 0x0, 0x1, 0x1, 0x0, 0x1, 0x0, 0x1, 0x1, 0x1, 0x0, 0x0, 0x1, 0x1, 0x1, 0x1, 0x0, 0x1, 0x1, 0x0, 0x0, 0x1, 0x0, 0x1, 0x0, 0x0, 0x0, 0x0, 0x2, @perf_config_ext={0x2, 0xffffffff}, 0x8a0, 0x1, 0x4, 0x8, 0x1, 0x7fff, 0x8, 0x0, 0x30c, 0x0, 0x81}, 0x0, 0x1, r0, 0x1)
socket$netlink(0x10, 0x3, 0x0)
11:56:38 executing program 6:
syz_mount_image$vfat(&(0x7f0000000000), &(0x7f0000000100)='./file0\x00', 0x80000, 0x8, &(0x7f0000000200)=[{&(0x7f0000010000)="eb3c906d6b66732e66617400028001000270000004f80100200040000000000000000000800029561eadf153595a4b414c4c4552202046415431362020200e1fbe5b7cac22c0740b56b40ebb0700cd105eebf032e4cd16cd19ebfe54686973206973206e6f74206120626f6f7461626c65206469736b2e2020506c6561736520696e73657274206120626f6f7461626c6520666c6f70707920616e640d0a707265737320616e79206b657920746f2074727920616761696e202e2e2e200d0a00", 0xc0}, {&(0x7f0000010100)="00000000000000000000000000000000000000000000000000000000000055aaf8fffffff0ffffffffffffff00"/64, 0x40, 0x1e0}, {&(0x7f0000010200)="f8fffffff0ffffffffffffff00"/32, 0x20, 0x400}, {&(0x7f0000010300)="53595a4b414c4c45522020080000eb80325132510000eb80325100000000000041660069006c00650030000f00fc0000ffffffffffffffffffff0000ffffffff46494c453020202020202010007deb70325132510000eb70325103000000000041660069006c00650031000f00100000ffffffffffffffffffff0000ffffffff46494c453120202020202020007deb70325132510000eb70325105000a00000041660069006c00650032000f00140000ffffffffffffffffffff0000ffffffff46494c453220202020202020007deb70325132510000eb70325106002823000041660069006c0065002e000f00d263006f006c0064000000ffff0000ffffffff46494c457e312020434f4c20007deb70325132510000eb703251070064000000", 0x120, 0x600}, {&(0x7f0000010500)="2e2020202020202020202010007deb70325132510000eb7032510300000000002e2e20202020202020202010007deb70325132510000eb70325100000000000041660069006c00650030000f00fc0000ffffffffffffffffffff0000ffffffff46494c453020202020202020007deb70325132510000eb70325104001a040000", 0x80, 0x11400}, {&(0x7f0000010600)='syzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkal\x00\x00\x00\x00\x00\x00', 0x420, 0x21400}, {&(0x7f0000010b00)='syzkallers\x00'/32, 0x20, 0x31400}, {&(0x7f0000010c00)='syzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallersyzkallers\x00'/128, 0x80, 0x51400}], 0x0, &(0x7f0000010d00))
11:56:38 executing program 7:
r0 = openat(0xffffffffffffff9c, &(0x7f0000000100)='./file1\x00', 0x4042, 0x0)
r1 = openat(0xffffffffffffff9c, &(0x7f0000000100)='./file1\x00', 0x101042, 0x0)
r2 = openat(0xffffffffffffff9c, &(0x7f0000000100)='./file1\x00', 0x0, 0x0)
ioctl$FS_IOC_SETFLAGS(r2, 0x40086602, &(0x7f0000000040))
pwritev(r1, &(0x7f0000000080)=[{&(0x7f0000000140)='\x00', 0x1a}], 0x1, 0x7fffffc, 0x0)
r3 = openat(0xffffffffffffff9c, &(0x7f0000000000)='./file1\x00', 0x4042, 0x0)
r4 = openat(0xffffffffffffff9c, &(0x7f0000000100)='./file1\x00', 0x0, 0x0)
sendfile(r3, r4, 0x0, 0x7fffffff)
sendto(0xffffffffffffffff, 0x0, 0x0, 0x0, 0x0, 0x0)
ioctl$EXT4_IOC_CLEAR_ES_CACHE(0xffffffffffffffff, 0x6628)
sendfile(r0, r0, 0x0, 0x100000)
[ 62.232853] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1
[ 62.235962] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9
[ 62.237640] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9
[ 62.241199] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4
[ 62.243932] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3
[ 62.245281] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2
[ 62.251021] Bluetooth: hci0: HCI_REQ-0x0c1a
[ 62.288181] Bluetooth: hci2: unexpected cc 0x0c03 length: 249 > 1
[ 62.290079] Bluetooth: hci3: unexpected cc 0x0c03 length: 249 > 1
[ 62.291548] Bluetooth: hci1: unexpected cc 0x0c03 length: 249 > 1
[ 62.293991] Bluetooth: hci3: unexpected cc 0x1003 length: 249 > 9
[ 62.296699] Bluetooth: hci1: unexpected cc 0x1003 length: 249 > 9
[ 62.304549] Bluetooth: hci3: unexpected cc 0x1001 length: 249 > 9
[ 62.306101] Bluetooth: hci2: unexpected cc 0x1003 length: 249 > 9
[ 62.309749] Bluetooth: hci1: unexpected cc 0x1001 length: 249 > 9
[ 62.310977] Bluetooth: hci2: unexpected cc 0x1001 length: 249 > 9
[ 62.313927] Bluetooth: hci3: unexpected cc 0x0c23 length: 249 > 4
[ 62.317266] Bluetooth: hci1: unexpected cc 0x0c23 length: 249 > 4
[ 62.319427] Bluetooth: hci2: unexpected cc 0x0c23 length: 249 > 4
[ 62.321068] Bluetooth: hci1: unexpected cc 0x0c25 length: 249 > 3
[ 62.322962] Bluetooth: hci2: unexpected cc 0x0c25 length: 249 > 3
[ 62.324280] Bluetooth: hci3: unexpected cc 0x0c25 length: 249 > 3
[ 62.325648] Bluetooth: hci1: unexpected cc 0x0c38 length: 249 > 2
[ 62.326961] Bluetooth: hci3: unexpected cc 0x0c38 length: 249 > 2
[ 62.328453] Bluetooth: hci2: unexpected cc 0x0c38 length: 249 > 2
[ 62.354031] Bluetooth: hci5: unexpected cc 0x0c03 length: 249 > 1
[ 62.355620] Bluetooth: hci4: unexpected cc 0x0c03 length: 249 > 1
[ 62.360458] Bluetooth: hci1: HCI_REQ-0x0c1a
[ 62.361530] Bluetooth: hci3: HCI_REQ-0x0c1a
[ 62.362981] Bluetooth: hci5: unexpected cc 0x1003 length: 249 > 9
[ 62.364446] Bluetooth: hci4: unexpected cc 0x1003 length: 249 > 9
[ 62.366112] Bluetooth: hci2: HCI_REQ-0x0c1a
[ 62.367302] Bluetooth: hci5: unexpected cc 0x1001 length: 249 > 9
[ 62.374547] Bluetooth: hci5: unexpected cc 0x0c23 length: 249 > 4
[ 62.376487] Bluetooth: hci5: unexpected cc 0x0c25 length: 249 > 3
[ 62.380945] Bluetooth: hci5: unexpected cc 0x0c38 length: 249 > 2
[ 62.386549] Bluetooth: hci5: HCI_REQ-0x0c1a
[ 62.398057] Bluetooth: hci4: unexpected cc 0x1001 length: 249 > 9
[ 62.406067] Bluetooth: hci4: unexpected cc 0x0c23 length: 249 > 4
[ 62.410698] Bluetooth: hci4: unexpected cc 0x0c25 length: 249 > 3
[ 62.412154] Bluetooth: hci4: unexpected cc 0x0c38 length: 249 > 2
[ 62.425583] Bluetooth: hci4: HCI_REQ-0x0c1a
[ 62.425829] Bluetooth: hci6: unexpected cc 0x0c03 length: 249 > 1
[ 62.438946] Bluetooth: hci6: unexpected cc 0x1003 length: 249 > 9
[ 62.450829] Bluetooth: hci6: unexpected cc 0x1001 length: 249 > 9
[ 62.460759] Bluetooth: hci6: unexpected cc 0x0c23 length: 249 > 4
[ 62.463967] Bluetooth: hci6: unexpected cc 0x0c25 length: 249 > 3
[ 62.464197] Bluetooth: hci7: unexpected cc 0x0c03 length: 249 > 1
[ 62.466270] Bluetooth: hci6: unexpected cc 0x0c38 length: 249 > 2
[ 62.471815] Bluetooth: hci7: unexpected cc 0x1003 length: 249 > 9
[ 62.474026] Bluetooth: hci7: unexpected cc 0x1001 length: 249 > 9
[ 62.484590] Bluetooth: hci7: unexpected cc 0x0c23 length: 249 > 4
[ 62.487555] Bluetooth: hci7: unexpected cc 0x0c25 length: 249 > 3
[ 62.497465] Bluetooth: hci6: HCI_REQ-0x0c1a
[ 62.517120] Bluetooth: hci7: unexpected cc 0x0c38 length: 249 > 2
[ 62.568468] Bluetooth: hci7: HCI_REQ-0x0c1a
[ 64.313846] Bluetooth: hci0: command 0x0409 tx timeout
[ 64.376579] Bluetooth: hci1: command 0x0409 tx timeout
[ 64.440441] Bluetooth: hci3: command 0x0409 tx timeout
[ 64.441448] Bluetooth: hci4: command 0x0409 tx timeout
[ 64.442119] Bluetooth: hci5: command 0x0409 tx timeout
[ 64.442753] Bluetooth: hci2: command 0x0409 tx timeout
[ 64.568468] Bluetooth: hci6: command 0x0409 tx timeout
[ 64.632522] Bluetooth: hci7: command 0x0409 tx timeout
[ 66.360386] Bluetooth: hci0: command 0x041b tx timeout
[ 66.424495] Bluetooth: hci1: command 0x041b tx timeout
[ 66.488555] Bluetooth: hci2: command 0x041b tx timeout
[ 66.489041] Bluetooth: hci5: command 0x041b tx timeout
[ 66.489847] Bluetooth: hci4: command 0x041b tx timeout
[ 66.490262] Bluetooth: hci3: command 0x041b tx timeout
[ 66.616463] Bluetooth: hci6: command 0x041b tx timeout
[ 66.680392] Bluetooth: hci7: command 0x041b tx timeout
[ 68.408396] Bluetooth: hci0: command 0x040f tx timeout
[ 68.472382] Bluetooth: hci1: command 0x040f tx timeout
[ 68.536465] Bluetooth: hci3: command 0x040f tx timeout
[ 68.537926] Bluetooth: hci4: command 0x040f tx timeout
[ 68.538462] Bluetooth: hci5: command 0x040f tx timeout
[ 68.538893] Bluetooth: hci2: command 0x040f tx timeout
[ 68.664454] Bluetooth: hci6: command 0x040f tx timeout
[ 68.728403] Bluetooth: hci7: command 0x040f tx timeout
[ 70.456383] Bluetooth: hci0: command 0x0419 tx timeout
[ 70.520379] Bluetooth: hci1: command 0x0419 tx timeout
[ 70.584703] Bluetooth: hci2: command 0x0419 tx timeout
[ 70.585937] Bluetooth: hci5: command 0x0419 tx timeout
[ 70.586940] Bluetooth: hci4: command 0x0419 tx timeout
[ 70.587581] Bluetooth: hci3: command 0x0419 tx timeout
[ 70.712444] Bluetooth: hci6: command 0x0419 tx timeout
[ 70.776420] Bluetooth: hci7: command 0x0419 tx timeout
11:57:36 executing program 3:
syz_emit_vhci(&(0x7f0000000280)=ANY=[@ANYBLOB="02c90010000c0005"], 0x15)
11:57:36 executing program 3:
syz_emit_vhci(&(0x7f0000000280)=ANY=[@ANYBLOB="02c90010000c0005"], 0x15)
11:57:36 executing program 3:
syz_emit_vhci(&(0x7f0000000280)=ANY=[@ANYBLOB="02c90010000c0005"], 0x15)
11:57:36 executing program 3:
r0 = socket$inet_udp(0x2, 0x2, 0x0)
r1 = socket$inet_udp(0x2, 0x2, 0x0)
ioctl$sock_inet_SIOCSIFFLAGS(r1, 0x8914, &(0x7f0000000000)={'lo\x00'})
socket$inet_udp(0x2, 0x2, 0x0)
setsockopt$sock_timeval(0xffffffffffffffff, 0x1, 0x56, &(0x7f00000000c0)={0x0, 0xea60}, 0x10)
perf_event_open(&(0x7f0000000280)={0x1, 0x80, 0x0, 0x0, 0x0, 0x0, 0x0, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @perf_bp={&(0x7f0000000080), 0xc}}, 0x0, 0xffffffffffffffff, 0xffffffffffffffff, 0x0)
bind$packet(0xffffffffffffffff, &(0x7f0000000000)={0x11, 0x0, 0x0, 0x1, 0x0, 0x6, @random="94031c855deb"}, 0x14)
ioctl$sock_inet_SIOCSIFFLAGS(r0, 0x8914, &(0x7f0000000000)={'lo\x00'})
[ 119.476791] audit: type=1400 audit(1663156657.044:7): avc: denied { open } for pid=3800 comm="syz-executor.3" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=perf_event permissive=1
[ 119.482200] audit: type=1400 audit(1663156657.045:8): avc: denied { kernel } for pid=3800 comm="syz-executor.3" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=perf_event permissive=1
[ 119.501333] hrtimer: interrupt took 28223 ns
[ 119.503908] ------------[ cut here ]------------
[ 119.503940]
[ 119.503945] ======================================================
[ 119.503951] WARNING: possible circular locking dependency detected
[ 119.503958] 6.0.0-rc5-next-20220914 #1 Not tainted
[ 119.503970] ------------------------------------------------------
[ 119.503975] syz-executor.3/3802 is trying to acquire lock:
[ 119.503987] ffffffff853fa878 ((console_sem).lock){-.-.}-{2:2}, at: down_trylock+0xe/0x70
[ 119.504049]
[ 119.504049] but task is already holding lock:
[ 119.504054] ffff88800dea9c20 (&ctx->lock){....}-{2:2}, at: __perf_event_task_sched_out+0x53b/0x18d0
[ 119.504103]
[ 119.504103] which lock already depends on the new lock.
[ 119.504103]
[ 119.504108]
[ 119.504108] the existing dependency chain (in reverse order) is:
[ 119.504114]
[ 119.504114] -> #3 (&ctx->lock){....}-{2:2}:
[ 119.504140] _raw_spin_lock+0x2a/0x40
[ 119.504170] __perf_event_task_sched_out+0x53b/0x18d0
[ 119.504193] __schedule+0xedd/0x2470
[ 119.504211] preempt_schedule_common+0x45/0xc0
[ 119.504232] __cond_resched+0x17/0x30
[ 119.504251] __mutex_lock+0xa3/0x14d0
[ 119.504271] __do_sys_perf_event_open+0x1eec/0x32c0
[ 119.504295] do_syscall_64+0x3b/0x90
[ 119.504329] entry_SYSCALL_64_after_hwframe+0x63/0xcd
[ 119.504372]
[ 119.504372] -> #2 (&rq->__lock){-.-.}-{2:2}:
[ 119.504398] _raw_spin_lock_nested+0x30/0x40
[ 119.504428] raw_spin_rq_lock_nested+0x1e/0x30
[ 119.504452] task_fork_fair+0x63/0x4d0
[ 119.504484] sched_cgroup_fork+0x3d0/0x540
[ 119.504510] copy_process+0x4183/0x6e20
[ 119.504528] kernel_clone+0xe7/0x890
[ 119.504545] user_mode_thread+0xad/0xf0
[ 119.504564] rest_init+0x24/0x250
[ 119.504595] arch_call_rest_init+0xf/0x14
[ 119.504615] start_kernel+0x4c1/0x4e6
[ 119.504633] secondary_startup_64_no_verify+0xe0/0xeb
[ 119.504658]
[ 119.504658] -> #1 (&p->pi_lock){-.-.}-{2:2}:
[ 119.504683] _raw_spin_lock_irqsave+0x39/0x60
[ 119.504712] try_to_wake_up+0xab/0x1920
[ 119.504736] up+0x75/0xb0
[ 119.504757] __up_console_sem+0x6e/0x80
[ 119.504786] console_unlock+0x46a/0x590
[ 119.504816] vt_ioctl+0x2822/0x2ca0
[ 119.504837] tty_ioctl+0x7c4/0x1700
[ 119.504856] __x64_sys_ioctl+0x19a/0x210
[ 119.504883] do_syscall_64+0x3b/0x90
[ 119.504908] entry_SYSCALL_64_after_hwframe+0x63/0xcd
[ 119.504940]
[ 119.504940] -> #0 ((console_sem).lock){-.-.}-{2:2}:
[ 119.504965] __lock_acquire+0x2a02/0x5e70
[ 119.504996] lock_acquire+0x1a2/0x530
[ 119.505026] _raw_spin_lock_irqsave+0x39/0x60
[ 119.505054] down_trylock+0xe/0x70
[ 119.505077] __down_trylock_console_sem+0x3b/0xd0
[ 119.505108] vprintk_emit+0x16b/0x560
[ 119.505138] vprintk+0x84/0xa0
[ 119.505169] _printk+0xba/0xf1
[ 119.505201] report_bug.cold+0x72/0xab
[ 119.505224] handle_bug+0x3c/0x70
[ 119.505248] exc_invalid_op+0x14/0x50
[ 119.505273] asm_exc_invalid_op+0x16/0x20
[ 119.505304] group_sched_out.part.0+0x2c7/0x460
[ 119.505330] ctx_sched_out+0x8f1/0xc10
[ 119.505355] __perf_event_task_sched_out+0x6d0/0x18d0
[ 119.505377] __schedule+0xedd/0x2470
[ 119.505395] preempt_schedule_common+0x45/0xc0
[ 119.505416] __cond_resched+0x17/0x30
[ 119.505434] __mutex_lock+0xa3/0x14d0
[ 119.505455] __do_sys_perf_event_open+0x1eec/0x32c0
[ 119.505478] do_syscall_64+0x3b/0x90
[ 119.505503] entry_SYSCALL_64_after_hwframe+0x63/0xcd
[ 119.505535]
[ 119.505535] other info that might help us debug this:
[ 119.505535]
[ 119.505540] Chain exists of:
[ 119.505540] (console_sem).lock --> &rq->__lock --> &ctx->lock
[ 119.505540]
[ 119.505567] Possible unsafe locking scenario:
[ 119.505567]
[ 119.505571] CPU0 CPU1
[ 119.505575] ---- ----
[ 119.505580] lock(&ctx->lock);
[ 119.505590] lock(&rq->__lock);
[ 119.505601] lock(&ctx->lock);
[ 119.505613] lock((console_sem).lock);
[ 119.505623]
[ 119.505623] *** DEADLOCK ***
[ 119.505623]
[ 119.505627] 2 locks held by syz-executor.3/3802:
[ 119.505640] #0: ffff88806cf37cd8 (&rq->__lock){-.-.}-{2:2}, at: __schedule+0x1cf/0x2470
[ 119.505687] #1: ffff88800dea9c20 (&ctx->lock){....}-{2:2}, at: __perf_event_task_sched_out+0x53b/0x18d0
[ 119.505738]
[ 119.505738] stack backtrace:
[ 119.505743] CPU: 1 PID: 3802 Comm: syz-executor.3 Not tainted 6.0.0-rc5-next-20220914 #1
[ 119.505766] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.14.0-0-g155821a1990b-prebuilt.qemu.org 04/01/2014
[ 119.505780] Call Trace:
[ 119.505786]
[ 119.505793] dump_stack_lvl+0x8b/0xb3
[ 119.505820] check_noncircular+0x263/0x2e0
[ 119.505851] ? format_decode+0x26c/0xb50
[ 119.505878] ? print_circular_bug+0x450/0x450
[ 119.505911] ? enable_ptr_key_workfn+0x20/0x20
[ 119.505936] ? __lockdep_reset_lock+0x180/0x180
[ 119.505968] ? format_decode+0x26c/0xb50
[ 119.505997] ? alloc_chain_hlocks+0x1ec/0x5a0
[ 119.506030] __lock_acquire+0x2a02/0x5e70
[ 119.506071] ? lockdep_hardirqs_on_prepare+0x410/0x410
[ 119.506113] lock_acquire+0x1a2/0x530
[ 119.506145] ? down_trylock+0xe/0x70
[ 119.506173] ? rcu_read_unlock+0x40/0x40
[ 119.506205] ? lockdep_hardirqs_on_prepare+0x410/0x410
[ 119.506245] ? vprintk+0x84/0xa0
[ 119.506279] _raw_spin_lock_irqsave+0x39/0x60
[ 119.506309] ? down_trylock+0xe/0x70
[ 119.506346] down_trylock+0xe/0x70
[ 119.506383] ? vprintk+0x84/0xa0
[ 119.506429] __down_trylock_console_sem+0x3b/0xd0
[ 119.506465] vprintk_emit+0x16b/0x560
[ 119.506497] ? lock_downgrade+0x6d0/0x6d0
[ 119.506532] vprintk+0x84/0xa0
[ 119.506565] _printk+0xba/0xf1
[ 119.506599] ? record_print_text.cold+0x16/0x16
[ 119.506637] ? hrtimer_try_to_cancel+0x163/0x2c0
[ 119.506662] ? lock_downgrade+0x6d0/0x6d0
[ 119.506695] ? report_bug.cold+0x66/0xab
[ 119.506722] ? group_sched_out.part.0+0x2c7/0x460
[ 119.506743] report_bug.cold+0x72/0xab
[ 119.506771] handle_bug+0x3c/0x70
[ 119.506797] exc_invalid_op+0x14/0x50
[ 119.506824] asm_exc_invalid_op+0x16/0x20
[ 119.506857] RIP: 0010:group_sched_out.part.0+0x2c7/0x460
[ 119.506881] Code: 5e 41 5f e9 3b b7 ef ff e8 36 b7 ef ff 65 8b 1d ab 15 ac 7e 31 ff 89 de e8 d6 b3 ef ff 85 db 0f 84 8a 00 00 00 e8 19 b7 ef ff <0f> 0b e9 a5 fe ff ff e8 0d b7 ef ff 48 8d 7d 10 48 b8 00 00 00 00
[ 119.506902] RSP: 0018:ffff88800ffa7978 EFLAGS: 00010006
[ 119.506919] RAX: 0000000040000002 RBX: 0000000000000000 RCX: 0000000000000000
[ 119.506933] RDX: ffff88803e1e0000 RSI: ffffffff81566027 RDI: 0000000000000005
[ 119.506948] RBP: ffff88801d0a8000 R08: 0000000000000005 R09: 0000000000000001
[ 119.506961] R10: 0000000000000000 R11: 0000000000000001 R12: ffff88800dea9c00
[ 119.506975] R13: ffff88806cf3d100 R14: ffffffff8547bfc0 R15: 0000000000000002
[ 119.506995] ? group_sched_out.part.0+0x2c7/0x460
[ 119.507019] ? group_sched_out.part.0+0x2c7/0x460
[ 119.507043] ctx_sched_out+0x8f1/0xc10
[ 119.507066] __perf_event_task_sched_out+0x6d0/0x18d0
[ 119.507119] ? lock_is_held_type+0xd7/0x130
[ 119.507152] ? __perf_cgroup_move+0x160/0x160
[ 119.507174] ? set_next_entity+0x304/0x550
[ 119.507205] ? update_curr+0x267/0x740
[ 119.507239] ? lock_is_held_type+0xd7/0x130
[ 119.507272] __schedule+0xedd/0x2470
[ 119.507296] ? io_schedule_timeout+0x150/0x150
[ 119.507320] ? find_held_lock+0x2c/0x110
[ 119.507363] ? lock_is_held_type+0xd7/0x130
[ 119.507395] ? __cond_resched+0x17/0x30
[ 119.507416] preempt_schedule_common+0x45/0xc0
[ 119.507440] __cond_resched+0x17/0x30
[ 119.507459] __mutex_lock+0xa3/0x14d0
[ 119.507483] ? lock_is_held_type+0xd7/0x130
[ 119.507514] ? __do_sys_perf_event_open+0x1eec/0x32c0
[ 119.507541] ? mutex_lock_io_nested+0x1310/0x1310
[ 119.507564] ? lock_release+0x3b2/0x750
[ 119.507596] ? __up_read+0x192/0x730
[ 119.507622] ? up_write+0x480/0x480
[ 119.507653] __do_sys_perf_event_open+0x1eec/0x32c0
[ 119.507684] ? perf_compat_ioctl+0x130/0x130
[ 119.507706] ? xfd_validate_state+0x59/0x180
[ 119.507748] ? syscall_enter_from_user_mode+0x1d/0x50
[ 119.507781] ? syscall_enter_from_user_mode+0x1d/0x50
[ 119.507818] do_syscall_64+0x3b/0x90
[ 119.507843] entry_SYSCALL_64_after_hwframe+0x63/0xcd
[ 119.507875] RIP: 0033:0x7f691c129b19
[ 119.507891] 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 bc ff ff ff f7 d8 64 89 01 48
[ 119.507912] RSP: 002b:00007f691969f188 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
[ 119.507932] RAX: ffffffffffffffda RBX: 00007f691c23cf60 RCX: 00007f691c129b19
[ 119.507946] RDX: ffffffffffffffff RSI: 0000000000000000 RDI: 0000000020000280
[ 119.507960] RBP: 00007f691c183f6d R08: 0000000000000000 R09: 0000000000000000
[ 119.507974] R10: ffffffffffffffff R11: 0000000000000246 R12: 0000000000000000
[ 119.507987] R13: 00007ffca028ad4f R14: 00007f691969f300 R15: 0000000000022000
[ 119.508011]
[ 119.619701] WARNING: CPU: 1 PID: 3802 at kernel/events/core.c:2309 group_sched_out.part.0+0x2c7/0x460
[ 119.620923] Modules linked in:
[ 119.621361] CPU: 1 PID: 3802 Comm: syz-executor.3 Not tainted 6.0.0-rc5-next-20220914 #1
[ 119.622460] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.14.0-0-g155821a1990b-prebuilt.qemu.org 04/01/2014
[ 119.623940] RIP: 0010:group_sched_out.part.0+0x2c7/0x460
[ 119.624673] Code: 5e 41 5f e9 3b b7 ef ff e8 36 b7 ef ff 65 8b 1d ab 15 ac 7e 31 ff 89 de e8 d6 b3 ef ff 85 db 0f 84 8a 00 00 00 e8 19 b7 ef ff <0f> 0b e9 a5 fe ff ff e8 0d b7 ef ff 48 8d 7d 10 48 b8 00 00 00 00
[ 119.627053] RSP: 0018:ffff88800ffa7978 EFLAGS: 00010006
[ 119.627786] RAX: 0000000040000002 RBX: 0000000000000000 RCX: 0000000000000000
[ 119.628740] RDX: ffff88803e1e0000 RSI: ffffffff81566027 RDI: 0000000000000005
[ 119.629691] RBP: ffff88801d0a8000 R08: 0000000000000005 R09: 0000000000000001
[ 119.630654] R10: 0000000000000000 R11: 0000000000000001 R12: ffff88800dea9c00
[ 119.631623] R13: ffff88806cf3d100 R14: ffffffff8547bfc0 R15: 0000000000000002
[ 119.632559] FS: 00007f691969f700(0000) GS:ffff88806cf00000(0000) knlGS:0000000000000000
[ 119.633644] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 119.634423] CR2: 00007f1d32185260 CR3: 000000001d31c000 CR4: 0000000000350ee0
[ 119.635377] Call Trace:
[ 119.635731]
[ 119.636037] ctx_sched_out+0x8f1/0xc10
[ 119.636576] __perf_event_task_sched_out+0x6d0/0x18d0
[ 119.637262] ? lock_is_held_type+0xd7/0x130
[ 119.637851] ? __perf_cgroup_move+0x160/0x160
[ 119.638463] ? set_next_entity+0x304/0x550
[ 119.639034] ? update_curr+0x267/0x740
[ 119.639600] ? lock_is_held_type+0xd7/0x130
[ 119.640174] __schedule+0xedd/0x2470
[ 119.640701] ? io_schedule_timeout+0x150/0x150
[ 119.641304] ? find_held_lock+0x2c/0x110
[ 119.641865] ? lock_is_held_type+0xd7/0x130
[ 119.642455] ? __cond_resched+0x17/0x30
[ 119.642990] preempt_schedule_common+0x45/0xc0
[ 119.643643] __cond_resched+0x17/0x30
[ 119.644163] __mutex_lock+0xa3/0x14d0
[ 119.644715] ? lock_is_held_type+0xd7/0x130
[ 119.645313] ? __do_sys_perf_event_open+0x1eec/0x32c0
[ 119.646034] ? mutex_lock_io_nested+0x1310/0x1310
[ 119.646701] ? lock_release+0x3b2/0x750
[ 119.647265] ? __up_read+0x192/0x730
[ 119.647793] ? up_write+0x480/0x480
[ 119.648308] __do_sys_perf_event_open+0x1eec/0x32c0
[ 119.649023] ? perf_compat_ioctl+0x130/0x130
[ 119.649642] ? xfd_validate_state+0x59/0x180
[ 119.650267] ? syscall_enter_from_user_mode+0x1d/0x50
[ 119.650989] ? syscall_enter_from_user_mode+0x1d/0x50
[ 119.651724] do_syscall_64+0x3b/0x90
[ 119.652249] entry_SYSCALL_64_after_hwframe+0x63/0xcd
[ 119.652965] RIP: 0033:0x7f691c129b19
[ 119.653501] 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 bc ff ff ff f7 d8 64 89 01 48
[ 119.655901] RSP: 002b:00007f691969f188 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
[ 119.656920] RAX: ffffffffffffffda RBX: 00007f691c23cf60 RCX: 00007f691c129b19
[ 119.657887] RDX: ffffffffffffffff RSI: 0000000000000000 RDI: 0000000020000280
[ 119.658860] RBP: 00007f691c183f6d R08: 0000000000000000 R09: 0000000000000000
[ 119.659839] R10: ffffffffffffffff R11: 0000000000000246 R12: 0000000000000000
[ 119.660802] R13: 00007ffca028ad4f R14: 00007f691969f300 R15: 0000000000022000
[ 119.661772]
[ 119.662100] irq event stamp: 2574
[ 119.662572] hardirqs last enabled at (2573): [] asm_sysvec_apic_timer_interrupt+0x16/0x20
[ 119.663916] hardirqs last disabled at (2574): [] __schedule+0x1225/0x2470
[ 119.665036] softirqs last enabled at (2544): [] __irq_exit_rcu+0x11b/0x180
[ 119.666195] softirqs last disabled at (2357): [] __irq_exit_rcu+0x11b/0x180
[ 119.667417] ---[ end trace 0000000000000000 ]---
[ 119.677355] device lo entered promiscuous mode
11:57:37 executing program 3:
r0 = socket$inet_udp(0x2, 0x2, 0x0)
r1 = socket$inet_udp(0x2, 0x2, 0x0)
ioctl$sock_inet_SIOCSIFFLAGS(r1, 0x8914, &(0x7f0000000000)={'lo\x00'})
socket$inet_udp(0x2, 0x2, 0x0)
setsockopt$sock_timeval(0xffffffffffffffff, 0x1, 0x56, &(0x7f00000000c0)={0x0, 0xea60}, 0x10)
perf_event_open(&(0x7f0000000280)={0x1, 0x80, 0x0, 0x0, 0x0, 0x0, 0x0, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @perf_bp={&(0x7f0000000080), 0xc}}, 0x0, 0xffffffffffffffff, 0xffffffffffffffff, 0x0)
bind$packet(0xffffffffffffffff, &(0x7f0000000000)={0x11, 0x0, 0x0, 0x1, 0x0, 0x6, @random="94031c855deb"}, 0x14)
ioctl$sock_inet_SIOCSIFFLAGS(r0, 0x8914, &(0x7f0000000000)={'lo\x00'})
[ 119.786376] device lo left promiscuous mode
[ 119.800516] device lo entered promiscuous mode
11:57:37 executing program 3:
r0 = socket$inet_udp(0x2, 0x2, 0x0)
r1 = socket$inet_udp(0x2, 0x2, 0x0)
ioctl$sock_inet_SIOCSIFFLAGS(r1, 0x8914, &(0x7f0000000000)={'lo\x00'})
socket$inet_udp(0x2, 0x2, 0x0)
setsockopt$sock_timeval(0xffffffffffffffff, 0x1, 0x56, &(0x7f00000000c0)={0x0, 0xea60}, 0x10)
perf_event_open(&(0x7f0000000280)={0x1, 0x80, 0x0, 0x0, 0x0, 0x0, 0x0, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @perf_bp={&(0x7f0000000080), 0xc}}, 0x0, 0xffffffffffffffff, 0xffffffffffffffff, 0x0)
bind$packet(0xffffffffffffffff, &(0x7f0000000000)={0x11, 0x0, 0x0, 0x1, 0x0, 0x6, @random="94031c855deb"}, 0x14)
ioctl$sock_inet_SIOCSIFFLAGS(r0, 0x8914, &(0x7f0000000000)={'lo\x00'})
[ 120.151408] device lo left promiscuous mode
[ 120.157444] device lo entered promiscuous mode
11:57:37 executing program 3:
r0 = socket$inet_udp(0x2, 0x2, 0x0)
r1 = socket$inet_udp(0x2, 0x2, 0x0)
ioctl$sock_inet_SIOCSIFFLAGS(r1, 0x8914, &(0x7f0000000000)={'lo\x00'})
socket$inet_udp(0x2, 0x2, 0x0)
setsockopt$sock_timeval(0xffffffffffffffff, 0x1, 0x56, &(0x7f00000000c0)={0x0, 0xea60}, 0x10)
perf_event_open(&(0x7f0000000280)={0x1, 0x80, 0x0, 0x0, 0x0, 0x0, 0x0, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @perf_bp={&(0x7f0000000080), 0xc}}, 0x0, 0xffffffffffffffff, 0xffffffffffffffff, 0x0)
bind$packet(0xffffffffffffffff, &(0x7f0000000000)={0x11, 0x0, 0x0, 0x1, 0x0, 0x6, @random="94031c855deb"}, 0x14)
ioctl$sock_inet_SIOCSIFFLAGS(r0, 0x8914, &(0x7f0000000000)={'lo\x00'})
[ 120.296510] device lo left promiscuous mode
[ 120.302948] device lo entered promiscuous mode
11:57:38 executing program 3:
r0 = socket$inet_udp(0x2, 0x2, 0x0)
r1 = socket$inet_udp(0x2, 0x2, 0x0)
ioctl$sock_inet_SIOCSIFFLAGS(r1, 0x8914, &(0x7f0000000000)={'lo\x00'})
socket$inet_udp(0x2, 0x2, 0x0)
setsockopt$sock_timeval(0xffffffffffffffff, 0x1, 0x56, &(0x7f00000000c0)={0x0, 0xea60}, 0x10)
perf_event_open(&(0x7f0000000280)={0x1, 0x80, 0x0, 0x0, 0x0, 0x0, 0x0, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @perf_bp={&(0x7f0000000080), 0xc}}, 0x0, 0xffffffffffffffff, 0xffffffffffffffff, 0x0)
bind$packet(0xffffffffffffffff, &(0x7f0000000000)={0x11, 0x0, 0x0, 0x1, 0x0, 0x6, @random="94031c855deb"}, 0x14)
ioctl$sock_inet_SIOCSIFFLAGS(r0, 0x8914, &(0x7f0000000000)={'lo\x00'})
[ 120.454631] device lo left promiscuous mode
[ 120.517245] device lo entered promiscuous mode
[ 123.770301] Bluetooth: hci4: unexpected cc 0x0c03 length: 249 > 1
[ 123.772764] Bluetooth: hci4: unexpected cc 0x1003 length: 249 > 9
[ 123.774250] Bluetooth: hci4: unexpected cc 0x1001 length: 249 > 9
[ 123.777110] Bluetooth: hci4: unexpected cc 0x0c23 length: 249 > 4
[ 123.779314] Bluetooth: hci4: unexpected cc 0x0c25 length: 249 > 3
[ 123.780159] Bluetooth: hci4: unexpected cc 0x0c38 length: 249 > 2
[ 123.785181] Bluetooth: hci4: HCI_REQ-0x0c1a
[ 125.688384] Bluetooth: hci1: Opcode 0x c03 failed: -110
[ 125.689192] Bluetooth: hci0: Opcode 0x c03 failed: -110
[ 125.752396] Bluetooth: hci3: Opcode 0x c03 failed: -110
[ 125.816372] Bluetooth: hci4: command 0x0409 tx timeout
[ 127.864426] Bluetooth: hci4: command 0x041b tx timeout
[ 127.944884] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1
[ 127.947170] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9
[ 127.948885] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9
[ 127.954883] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4
[ 127.958123] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3
[ 127.959753] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2
[ 127.963306] Bluetooth: hci0: HCI_REQ-0x0c1a
VM DIAGNOSIS:
11:57:37 Registers:
info registers vcpu 0
RAX=0000000000000000 RBX=ffff88800847b280 RCX=0000000000000002 RDX=0000000000000000
RSI=0000000000000000 RDI=ffff88803fc01000 RBP=ffff88800847b280 RSP=ffff88803f7bfb90
R8 =0000000000000008 R9 =ffff88803fc01000 R10=0000000000000006 R11=0000000000000001
R12=ffff88803fc00000 R13=0000000000040cc0 R14=00000000ffffffff R15=0000000000000003
RIP=ffffffff8413d714 RFL=00000212 [----A--] CPL=0 II=0 A20=1 SMM=0 HLT=0
ES =0000 0000000000000000 00000000 00000000
CS =0010 0000000000000000 ffffffff 00a09b00 DPL=0 CS64 [-RA]
SS =0018 0000000000000000 ffffffff 00c09300 DPL=0 DS [-WA]
DS =0000 0000000000000000 00000000 00000000
FS =0000 00007fd0013e8540 00000000 00000000
GS =0000 ffff88806ce00000 00000000 00000000
LDT=0000 fffffe0000000000 00000000 00000000
TR =0040 fffffe0000003000 00004087 00008b00 DPL=0 TSS64-busy
GDT= fffffe0000001000 0000007f
IDT= fffffe0000000000 00000fff
CR0=80050033 CR2=00007ffc919dfdf0 CR3=000000001ecbc000 CR4=00350ef0
DR0=0000000000000000 DR1=0000000000000000 DR2=0000000000000000 DR3=0000000000000000
DR6=00000000ffff0ff0 DR7=0000000000000400
EFER=0000000000000d01
FCW=037f FSW=0000 [ST=0] FTW=00 MXCSR=00001f80
FPR0=0000000000000000 0000 FPR1=0000000000000000 0000
FPR2=0000000000000000 0000 FPR3=0000000000000000 0000
FPR4=0000000000000000 0000 FPR5=0000000000000000 0000
FPR6=0000000000000000 0000 FPR7=0000000000000000 0000
YMM00=0000000000000000 0000000000000000 2525252525252525 2525252525252525
YMM01=0000000000000000 0000000000000000 00ff000000000000 0000000000ff0000
YMM02=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM03=0000000000000000 0000000000000000 756e20796d6d7564 20736e6f6974706f
YMM04=0000000000000000 0000000000000000 2f2f2f2f2f2f2f2f 2f2f2f2f2f2f2f2f
YMM05=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM06=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM07=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM08=0000000000000000 0000000000000000 73253d656d616e6c 6165722073253d73
YMM09=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM10=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM11=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM12=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM13=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM14=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM15=0000000000000000 0000000000000000 0000000000000000 0000000000000000
info registers vcpu 1
RAX=000000000000006c RBX=00000000000003f8 RCX=0000000000000000 RDX=00000000000003f8
RSI=ffffffff822b24f1 RDI=ffffffff87641b60 RBP=ffffffff87641b20 RSP=ffff88800ffa73c8
R8 =0000000000000001 R9 =000000000000000a R10=000000000000006c R11=0000000000000001
R12=000000000000006c R13=ffffffff87641b20 R14=0000000000000010 R15=ffffffff822b24e0
RIP=ffffffff822b2549 RFL=00000002 [-------] CPL=0 II=0 A20=1 SMM=0 HLT=0
ES =0000 0000000000000000 00000000 00000000
CS =0010 0000000000000000 ffffffff 00a09b00 DPL=0 CS64 [-RA]
SS =0018 0000000000000000 ffffffff 00c09300 DPL=0 DS [-WA]
DS =0000 0000000000000000 00000000 00000000
FS =0000 00007f691969f700 00000000 00000000
GS =0000 ffff88806cf00000 00000000 00000000
LDT=0000 fffffe0000000000 00000000 00000000
TR =0040 fffffe000004a000 00004087 00008b00 DPL=0 TSS64-busy
GDT= fffffe0000048000 0000007f
IDT= fffffe0000000000 00000fff
CR0=80050033 CR2=00007f1d32185260 CR3=000000001d31c000 CR4=00350ee0
DR0=0000000000000000 DR1=0000000000000000 DR2=0000000000000000 DR3=0000000000000000
DR6=00000000ffff0ff0 DR7=0000000000000400
EFER=0000000000000d01
FCW=037f FSW=0000 [ST=0] FTW=00 MXCSR=00001f80
FPR0=0000000000000000 0000 FPR1=0000000000000000 0000
FPR2=0000000000000000 0000 FPR3=0000000000000000 0000
FPR4=0000000000000000 0000 FPR5=0000000000000000 0000
FPR6=0000000000000000 0000 FPR7=0000000000000000 0000
YMM00=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM01=0000000000000000 0000000000000000 ffffffffffffffff ffffffffffffffff
YMM02=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM03=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM04=0000000000000000 0000000000000000 0000000000000000 00000000000000ff
YMM05=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM06=0000000000000000 0000000000000000 0000000000000000 000000524f525245
YMM07=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM08=0000000000000000 0000000000000000 0000000000000000 00524f5252450040
YMM09=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM10=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM11=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM12=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM13=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM14=0000000000000000 0000000000000000 0000000000000000 0000000000000000
YMM15=0000000000000000 0000000000000000 0000000000000000 0000000000000000