./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor1035002838 <...> Warning: Permanently added '10.128.1.87' (ED25519) to the list of known hosts. execve("./syz-executor1035002838", ["./syz-executor1035002838"], 0x7ffd8776dde0 /* 10 vars */) = 0 brk(NULL) = 0x555556beb000 brk(0x555556bebd40) = 0x555556bebd40 arch_prctl(ARCH_SET_FS, 0x555556beb3c0) = 0 set_tid_address(0x555556beb690) = 5067 set_robust_list(0x555556beb6a0, 24) = 0 rseq(0x555556bebce0, 0x20, 0, 0x53053053) = 0 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 readlink("/proc/self/exe", "/root/syz-executor1035002838", 4096) = 28 getrandom("\x91\xf3\x73\xce\x76\xe6\x62\x7b", 8, GRND_NONBLOCK) = 8 brk(NULL) = 0x555556bebd40 brk(0x555556c0cd40) = 0x555556c0cd40 brk(0x555556c0d000) = 0x555556c0d000 mprotect(0x7f421e112000, 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(0x7f421e11832c, FUTEX_WAKE_PRIVATE, 1000000) = 0 rt_sigaction(SIGRT_1, {sa_handler=0x7f421e0b4b20, sa_mask=[], sa_flags=SA_RESTORER|SA_ONSTACK|SA_RESTART|SA_SIGINFO, sa_restorer=0x7f421e0a61a0}, 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) = 0x7f421e031000 mprotect(0x7f421e032000, 131072, PROT_READ|PROT_WRITE) = 0 [ 74.008033][ T27] audit: type=1400 audit(1702839341.115:83): avc: denied { execmem } for pid=5067 comm="syz-executor103" scontext=root:sysadm_r:sysadm_t tcontext=root:sysadm_r:sysadm_t tclass=process permissive=1 [ 74.032091][ T27] audit: type=1400 audit(1702839341.145:84): avc: denied { append } for pid=4497 comm="syslogd" name="messages" dev="tmpfs" ino=3 scontext=system_u:system_r:syslogd_t tcontext=system_u:object_r:tmpfs_t tclass=file permissive=1 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=0x7f421e051990, parent_tid=0x7f421e051990, exit_signal=0, stack=0x7f421e031000, stack_size=0x20300, tls=0x7f421e0516c0} => {parent_tid=[5068]}, 88) = 5068 ./strace-static-x86_64: Process 5068 attached [pid 5067] rt_sigprocmask(SIG_SETMASK, [], [pid 5068] rseq(0x7f421e051fe0, 0x20, 0, 0x53053053 [pid 5067] <... rt_sigprocmask resumed>NULL, 8) = 0 [pid 5068] <... rseq resumed>) = 0 [pid 5067] futex(0x7f421e118328, FUTEX_WAKE_PRIVATE, 1000000 [pid 5068] set_robust_list(0x7f421e0519a0, 24 [pid 5067] <... futex resumed>) = 0 [pid 5068] <... set_robust_list resumed>) = 0 [pid 5068] rt_sigprocmask(SIG_SETMASK, [], [pid 5067] futex(0x7f421e11832c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5068] <... rt_sigprocmask resumed>NULL, 8) = 0 [pid 5068] pipe([3, 4]) = 0 [pid 5068] futex(0x7f421e11832c, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5068] futex(0x7f421e118328, FUTEX_WAIT_PRIVATE, 0, NULL [pid 5067] <... futex resumed>) = 0 [pid 5067] futex(0x7f421e118328, FUTEX_WAKE_PRIVATE, 1000000) = 1 [pid 5068] <... futex resumed>) = 0 [pid 5068] pipe2([5, 6], O_EXCL) = 0 [pid 5067] futex(0x7f421e11832c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5068] futex(0x7f421e11832c, FUTEX_WAKE_PRIVATE, 1000000 [pid 5067] <... futex resumed>) = -1 EAGAIN (Resource temporarily unavailable) [pid 5067] futex(0x7f421e118328, FUTEX_WAKE_PRIVATE, 1000000 [pid 5068] <... futex resumed>) = 0 [pid 5067] <... futex resumed>) = 0 [pid 5068] splice(3, NULL, 6, NULL, 4, 0 [ 74.054380][ T27] audit: type=1400 audit(1702839341.145:85): avc: denied { open } for pid=4497 comm="syslogd" path="/tmp/messages" dev="tmpfs" ino=3 scontext=system_u:system_r:syslogd_t tcontext=system_u:object_r:tmpfs_t tclass=file permissive=1 [ 74.076964][ T27] audit: type=1400 audit(1702839341.145:86): avc: denied { getattr } for pid=4497 comm="syslogd" path="/tmp/messages" dev="tmpfs" ino=3 scontext=system_u:system_r:syslogd_t tcontext=system_u:object_r:tmpfs_t tclass=file permissive=1 [pid 5067] futex(0x7f421e11832c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000}) = -1 ETIMEDOUT (Connection timed out) [pid 5067] futex(0x7f421e11833c, FUTEX_WAKE_PRIVATE, 1000000) = 0 [pid 5067] mmap(NULL, 135168, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f421e010000 [pid 5067] mprotect(0x7f421e011000, 131072, PROT_READ|PROT_WRITE) = 0 [pid 5067] rt_sigprocmask(SIG_BLOCK, ~[], [], 8) = 0 [pid 5067] clone3({flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, child_tid=0x7f421e030990, parent_tid=0x7f421e030990, exit_signal=0, stack=0x7f421e010000, stack_size=0x20300, tls=0x7f421e0306c0}./strace-static-x86_64: Process 5069 attached => {parent_tid=[5069]}, 88) = 5069 [pid 5069] rseq(0x7f421e030fe0, 0x20, 0, 0x53053053 [pid 5067] rt_sigprocmask(SIG_SETMASK, [], [pid 5069] <... rseq resumed>) = 0 [pid 5067] <... rt_sigprocmask resumed>NULL, 8) = 0 [pid 5069] set_robust_list(0x7f421e0309a0, 24 [pid 5067] futex(0x7f421e118338, FUTEX_WAKE_PRIVATE, 1000000 [pid 5069] <... set_robust_list resumed>) = 0 [pid 5067] <... futex resumed>) = 0 [pid 5069] rt_sigprocmask(SIG_SETMASK, [], [pid 5067] futex(0x7f421e11833c, FUTEX_WAIT_PRIVATE, 0, {tv_sec=0, tv_nsec=50000000} [pid 5069] <... rt_sigprocmask resumed>NULL, 8) = 0 [pid 5069] write(4, "\xda", 1) = 1 [pid 5069] futex(0x7f421e11833c, FUTEX_WAKE_PRIVATE, 1000000 [pid 5067] <... futex resumed>) = 0 [pid 5069] <... futex resumed>) = 1 [ 74.254164][ T5068] [ 74.256529][ T5068] ============================================ [ 74.262668][ T5068] WARNING: possible recursive locking detected [ 74.268812][ T5068] 6.7.0-rc5-syzkaller-00230-g3b8a9b2e6809 #0 Not tainted [ 74.275815][ T5068] -------------------------------------------- [ 74.281948][ T5068] syz-executor103/5068 is trying to acquire lock: [ 74.288341][ T5068] ffff888020667068 (&pipe->mutex/1){+.+.}-{3:3}, at: pipe_write+0x13a/0x1bc0 [ 74.297132][ T5068] [ 74.297132][ T5068] but task is already holding lock: [ 74.304494][ T5068] ffff888020667468 (&pipe->mutex/1){+.+.}-{3:3}, at: pipe_wait_readable+0x3c9/0x4a0 [ 74.314340][ T5068] [ 74.314340][ T5068] other info that might help us debug this: [ 74.322394][ T5068] Possible unsafe locking scenario: [ 74.322394][ T5068] [ 74.329848][ T5068] CPU0 [ 74.333241][ T5068] ---- [ 74.336582][ T5068] lock(&pipe->mutex/1); [ 74.340927][ T5068] lock(&pipe->mutex/1); [ 74.345266][ T5068] [ 74.345266][ T5068] *** DEADLOCK *** [ 74.345266][ T5068] [ 74.353403][ T5068] May be due to missing lock nesting notation [ 74.353403][ T5068] [ 74.361799][ T5068] 1 lock held by syz-executor103/5068: [ 74.367247][ T5068] #0: ffff888020667468 (&pipe->mutex/1){+.+.}-{3:3}, at: pipe_wait_readable+0x3c9/0x4a0 [ 74.377091][ T5068] [ 74.377091][ T5068] stack backtrace: [ 74.382964][ T5068] CPU: 1 PID: 5068 Comm: syz-executor103 Not tainted 6.7.0-rc5-syzkaller-00230-g3b8a9b2e6809 #0 [ 74.393365][ T5068] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/17/2023 [ 74.403416][ T5068] Call Trace: [ 74.406688][ T5068] [ 74.409609][ T5068] dump_stack_lvl+0xd9/0x1b0 [ 74.414209][ T5068] __lock_acquire+0x20f8/0x3b20 [ 74.419066][ T5068] ? lockdep_hardirqs_on_prepare+0x420/0x420 [ 74.425053][ T5068] ? hlock_class+0x4e/0x130 [ 74.429557][ T5068] ? __lock_acquire+0xc65/0x3b20 [ 74.434494][ T5068] lock_acquire+0x1ae/0x520 [ 74.439010][ T5068] ? pipe_write+0x13a/0x1bc0 [ 74.443597][ T5068] ? lock_sync+0x190/0x190 [ 74.448012][ T5068] ? preempt_count_sub+0x160/0x160 [ 74.453117][ T5068] __mutex_lock+0x175/0x9d0 [ 74.457630][ T5068] ? pipe_write+0x13a/0x1bc0 [ 74.462213][ T5068] ? avc_has_perm_noaudit+0x123/0x3a0 [ 74.467580][ T5068] ? reacquire_held_locks+0x4c0/0x4c0 [ 74.472950][ T5068] ? pipe_write+0x13a/0x1bc0 [ 74.477537][ T5068] ? mutex_trylock+0x130/0x130 [ 74.482306][ T5068] ? avc_has_perm_noaudit+0x145/0x3a0 [ 74.487675][ T5068] ? pipe_write+0x13a/0x1bc0 [ 74.492263][ T5068] ? avc_has_perm_noaudit+0x3a0/0x3a0 [ 74.497627][ T5068] pipe_write+0x13a/0x1bc0 [ 74.502042][ T5068] ? rcu_is_watching+0x12/0xb0 [ 74.506805][ T5068] ? trace_contention_end+0xd6/0x100 [ 74.512088][ T5068] ? do_proc_dopipe_max_size_conv+0x1b0/0x1b0 [ 74.518153][ T5068] ? file_has_perm+0x286/0x350 [ 74.522910][ T5068] ? bpf_fd_pass+0x290/0x290 [ 74.527493][ T5068] do_iter_readv_writev+0x21e/0x3c0 [ 74.532691][ T5068] ? generic_copy_file_range+0x1d0/0x1d0 [ 74.538319][ T5068] ? selinux_file_permission+0x126/0x590 [ 74.543946][ T5068] ? security_file_permission+0x94/0x100 [ 74.549576][ T5068] do_iter_write+0x17f/0x7f0 [ 74.554164][ T5068] vfs_iter_write+0x7a/0xb0 [ 74.558688][ T5068] iter_file_splice_write+0x698/0xc00 [ 74.564067][ T5068] ? splice_from_pipe_next+0x5d0/0x5d0 [ 74.569522][ T5068] ? lockdep_hardirqs_on_prepare+0x420/0x420 [ 74.575508][ T5068] ? security_file_permission+0x94/0x100 [ 74.581145][ T5068] ? splice_from_pipe_next+0x5d0/0x5d0 [ 74.586690][ T5068] do_splice+0x127f/0x1f80 [ 74.591107][ T5068] ? splice_file_to_pipe+0x120/0x120 [ 74.596393][ T5068] ? direct_file_splice_eof+0xb0/0xb0 [ 74.601763][ T5068] ? reacquire_held_locks+0x4c0/0x4c0 [ 74.607147][ T5068] __do_splice+0x323/0x360 [ 74.611565][ T5068] ? do_splice+0x1f80/0x1f80 [ 74.616152][ T5068] __x64_sys_splice+0x1ce/0x260 [ 74.621034][ T5068] do_syscall_64+0x40/0x110 [ 74.625537][ T5068] entry_SYSCALL_64_after_hwframe+0x63/0x6b [ 74.631437][ T5068] RIP: 0033:0x7f421e08ec79 [ 74.635843][ T5068] 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 [ 74.655445][ T5068] RSP: 002b:00007f421e051228 EFLAGS: 00000246 ORIG_RAX: 0000000000000113 [ 74.663854][ T5068] RAX: ffffffffffffffda RBX: 00007f421e118328 RCX: 00007f421e08ec79 [ 74.671819][ T5068] RDX: 0000000000000006 RSI: 0000000000000000 RDI: 0000000000000003 [ 74.679780][ T5068] RBP: 00007f421e118320 R08: 0000000000000004 R09: 0000000000000000 [ 74.687743][ T5068] R10: 0000000000000000 R11: 0000000000000246 R12: 00007f421e11832c [ 74.695792][ T5068] R13: 0000000000000000 R14: 00007fffc32f3560 R15: 00007fffc32f3648 [pid 5069] futex(0x7f421e118338, FUTEX_WAIT_PRIVATE, 0, NULL [pid 5068] <... splice resumed>) = -1 EXDEV (Invalid cross-device link) [pid 5068] futex(0x7f421e11832c, FUTEX_WAKE_PRIVATE, 1000000 [pid 5067] exit_group(0 [pid 5068] <... futex resumed>) = 231 [pid 5067] <... exit_group resumed>) = ? [pid 5069] <... futex resumed>) = ? [pid 5068] +++ exited with 0 +++ [pid 5069] +++ exited with 0 +++ +++ exited with 0 +++ [ 74.703762][ T5068]