sp0: Synchronizing with TNC sp1: Synchronizing with TNC BUG: MAX_LOCKDEP_CHAINS too low! turning off the locking correctness validator. CPU: 1 PID: 24970 Comm: syz-executor.1 Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/02/2023 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x1fc/0x2ef lib/dump_stack.c:118 add_chain_cache kernel/locking/lockdep.c:2259 [inline] lookup_chain_cache_add kernel/locking/lockdep.c:2371 [inline] validate_chain kernel/locking/lockdep.c:2391 [inline] __lock_acquire.cold+0x420/0x57e kernel/locking/lockdep.c:3416 lock_acquire+0x170/0x3c0 kernel/locking/lockdep.c:3908 __raw_spin_lock_irq include/linux/spinlock_api_smp.h:128 [inline] _raw_spin_lock_irq+0x5b/0x80 kernel/locking/spinlock.c:160 spin_lock_irq include/linux/spinlock.h:354 [inline] __wait_for_common kernel/sched/completion.c:103 [inline] wait_for_common_io.constprop.0+0x99/0x3b0 kernel/sched/completion.c:121 submit_bio_wait+0x10c/0x1b0 block/bio.c:931 blkdev_issue_flush+0x1f9/0x2f0 block/blk-flush.c:563 hfsplus_file_fsync+0x344/0x4f0 fs/hfsplus/inode.c:323 vfs_fsync_range+0x13a/0x220 fs/sync.c:197 generic_write_sync include/linux/fs.h:2750 [inline] generic_file_write_iter+0x4be/0x730 mm/filemap.c:3327 call_write_iter include/linux/fs.h:1821 [inline] new_sync_write fs/read_write.c:474 [inline] __vfs_write+0x51b/0x770 fs/read_write.c:487 vfs_write+0x1f3/0x540 fs/read_write.c:549 ksys_write+0x12b/0x2a0 fs/read_write.c:599 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x7ff2db02e0f9 Code: 28 00 00 00 75 05 48 83 c4 28 c3 e8 f1 19 00 00 90 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 b8 ff ff ff f7 d8 64 89 01 48 RSP: 002b:00007ff2d957f168 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 00007ff2db14e050 RCX: 00007ff2db02e0f9 RDX: 000000006db6e559 RSI: 0000000020000080 RDI: 000000000000000a RBP: 00007ff2db089ae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffec66d3cbf R14: 00007ff2d957f300 R15: 0000000000022000 audit: type=1804 audit(1678127549.657:21727): pid=25025 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1405/bus" dev="sda1" ino=14257 res=1 audit: type=1804 audit(1678127549.687:21728): pid=25025 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1405/bus" dev="sda1" ino=14257 res=1 audit: type=1804 audit(1678127549.687:21729): pid=24936 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1405/bus" dev="sda1" ino=14257 res=1 audit: type=1804 audit(1678127549.687:21730): pid=25027 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1405/bus" dev="sda1" ino=14257 res=1 sp0: Synchronizing with TNC IPVS: ftp: loaded support on port[0] = 21 sp0: Synchronizing with TNC attempt to access beyond end of device loop3: rw=2049, want=1041, limit=128 audit: type=1804 audit(1678127551.197:21731): pid=25032 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1406/bus" dev="sda1" ino=15589 res=1 audit: type=1804 audit(1678127551.227:21732): pid=25032 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1406/bus" dev="sda1" ino=15589 res=1 sp0: Synchronizing with TNC netlink: 28 bytes leftover after parsing attributes in process `syz-executor.4'. IPVS: ftp: loaded support on port[0] = 21 IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 attempt to access beyond end of device wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 loop3: rw=2049, want=1041, limit=128 netlink: 28 bytes leftover after parsing attributes in process `syz-executor.4'. IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready netlink: 28 bytes leftover after parsing attributes in process `syz-executor.4'. attempt to access beyond end of device loop3: rw=2049, want=921, limit=128 IPVS: ftp: loaded support on port[0] = 21 sp0: Synchronizing with TNC sp1: Synchronizing with TNC sp2: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC IPVS: ftp: loaded support on port[0] = 21 netlink: 28 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 28 bytes leftover after parsing attributes in process `syz-executor.2'. sp1: Synchronizing with TNC netlink: 28 bytes leftover after parsing attributes in process `syz-executor.2'. sp2: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC sp2: Synchronizing with TNC netlink: 28 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 28 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 28 bytes leftover after parsing attributes in process `syz-executor.2'. sp1: Synchronizing with TNC sp2: Synchronizing with TNC netlink: 28 bytes leftover after parsing attributes in process `syz-executor.2'. sp0: Synchronizing with TNC sp1: Synchronizing with TNC sp2: Synchronizing with TNC sp0: Synchronizing with TNC sp2: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC sp2: Synchronizing with TNC sp1: Synchronizing with TNC sp2: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC sp2: Synchronizing with TNC sp0: Synchronizing with TNC sp2: Synchronizing with TNC sp0: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC sp2: Synchronizing with TNC sp1: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC sp2: Synchronizing with TNC IPVS: ftp: loaded support on port[0] = 21 sp0: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC kauditd_printk_skb: 2 callbacks suppressed audit: type=1804 audit(1678127560.457:21735): pid=26026 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1420/bus" dev="sda1" ino=15617 res=1 audit: type=1804 audit(1678127560.507:21736): pid=26026 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1420/bus" dev="sda1" ino=15617 res=1 audit: type=1804 audit(1678127560.507:21737): pid=26026 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1420/bus" dev="sda1" ino=15617 res=1 audit: type=1804 audit(1678127560.507:21738): pid=26026 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1420/bus" dev="sda1" ino=15617 res=1 sp0: Synchronizing with TNC sp1: Synchronizing with TNC nla_parse: 4 callbacks suppressed netlink: 28 bytes leftover after parsing attributes in process `syz-executor.2'. sp0: Synchronizing with TNC sp0: Synchronizing with TNC IPVS: ftp: loaded support on port[0] = 21 sp1: Synchronizing with TNC sp0: Synchronizing with TNC audit: type=1804 audit(1678127561.217:21739): pid=26288 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1422/bus" dev="sda1" ino=15627 res=1 sp1: Synchronizing with TNC sp2: Synchronizing with TNC netlink: 80 bytes leftover after parsing attributes in process `syz-executor.2'. audit: type=1804 audit(1678127561.257:21740): pid=26288 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1422/bus" dev="sda1" ino=15627 res=1 IPVS: ftp: loaded support on port[0] = 21 netlink: 80 bytes leftover after parsing attributes in process `syz-executor.2'. sp0: Synchronizing with TNC sp1: Synchronizing with TNC audit: type=1804 audit(1678127561.257:21741): pid=26300 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1422/bus" dev="sda1" ino=15627 res=1 sp2: Synchronizing with TNC netlink: 80 bytes leftover after parsing attributes in process `syz-executor.2'. audit: type=1804 audit(1678127561.257:21742): pid=26300 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1422/bus" dev="sda1" ino=15627 res=1 sp1: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC netlink: 28 bytes leftover after parsing attributes in process `syz-executor.2'. sp2: Synchronizing with TNC sp0: Synchronizing with TNC sp2: Synchronizing with TNC sp1: Synchronizing with TNC netlink: 80 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 80 bytes leftover after parsing attributes in process `syz-executor.2'. sp0: Synchronizing with TNC netlink: 80 bytes leftover after parsing attributes in process `syz-executor.2'. sp1: Synchronizing with TNC sp2: Synchronizing with TNC netlink: 68 bytes leftover after parsing attributes in process `syz-executor.2'. sp0: Synchronizing with TNC sp1: Synchronizing with TNC netlink: 68 bytes leftover after parsing attributes in process `syz-executor.2'. sp0: Synchronizing with TNC sp1: Synchronizing with TNC sp0: Synchronizing with TNC audit: type=1800 audit(1678127563.807:21743): pid=26716 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name="file0" dev="sda1" ino=14065 res=0 x_tables: ip6_tables: icmp6 match: only valid for protocol 58 x_tables: ip6_tables: icmp6 match: only valid for protocol 58 sp1: Synchronizing with TNC audit: type=1804 audit(1678127563.887:21744): pid=26718 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir3827334657/syzkaller.dZWOlA/1426/file0" dev="sda1" ino=14065 res=1 sp0: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC x_tables: ip6_tables: icmp6 match: only valid for protocol 58 sp0: Synchronizing with TNC sp0: Synchronizing with TNC sp0: Synchronizing with TNC sp0: Synchronizing with TNC sp1: Synchronizing with TNC kauditd_printk_skb: 2 callbacks suppressed audit: type=1800 audit(1678127565.678:21747): pid=26982 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.5" name="file0" dev="sda1" ino=14305 res=0 x_tables: ip6_tables: icmp6 match: only valid for protocol 58 x_tables: ip6_tables: icmp6 match: only valid for protocol 58 audit: type=1804 audit(1678127565.808:21748): pid=26983 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.5" name="/root/syzkaller-testdir3334614042/syzkaller.0EkbrC/1453/file0" dev="sda1" ino=14305 res=1 sp0: Synchronizing with TNC sp1: Synchronizing with TNC sp2: Synchronizing with TNC IPv6: ADDRCONF(NETDEV_CHANGE): erspan0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready nla_parse: 10 callbacks suppressed netlink: 68 bytes leftover after parsing attributes in process `syz-executor.2'. sp0: Synchronizing with TNC