I0504 04:31:10.596298 12983 x:0] *************************** I0504 04:31:10.598137 12983 x:0] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -platform=ptrace -file-access=shared -network=sandbox exec -user=0:0 -cap CAP_CHOWN -cap CAP_DAC_OVERRIDE -cap CAP_DAC_READ_SEARCH -cap CAP_FOWNER -cap CAP_FSETID -cap CAP_KILL -cap CAP_SETGID -cap CAP_SETUID -cap CAP_SETPCAP -cap CAP_LINUX_IMMUTABLE -cap CAP_NET_BIND_SERVICE -cap CAP_NET_BROADCAST -cap CAP_NET_ADMIN -cap CAP_NET_RAW -cap CAP_IPC_LOCK -cap CAP_IPC_OWNER -cap CAP_SYS_MODULE -cap CAP_SYS_RAWIO -cap CAP_SYS_CHROOT -cap CAP_SYS_PTRACE -cap CAP_SYS_PACCT -cap CAP_SYS_ADMIN -cap CAP_SYS_BOOT -cap CAP_SYS_NICE -cap CAP_SYS_RESOURCE -cap CAP_SYS_TIME -cap CAP_SYS_TTY_CONFIG -cap CAP_MKNOD -cap CAP_LEASE -cap CAP_AUDIT_WRITE -cap CAP_AUDIT_CONTROL -cap CAP_SETFCAP -cap CAP_MAC_OVERRIDE -cap CAP_MAC_ADMIN -cap CAP_SYSLOG -cap CAP_WAKE_ALARM -cap CAP_BLOCK_SUSPEND -cap CAP_AUDIT_READ ci-gvisor-ptrace-proxy-sandbox-race-0 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=4 -repeat=0 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller637897688] I0504 04:31:10.598888 12983 x:0] Version release-20190304.1-170-g8972e47a2edb I0504 04:31:10.598984 12983 x:0] PID: 12983 I0504 04:31:10.599090 12983 x:0] UID: 0, GID: 0 I0504 04:31:10.599488 12983 x:0] Configuration: I0504 04:31:10.599556 12983 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0504 04:31:10.599651 12983 x:0] Platform: ptrace I0504 04:31:10.599724 12983 x:0] FileAccess: shared, overlay: false I0504 04:31:10.599806 12983 x:0] Network: sandbox, logging: false I0504 04:31:10.599886 12983 x:0] Strace: false, max size: 1024, syscalls: [] I0504 04:31:10.599981 12983 x:0] *************************** D0504 04:31:10.608744 12983 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:10.656602 20268 x:0] Clock(Monotonic): error: 152 ns, adjusted frequency from 2299813796 Hz to 2299814149 Hz D0504 04:31:10.657009 20268 x:0] Clock(Realtime): error: 159 ns, adjusted frequency from 2299814060 Hz to 2299814159 Hz D0504 04:31:10.657063 20268 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:6490005464229085 monotonicBaseRef:4001567614 monotonicFrequency:2299814149 realtimeReady:1 realtimeBaseCycles:6490005465426796 realtimeBaseRef:1556944270657001703 realtimeFrequency:2299814159} D0504 04:31:10.658238 12983 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0504 04:31:10.677049 12983 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:10.677235 12983 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:10.678524 20268 x:0] urpc: unmarshal success. D0504 04:31:10.678831 20268 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0504 04:31:10.678998 20268 x:0] urpc: successfully marshalled 37 bytes. D0504 04:31:10.679663 12983 x:0] urpc: successfully marshalled 117 bytes. D0504 04:31:10.680030 12983 x:0] urpc: unmarshal success. D0504 04:31:10.680586 12983 x:0] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-0", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=4 -repeat=0 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller637897688 D0504 04:31:10.691640 12983 x:0] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-0" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:10.691837 12983 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:10.694798 20268 x:0] urpc: unmarshal success. D0504 04:31:10.697124 20268 x:0] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=4 -repeat=0 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller637897688 I0504 04:31:10.697770 20268 x:0] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=4 -repeat=0 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller637897688] D0504 04:31:10.698375 20268 x:0] send [FD 7] [Tag 000001] Twalkgetattr{FID: 1, NewFID: 4, Names: [syz-execprog]} D0504 04:31:10.698682 12983 x:0] urpc: successfully marshalled 579 bytes. D0504 04:31:10.699485 20268 x:0] recv [FD 7] [Tag 000001] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 16280512, BlockSize: 4096, Blocks: 31800, ATime: {Sec: 1556817958, NanoSec: 715150015}, MTime: {Sec: 1556817958, NanoSec: 715150015}, CTime: {Sec: 1556944270, NanoSec: 366371371}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 9704698}]} D0504 04:31:10.699751 20268 x:0] send [FD 7] [Tag 000001] Tgetattr{FID: 4, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0504 04:31:10.700740 20268 x:0] recv [FD 7] [Tag 000001] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 9704698}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 16280512, BlockSize: 4096, Blocks: 31800, ATime: {Sec: 1556817958, NanoSec: 715150015}, MTime: {Sec: 1556817958, NanoSec: 715150015}, CTime: {Sec: 1556944270, NanoSec: 366371371}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0504 04:31:10.700837 20268 x:0] send [FD 7] [Tag 000001] Twalk{FID: 4, NewFID: 6, Names: []} D0504 04:31:10.701427 20268 x:0] recv [FD 7] [Tag 000001] Rwalk{QIDs: []} D0504 04:31:10.701532 20268 x:0] send [FD 7] [Tag 000001] Tlopen{FID: 6, Flags: ReadOnly} D0504 04:31:10.702140 20268 x:0] recv [FD 7] [Tag 000001] Rlopen{QID: QID{Type: 0, Version: 0, Path: 9704698}, IoUnit: 0, File: &{{29}}} D0504 04:31:10.705091 20268 x:0] Allocating stack with size of 8388608 bytes D0504 04:31:10.709083 20268 x:0] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-0 0}:0xc000298d60 {ci-gvisor-ptrace-proxy-sandbox-race-0 18}:0xc000299400] D0504 04:31:10.709699 20268 x:0] urpc: successfully marshalled 37 bytes. D0504 04:31:10.710064 12983 x:0] urpc: unmarshal success. D0504 04:31:10.710176 12983 x:0] Wait on PID 18 in container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:10.710267 12983 x:0] Waiting for PID 18 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:10.710351 12983 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:10.711753 12983 x:0] urpc: successfully marshalled 119 bytes. D0504 04:31:10.711267 20268 x:0] urpc: unmarshal success. D0504 04:31:10.711647 20268 x:0] containerManager.Wait D0504 04:31:10.763970 20268 x:0] [ 18] Interrupt queued D0504 04:31:11.572791 20268 x:0] [ 19] Interrupt queued D0504 04:31:11.588724 20268 x:0] [ 20] Interrupt queued D0504 04:31:11.610945 20268 x:0] [ 21] Interrupt queued D0504 04:31:11.656385 20268 x:0] Clock(Monotonic): error: -11 ns, adjusted frequency from 2299814149 Hz to 2299813758 Hz D0504 04:31:11.656503 20268 x:0] Clock(Realtime): error: -73 ns, adjusted frequency from 2299814159 Hz to 2299813653 Hz D0504 04:31:11.656539 20268 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:6490007763734395 monotonicBaseRef:5001433325 monotonicFrequency:2299813758 realtimeReady:1 realtimeBaseCycles:6490007764086117 realtimeBaseRef:1556944271656499558 realtimeFrequency:2299813653} D0504 04:31:11.840502 20268 x:0] [ 22] Interrupt queued D0504 04:31:11.868623 20268 x:0] [ 23] Interrupt queued D0504 04:31:11.893582 20268 x:0] [ 24] Interrupt queued D0504 04:31:11.955017 20268 x:0] send [FD 7] [Tag 000001] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0504 04:31:11.955721 20268 x:0] recv [FD 7] [Tag 000001] Rlerror{Error: 2} D0504 04:31:12.303102 20268 x:0] [ 25] Interrupt queued D0504 04:31:12.305353 20268 x:0] [ 26] Interrupt queued D0504 04:31:12.327548 20268 x:0] [ 27] Interrupt queued D0504 04:31:12.361707 20268 x:0] [ 28] Interrupt queued D0504 04:31:12.532738 20268 x:0] [ 29] Interrupt queued D0504 04:31:12.598634 20268 x:0] [ 30] Interrupt queued D0504 04:31:12.655412 20268 x:0] Clock(Monotonic): error: -18 ns, adjusted frequency from 2299813758 Hz to 2299813784 Hz D0504 04:31:12.656097 20268 x:0] Clock(Realtime): error: 37 ns, adjusted frequency from 2299813653 Hz to 2299813919 Hz D0504 04:31:12.656137 20268 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:6490010061361272 monotonicBaseRef:6000482430 monotonicFrequency:2299813784 realtimeReady:1 realtimeBaseCycles:6490010062951138 realtimeBaseRef:1556944272656087075 realtimeFrequency:2299813919} runtime/cgo: pthread_create failed: Resource temporarily unavailable W0504 04:31:13.019298 12983 x:0] FATAL ERROR: waiting on pid 18: waiting on PID 18 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 18: waiting on PID 18 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0": urpc method "containerManager.WaitPID" failed: EOF DIAGNOSIS: I0504 04:31:13.258848 27910 x:0] *************************** I0504 04:31:13.263835 27910 x:0] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -platform=ptrace -file-access=shared -network=sandbox debug -stacks ci-gvisor-ptrace-proxy-sandbox-race-0] I0504 04:31:13.264511 27910 x:0] Version release-20190304.1-170-g8972e47a2edb I0504 04:31:13.264868 27910 x:0] PID: 27910 I0504 04:31:13.265373 27910 x:0] UID: 0, GID: 0 I0504 04:31:13.265438 27910 x:0] Configuration: I0504 04:31:13.265482 27910 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0504 04:31:13.270049 27910 x:0] Platform: ptrace I0504 04:31:13.270207 27910 x:0] FileAccess: shared, overlay: false I0504 04:31:13.270721 27910 x:0] Network: sandbox, logging: false I0504 04:31:13.270834 27910 x:0] Strace: false, max size: 1024, syscalls: [] I0504 04:31:13.271250 27910 x:0] *************************** D0504 04:31:13.271598 27910 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:13.273866 27910 x:0] List containers "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" W0504 04:31:13.275073 27910 x:0] FATAL ERROR: loading container "ci-gvisor-ptrace-proxy-sandbox-race-0": file does not exist loading container "ci-gvisor-ptrace-proxy-sandbox-race-0": file does not exist Error collecting stacks: failed to run ["/syzkaller/managers/ptrace-proxy-sandbox-race/current/image" "-root" "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-platform=ptrace" "-file-access=shared" "-network=sandbox" "debug" "-stacks" "ci-gvisor-ptrace-proxy-sandbox-race-0"]: exit status 128 I0504 04:31:13.258848 27910 x:0] *************************** I0504 04:31:13.263835 27910 x:0] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -platform=ptrace -file-access=shared -network=sandbox debug -stacks ci-gvisor-ptrace-proxy-sandbox-race-0] I0504 04:31:13.264511 27910 x:0] Version release-20190304.1-170-g8972e47a2edb I0504 04:31:13.264868 27910 x:0] PID: 27910 I0504 04:31:13.265373 27910 x:0] UID: 0, GID: 0 I0504 04:31:13.265438 27910 x:0] Configuration: I0504 04:31:13.265482 27910 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0504 04:31:13.270049 27910 x:0] Platform: ptrace I0504 04:31:13.270207 27910 x:0] FileAccess: shared, overlay: false I0504 04:31:13.270721 27910 x:0] Network: sandbox, logging: false I0504 04:31:13.270834 27910 x:0] Strace: false, max size: 1024, syscalls: [] I0504 04:31:13.271250 27910 x:0] *************************** D0504 04:31:13.271598 27910 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:13.273866 27910 x:0] List containers "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" W0504 04:31:13.275073 27910 x:0] FATAL ERROR: loading container "ci-gvisor-ptrace-proxy-sandbox-race-0": file does not exist loading container "ci-gvisor-ptrace-proxy-sandbox-race-0": file does not exist W0504 04:31:13.025011 19985 x:0] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-0" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0504 04:31:13.025990 19985 x:0] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:13.026138 19985 x:0] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:13.026274 19985 x:0] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-0" by destroying sandbox D0504 04:31:13.026310 19985 x:0] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0504 04:31:13.026393 19985 x:0] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-0", PID: 20263 I0504 04:31:13.131503 19985 x:0] Exiting with status: 6