./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor3602423651 <...> Warning: Permanently added '10.128.1.1' (ECDSA) to the list of known hosts. execve("./syz-executor3602423651", ["./syz-executor3602423651"], 0x7ffc996e21c0 /* 10 vars */) = 0 brk(NULL) = 0x555555b93000 brk(0x555555b93c40) = 0x555555b93c40 arch_prctl(ARCH_SET_FS, 0x555555b93300) = 0 uname({sysname="Linux", nodename="syzkaller", ...}) = 0 set_tid_address(0x555555b935d0) = 5000 set_robust_list(0x555555b935e0, 24) = 0 rt_sigaction(SIGRTMIN, {sa_handler=0x7f4cdfce5c10, sa_mask=[], sa_flags=SA_RESTORER|SA_SIGINFO, sa_restorer=0x7f4cdfce62e0}, NULL, 8) = 0 rt_sigaction(SIGRT_1, {sa_handler=0x7f4cdfce5cb0, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART|SA_SIGINFO, sa_restorer=0x7f4cdfce62e0}, NULL, 8) = 0 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 readlink("/proc/self/exe", "/root/syz-executor3602423651", 4096) = 28 brk(0x555555bb4c40) = 0x555555bb4c40 brk(0x555555bb5000) = 0x555555bb5000 mprotect(0x7f4cdfda6000, 16384, PROT_READ) = 0 mmap(0x1ffff000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x1ffff000 mmap(0x20000000, 16777216, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x20000000 mmap(0x21000000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x21000000 futex(0x7f4cdfdac40c, FUTEX_WAKE_PRIVATE, 1000000) = 0 mmap(NULL, 135168, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f4cdfcb6000 mprotect(0x7f4cdfcb7000, 131072, PROT_READ|PROT_WRITE) = 0 clone(child_stack=0x7f4cdfcd63f0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID./strace-static-x86_64: Process 5001 attached , parent_tid=[5001], tls=0x7f4cdfcd6700, child_tidptr=0x7f4cdfcd69d0) = 5001 [pid 5001] set_robust_list(0x7f4cdfcd69e0, 24) = 0 [pid 5001] futex(0x7f4cdfdac408, FUTEX_WAIT_PRIVATE, 0, NULL [pid 5000] futex(0x7f4cdfdac408, FUTEX_WAKE_PRIVATE, 1000000 [pid 5001] <... futex resumed>) = -1 EAGAIN (Resource temporarily unavailable) [pid 5001] pipe( [pid 5000] <... futex resumed>) = 0 [pid 5001] <... pipe resumed>[3, 4]) = 0 [pid 5000] futex(0x7f4cdfdac40c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5001] futex(0x7f4cdfdac40c, FUTEX_WAKE_PRIVATE, 1000000) = 0 [pid 5001] futex(0x7f4cdfdac408, FUTEX_WAIT_PRIVATE, 0, NULL [pid 5000] <... futex resumed>) = -1 EAGAIN (Resource temporarily unavailable) [pid 5000] futex(0x7f4cdfdac408, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5000] futex(0x7f4cdfdac40c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5001] <... futex resumed>) = 0 [pid 5001] pipe2([5, 6], O_EXCL) = 0 [pid 5001] futex(0x7f4cdfdac40c, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5000] <... futex resumed>) = 0 [pid 5000] futex(0x7f4cdfdac408, FUTEX_WAKE_PRIVATE, 1000000) = 0 [pid 5000] futex(0x7f4cdfdac40c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5001] splice(3, NULL, 6, NULL, 4, 0 [pid 5000] <... futex resumed>) = -1 ETIMEDOUT (Connection timed out) [pid 5000] futex(0x7f4cdfdac40c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=0}) = -1 ETIMEDOUT (Connection timed out) [pid 5000] futex(0x7f4cdfdac41c, FUTEX_WAKE_PRIVATE, 1000000) = 0 [pid 5000] mmap(NULL, 135168, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f4cdfc95000 [pid 5000] mprotect(0x7f4cdfc96000, 131072, PROT_READ|PROT_WRITE) = 0 [pid 5000] clone(child_stack=0x7f4cdfcb53f0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID./strace-static-x86_64: Process 5002 attached [pid 5002] set_robust_list(0x7f4cdfcb59e0, 24) = 0 [pid 5002] futex(0x7f4cdfdac418, FUTEX_WAIT_PRIVATE, 0, NULL [pid 5000] <... clone resumed>, parent_tid=[5002], tls=0x7f4cdfcb5700, child_tidptr=0x7f4cdfcb59d0) = 5002 [pid 5000] futex(0x7f4cdfdac418, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5002] <... futex resumed>) = 0 [pid 5002] write(4, "\xda", 1 [pid 5000] futex(0x7f4cdfdac41c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5002] <... write resumed>) = 1 [pid 5002] futex(0x7f4cdfdac41c, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5000] <... futex resumed>) = 0 [ 48.731788][ T5001] [ 48.734169][ T5001] ============================================ [ 48.740323][ T5001] WARNING: possible recursive locking detected [ 48.746475][ T5001] 6.4.0-rc7-syzkaller-00194-g8a28a0b6f1a1 #0 Not tainted [ 48.753478][ T5001] -------------------------------------------- [ 48.759612][ T5001] syz-executor360/5001 is trying to acquire lock: [ 48.766017][ T5001] ffff88802712bc68 (&pipe->mutex/1){+.+.}-{3:3}, at: pipe_write+0x140/0x1ca0 [ 48.774809][ T5001] [ 48.774809][ T5001] but task is already holding lock: [ 48.782340][ T5001] ffff8880753f7c68 (&pipe->mutex/1){+.+.}-{3:3}, at: pipe_wait_readable+0x39f/0x420 [ 48.791756][ T5001] [ 48.791756][ T5001] other info that might help us debug this: [ 48.799804][ T5001] Possible unsafe locking scenario: [ 48.799804][ T5001] [ 48.807284][ T5001] CPU0 [ 48.810550][ T5001] ---- [ 48.813814][ T5001] lock(&pipe->mutex/1); [ 48.818132][ T5001] lock(&pipe->mutex/1); [ 48.822459][ T5001] [ 48.822459][ T5001] *** DEADLOCK *** [ 48.822459][ T5001] [pid 5002] futex(0x7f4cdfdac418, FUTEX_WAIT_PRIVATE, 0, NULL [pid 5000] exit_group(0 [pid 5002] <... futex resumed>) = ? [pid 5002] +++ exited with 0 +++ [pid 5000] <... exit_group resumed>) = ? [ 48.830669][ T5001] May be due to missing lock nesting notation [ 48.830669][ T5001] [ 48.838975][ T5001] 1 lock held by syz-executor360/5001: [ 48.844417][ T5001] #0: ffff8880753f7c68 (&pipe->mutex/1){+.+.}-{3:3}, at: pipe_wait_readable+0x39f/0x420 [ 48.854245][ T5001] [ 48.854245][ T5001] stack backtrace: [ 48.860129][ T5001] CPU: 1 PID: 5001 Comm: syz-executor360 Not tainted 6.4.0-rc7-syzkaller-00194-g8a28a0b6f1a1 #0 [ 48.870526][ T5001] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 05/27/2023 [ 48.880658][ T5001] Call Trace: [ 48.883927][ T5001] [ 48.886867][ T5001] dump_stack_lvl+0xd9/0x150 [ 48.891468][ T5001] __lock_acquire+0x13eb/0x5f30 [ 48.896311][ T5001] ? print_usage_bug.part.0+0x660/0x660 [ 48.901940][ T5001] ? lockdep_hardirqs_on_prepare+0x410/0x410 [ 48.907913][ T5001] ? __lock_acquire+0x1987/0x5f30 [ 48.912926][ T5001] lock_acquire+0x1b1/0x520 [ 48.917420][ T5001] ? pipe_write+0x140/0x1ca0 [ 48.921995][ T5001] ? lock_sync+0x190/0x190 [ 48.926400][ T5001] ? __lock_acquire+0xc17/0x5f30 [ 48.931327][ T5001] __mutex_lock+0x12f/0x1350 [ 48.935905][ T5001] ? pipe_write+0x140/0x1ca0 [ 48.940479][ T5001] ? pipe_write+0x140/0x1ca0 [ 48.945051][ T5001] ? mutex_lock_io_nested+0x11a0/0x11a0 [ 48.950585][ T5001] ? find_held_lock+0x2d/0x110 [ 48.955334][ T5001] ? mark_lock.part.0+0xee/0x1970 [ 48.960375][ T5001] ? aa_file_perm+0x567/0x1250 [ 48.965130][ T5001] ? lock_downgrade+0x690/0x690 [ 48.969974][ T5001] pipe_write+0x140/0x1ca0 [ 48.974377][ T5001] ? aa_file_perm+0x591/0x1250 [ 48.979138][ T5001] ? do_proc_dopipe_max_size_conv+0x1c0/0x1c0 [ 48.985207][ T5001] ? mark_held_locks+0x9f/0xe0 [ 48.989988][ T5001] ? asm_common_interrupt+0x26/0x40 [ 48.995204][ T5001] do_iter_readv_writev+0x20b/0x3b0 [ 49.000515][ T5001] ? generic_copy_file_range+0x1d0/0x1d0 [ 49.006160][ T5001] ? security_file_permission+0xaf/0xd0 [ 49.011715][ T5001] do_iter_write+0x185/0x7e0 [ 49.016309][ T5001] vfs_iter_write+0x74/0xa0 [ 49.020836][ T5001] iter_file_splice_write+0x743/0xc80 [ 49.026220][ T5001] ? page_cache_pipe_buf_confirm+0x5b0/0x5b0 [ 49.032200][ T5001] ? bpf_lsm_file_permission+0x9/0x10 [ 49.037566][ T5001] ? security_file_permission+0xaf/0xd0 [ 49.043184][ T5001] ? page_cache_pipe_buf_confirm+0x5b0/0x5b0 [ 49.049156][ T5001] do_splice+0xb8c/0x1e50 [ 49.053479][ T5001] ? find_held_lock+0x2d/0x110 [ 49.058233][ T5001] ? splice_file_to_pipe+0x120/0x120 [ 49.063614][ T5001] ? pipe_to_sendpage+0x380/0x380 [ 49.068632][ T5001] __do_splice+0x14e/0x270 [ 49.073043][ T5001] ? do_splice+0x1e50/0x1e50 [ 49.077626][ T5001] __x64_sys_splice+0x19c/0x250 [ 49.082557][ T5001] do_syscall_64+0x39/0xb0 [ 49.086959][ T5001] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 49.092847][ T5001] RIP: 0033:0x7f4cdfd23c79 [ 49.097249][ T5001] Code: 28 00 00 00 75 05 48 83 c4 28 c3 e8 81 14 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 [ 49.116957][ T5001] RSP: 002b:00007f4cdfcd6308 EFLAGS: 00000246 ORIG_RAX: 0000000000000113 [ 49.125460][ T5001] RAX: ffffffffffffffda RBX: 00007f4cdfdac408 RCX: 00007f4cdfd23c79 [pid 5001] <... splice resumed>) = ? [pid 5001] +++ exited with 0 +++ +++ exited with 0 +++