./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor1786581968 <...> Warning: Permanently added '10.128.1.158' (ED25519) to the list of known hosts. execve("./syz-executor1786581968", ["./syz-executor1786581968"], 0x7fff27a9f9e0 /* 10 vars */) = 0 brk(NULL) = 0x555555a34000 brk(0x555555a34d40) = 0x555555a34d40 arch_prctl(ARCH_SET_FS, 0x555555a343c0) = 0 set_tid_address(0x555555a34690) = 5061 set_robust_list(0x555555a346a0, 24) = 0 rseq(0x555555a34ce0, 0x20, 0, 0x53053053) = 0 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 readlink("/proc/self/exe", "/root/syz-executor1786581968", 4096) = 28 getrandom("\x90\x8e\x52\xf4\x12\x74\x7b\x42", 8, GRND_NONBLOCK) = 8 brk(NULL) = 0x555555a34d40 brk(0x555555a55d40) = 0x555555a55d40 brk(0x555555a56000) = 0x555555a56000 mprotect(0x7f696feca000, 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(0x7f696fed032c, FUTEX_WAKE_PRIVATE, 1000000) = 0 rt_sigaction(SIGRT_1, {sa_handler=0x7f696fe6cf60, sa_mask=[], sa_flags=SA_RESTORER|SA_ONSTACK|SA_RESTART|SA_SIGINFO, sa_restorer=0x7f696fe5e5e0}, NULL, 8) = 0 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0 mmap(NULL, 135168, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f696fde9000 mprotect(0x7f696fdea000, 131072, PROT_READ|PROT_WRITE) = 0 rt_sigprocmask(SIG_BLOCK, ~[], [], 8) = 0 clone3({flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, child_tid=0x7f696fe09990, parent_tid=0x7f696fe09990, exit_signal=0, stack=0x7f696fde9000, stack_size=0x20300, tls=0x7f696fe096c0}./strace-static-x86_64: Process 5062 attached [pid 5062] rseq(0x7f696fe09fe0, 0x20, 0, 0x53053053 [pid 5061] <... clone3 resumed> => {parent_tid=[5062]}, 88) = 5062 [pid 5062] <... rseq resumed>) = 0 [pid 5061] rt_sigprocmask(SIG_SETMASK, [], [pid 5062] set_robust_list(0x7f696fe099a0, 24 [pid 5061] <... rt_sigprocmask resumed>NULL, 8) = 0 [pid 5062] <... set_robust_list resumed>) = 0 [pid 5061] futex(0x7f696fed0328, FUTEX_WAKE_PRIVATE, 1000000 [pid 5062] rt_sigprocmask(SIG_SETMASK, [], [pid 5061] <... futex resumed>) = 0 [pid 5062] <... rt_sigprocmask resumed>NULL, 8) = 0 [pid 5061] futex(0x7f696fed032c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5062] pipe2([3, 4], 0) = 0 [pid 5062] futex(0x7f696fed032c, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5062] futex(0x7f696fed0328, FUTEX_WAIT_PRIVATE, 0, NULL [pid 5061] <... futex resumed>) = 0 [pid 5061] futex(0x7f696fed0328, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5062] <... futex resumed>) = 0 [pid 5061] futex(0x7f696fed032c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5062] pipe2([5, 6], O_EXCL|O_NONBLOCK) = 0 [pid 5062] futex(0x7f696fed032c, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5061] <... futex resumed>) = 0 [pid 5062] futex(0x7f696fed0328, FUTEX_WAIT_PRIVATE, 0, NULL [pid 5061] futex(0x7f696fed0328, FUTEX_WAKE_PRIVATE, 1000000 [pid 5062] <... futex resumed>) = -1 EAGAIN (Resource temporarily unavailable) [pid 5061] <... futex resumed>) = 0 [pid 5062] openat(AT_FDCWD, "/proc/thread-self/fd/4", O_RDWR [pid 5061] futex(0x7f696fed032c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5062] <... openat resumed>) = 7 [pid 5062] futex(0x7f696fed032c, FUTEX_WAKE_PRIVATE, 1000000 [pid 5061] <... futex resumed>) = 0 [pid 5062] <... futex resumed>) = 1 [pid 5061] futex(0x7f696fed0328, FUTEX_WAKE_PRIVATE, 1000000 [pid 5062] splice(7, NULL, 6, NULL, 256, 0 [pid 5061] <... futex resumed>) = 0 [ 74.547284][ T27] audit: type=1400 audit(1703751301.665:83): avc: denied { execmem } for pid=5061 comm="syz-executor178" scontext=root:sysadm_r:sysadm_t tcontext=root:sysadm_r:sysadm_t tclass=process permissive=1 [pid 5061] futex(0x7f696fed032c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000}) = -1 ETIMEDOUT (Connection timed out) [pid 5061] futex(0x7f696fed033c, FUTEX_WAKE_PRIVATE, 1000000) = 0 [pid 5061] mmap(NULL, 135168, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f696fdc8000 [pid 5061] mprotect(0x7f696fdc9000, 131072, PROT_READ|PROT_WRITE) = 0 [pid 5061] rt_sigprocmask(SIG_BLOCK, ~[], [], 8) = 0 [pid 5061] clone3({flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, child_tid=0x7f696fde8990, parent_tid=0x7f696fde8990, exit_signal=0, stack=0x7f696fdc8000, stack_size=0x20300, tls=0x7f696fde86c0}./strace-static-x86_64: Process 5063 attached => {parent_tid=[5063]}, 88) = 5063 [pid 5061] rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 [pid 5061] futex(0x7f696fed0338, FUTEX_WAKE_PRIVATE, 1000000) = 0 [pid 5061] futex(0x7f696fed033c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5063] rseq(0x7f696fde8fe0, 0x20, 0, 0x53053053) = 0 [pid 5063] set_robust_list(0x7f696fde89a0, 24) = 0 [pid 5063] rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 [pid 5063] write(4, "\x5d\x00\x00\x00\x7d\x01\x00\x00\x00\x43\x00\x00\xfe\x00\x00\x00\x00\x08\x04\x00\x00\x00\x02\x00\x00\x00\x00\x00\x00\x00\x93\x37\x89\x23\x09\x00\x00\x00\x08\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x03\x00\x2a\x7b\xca\x05\x00\x66\x64\x2f\x34\x00\x05\x00\x66\x64\x2f\x34\x00\x03\x00\x2f\x27\x2e\x05\x00\x66\x64\x2f\x34\x00\xff\xff\xff\xff\x00\x00\x00\x00\x00\x00\x00\x00", 93) = 93 [pid 5063] futex(0x7f696fed033c, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5061] <... futex resumed>) = 0 [ 74.716653][ T5062] [ 74.719026][ T5062] ============================================ [ 74.725159][ T5062] WARNING: possible recursive locking detected [ 74.731418][ T5062] 6.7.0-rc7-syzkaller-00016-gf5837722ffec #0 Not tainted [ 74.738436][ T5062] -------------------------------------------- [ 74.744566][ T5062] syz-executor178/5062 is trying to acquire lock: [ 74.751143][ T5062] ffff88807b9f6068 (&pipe->mutex/1){+.+.}-{3:3}, at: pipe_write+0x13a/0x1bc0 [ 74.759999][ T5062] [ 74.759999][ T5062] but task is already holding lock: [ 74.769086][ T5062] ffff88807b9f6468 (&pipe->mutex/1){+.+.}-{3:3}, at: pipe_wait_readable+0x3c9/0x4a0 [ 74.778476][ T5062] [ 74.778476][ T5062] other info that might help us debug this: [ 74.786521][ T5062] Possible unsafe locking scenario: [ 74.786521][ T5062] [ 74.793958][ T5062] CPU0 [ 74.797244][ T5062] ---- [ 74.800528][ T5062] lock(&pipe->mutex/1); [ 74.804849][ T5062] lock(&pipe->mutex/1); [ 74.809169][ T5062] [ 74.809169][ T5062] *** DEADLOCK *** [ 74.809169][ T5062] [ 74.817296][ T5062] May be due to missing lock nesting notation [ 74.817296][ T5062] [ 74.825620][ T5062] 1 lock held by syz-executor178/5062: [ 74.831404][ T5062] #0: ffff88807b9f6468 (&pipe->mutex/1){+.+.}-{3:3}, at: pipe_wait_readable+0x3c9/0x4a0 [ 74.841340][ T5062] [ 74.841340][ T5062] stack backtrace: [ 74.847393][ T5062] CPU: 1 PID: 5062 Comm: syz-executor178 Not tainted 6.7.0-rc7-syzkaller-00016-gf5837722ffec #0 [ 74.857873][ T5062] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/17/2023 [ 74.867918][ T5062] Call Trace: [ 74.871284][ T5062] [ 74.874195][ T5062] dump_stack_lvl+0xd9/0x1b0 [ 74.878789][ T5062] __lock_acquire+0x20f8/0x3b20 [ 74.883635][ T5062] ? lockdep_hardirqs_on_prepare+0x420/0x420 [ 74.889694][ T5062] ? hlock_class+0x4e/0x130 [ 74.894193][ T5062] ? __lock_acquire+0xc65/0x3b20 [ 74.899120][ T5062] lock_acquire+0x1ae/0x520 [ 74.903615][ T5062] ? pipe_write+0x13a/0x1bc0 [ 74.908196][ T5062] ? lock_sync+0x190/0x190 [ 74.912687][ T5062] ? preempt_count_sub+0x160/0x160 [ 74.917809][ T5062] __mutex_lock+0x175/0x9d0 [ 74.922390][ T5062] ? pipe_write+0x13a/0x1bc0 [ 74.928544][ T5062] ? avc_has_perm_noaudit+0x123/0x3a0 [ 74.934172][ T5062] ? reacquire_held_locks+0x4c0/0x4c0 [ 74.939540][ T5062] ? pipe_write+0x13a/0x1bc0 [ 74.944130][ T5062] ? mutex_trylock+0x130/0x130 [ 74.949044][ T5062] ? avc_has_perm_noaudit+0x145/0x3a0 [ 74.954711][ T5062] ? pipe_write+0x13a/0x1bc0 [ 74.959307][ T5062] ? avc_has_perm_noaudit+0x3a0/0x3a0 [ 74.964678][ T5062] pipe_write+0x13a/0x1bc0 [ 74.969105][ T5062] ? rcu_is_watching+0x12/0xb0 [ 74.973859][ T5062] ? trace_contention_end+0xd6/0x100 [ 74.979137][ T5062] ? do_proc_dopipe_max_size_conv+0x1b0/0x1b0 [ 74.985218][ T5062] ? file_has_perm+0x286/0x350 [ 74.989973][ T5062] ? bpf_fd_pass+0x290/0x290 [ 74.994556][ T5062] do_iter_readv_writev+0x21e/0x3c0 [ 74.999749][ T5062] ? generic_copy_file_range+0x1d0/0x1d0 [ 75.005367][ T5062] ? selinux_file_permission+0x126/0x590 [ 75.010983][ T5062] ? security_file_permission+0x94/0x100 [ 75.016610][ T5062] do_iter_write+0x17f/0x7f0 [ 75.028959][ T5062] vfs_iter_write+0x7a/0xb0 [ 75.033487][ T5062] iter_file_splice_write+0x698/0xc00 [ 75.038877][ T5062] ? splice_from_pipe_next+0x5d0/0x5d0 [ 75.044378][ T5062] ? lockdep_hardirqs_on_prepare+0x420/0x420 [ 75.050543][ T5062] ? security_file_permission+0x94/0x100 [ 75.056181][ T5062] ? splice_from_pipe_next+0x5d0/0x5d0 [ 75.061642][ T5062] do_splice+0x127f/0x1f80 [ 75.066059][ T5062] ? splice_file_to_pipe+0x120/0x120 [ 75.071345][ T5062] ? direct_file_splice_eof+0xb0/0xb0 [ 75.076811][ T5062] ? reacquire_held_locks+0x4c0/0x4c0 [ 75.082206][ T5062] __do_splice+0x323/0x360 [ 75.086615][ T5062] ? do_splice+0x1f80/0x1f80 [ 75.091200][ T5062] __x64_sys_splice+0x1ce/0x260 [ 75.096197][ T5062] do_syscall_64+0x40/0x110 [ 75.100695][ T5062] entry_SYSCALL_64_after_hwframe+0x63/0x6b [ 75.106617][ T5062] RIP: 0033:0x7f696fe470b9 [ 75.111041][ T5062] Code: 28 00 00 00 75 05 48 83 c4 28 c3 e8 c1 17 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 b0 ff ff ff f7 d8 64 89 01 48 [ 75.131431][ T5062] RSP: 002b:00007f696fe09188 EFLAGS: 00000246 ORIG_RAX: 0000000000000113 [ 75.139876][ T5062] RAX: ffffffffffffffda RBX: 00007f696fed0328 RCX: 00007f696fe470b9 [ 75.147851][ T5062] RDX: 0000000000000006 RSI: 0000000000000000 RDI: 0000000000000007 [ 75.157650][ T5062] RBP: 00007f696fed0320 R08: 0000000000000100 R09: 0000000000000000 [pid 5063] futex(0x7f696fed0338, FUTEX_WAIT_PRIVATE, 0, NULL [pid 5061] exit_group(0 [pid 5063] <... futex resumed>) = ? [pid 5061] <... exit_group resumed>) = ? [pid 5063] +++ exited with 0 +++ [pid 5062] <... splice resumed>) = ? [pid 5062] +++ exited with 0 +++ +++ exited with 0 +++ [ 75.165741][ T5062] R10: 0000000