D1202 13:57:15.467559 35511 x:0] Clock(Monotonic): error: 231 ns, adjusted frequency from 2299805585 Hz to 2299806331 Hz D1202 13:57:15.467876 35511 x:0] Clock(Realtime): error: 167 ns, adjusted frequency from 2299805642 Hz to 2299806285 Hz D1202 13:57:15.467969 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398536301432687 monotonicBaseRef:2000318712 monotonicFrequency:2299806331 realtimeReady:1 realtimeBaseCycles:39398536302277214 realtimeBaseRef:1575295035467870261 realtimeFrequency:2299806285} I1202 13:57:15.788397 35744 x:0] *************************** I1202 13:57:15.788562 35744 x:0] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot 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-test-2 /syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true] I1202 13:57:15.789483 35744 x:0] Version release-20191114.0-43-gaa70523da215 I1202 13:57:15.789544 35744 x:0] PID: 35744 I1202 13:57:15.789582 35744 x:0] UID: 0, GID: 0 I1202 13:57:15.789691 35744 x:0] Configuration: I1202 13:57:15.789740 35744 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root I1202 13:57:15.789804 35744 x:0] Platform: ptrace I1202 13:57:15.789859 35744 x:0] FileAccess: shared, overlay: false I1202 13:57:15.789947 35744 x:0] Network: sandbox, logging: false I1202 13:57:15.790008 35744 x:0] Strace: false, max size: 1024, syscalls: [] I1202 13:57:15.790068 35744 x:0] *************************** W1202 13:57:15.791674 35744 x:0] Block the TERM signal. This is only safe in tests! D1202 13:57:15.792535 35744 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:15.824297 35744 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-test-2": signal 0 D1202 13:57:15.824445 35744 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:15.824531 35744 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:15.826844 35744 x:0] urpc: successfully marshalled 122 bytes. D1202 13:57:15.827115 35511 x:0] urpc: unmarshal success. D1202 13:57:15.827285 35511 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-test-2 Signo:0 PID:0 Mode:Process} D1202 13:57:15.827531 35511 x:0] urpc: successfully marshalled 37 bytes. D1202 13:57:15.827810 35744 x:0] urpc: unmarshal success. D1202 13:57:15.827908 35744 x:0] Exec arguments: /syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true D1202 13:57:15.827976 35744 x:0] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D1202 13:57:15.828309 35744 x:0] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-test-2", args: /syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true D1202 13:57:15.828471 35744 x:0] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-test-2" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:15.828580 35744 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:15.830417 35511 x:0] urpc: unmarshal success. D1202 13:57:15.831560 35511 x:0] containerManager.ExecuteAsync: /syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true D1202 13:57:15.831741 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D1202 13:57:15.832795 35511 x:0] recv [channel @0xc0003de600] Rlerror{Error: 2} D1202 13:57:15.832779 35744 x:0] urpc: successfully marshalled 573 bytes. I1202 13:57:15.833223 35511 x:0] EXEC: [/syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true] D1202 13:57:15.833675 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-fuzzer]} D1202 13:57:15.835450 35511 x:0] recv [channel @0xc0003de600] 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: 21953408, BlockSize: 4096, Blocks: 42880, ATime: {Sec: 1575226840, NanoSec: 884869437}, MTime: {Sec: 1575226840, NanoSec: 884869437}, CTime: {Sec: 1575295035, NanoSec: 687800655}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124498}]} D1202 13:57:15.835924 35511 x:0] send [channel @0xc0003de600] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1202 13:57:15.836913 35511 x:0] recv [channel @0xc0003de600] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124498}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 21953408, BlockSize: 4096, Blocks: 42880, ATime: {Sec: 1575226840, NanoSec: 884869437}, MTime: {Sec: 1575226840, NanoSec: 884869437}, CTime: {Sec: 1575295035, NanoSec: 687800655}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1202 13:57:15.837027 35511 x:0] send [channel @0xc0003de600] Twalk{FID: 5, NewFID: 6, Names: []} D1202 13:57:15.837866 35511 x:0] recv [channel @0xc0003de600] Rwalk{QIDs: []} D1202 13:57:15.837965 35511 x:0] send [channel @0xc0003de600] Tlopen{FID: 6, Flags: ReadOnly} D1202 13:57:15.838717 35511 x:0] recv [channel @0xc0003de600] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16124498}, IoUnit: 0, File: &{{33}}} D1202 13:57:15.847578 35511 x:0] Allocating stack with size of 8388608 bytes D1202 13:57:15.850180 35511 x:0] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-test-2 0}:0xc0002fc3e0 {ci-gvisor-ptrace-proxy-sandbox-race-test-2 17}:0xc0004112a0] D1202 13:57:15.850485 35511 x:0] urpc: successfully marshalled 37 bytes. D1202 13:57:15.850894 35744 x:0] urpc: unmarshal success. D1202 13:57:15.851111 35744 x:0] Wait on PID 17 in container "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:15.851312 35744 x:0] Waiting for PID 17 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:15.851404 35744 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:15.851719 35744 x:0] urpc: successfully marshalled 105 bytes. D1202 13:57:15.862158 35511 x:0] [ 17] Interrupt queued D1202 13:57:15.852314 35511 x:0] urpc: unmarshal success. D1202 13:57:15.869214 35511 x:0] containerManager.Wait D1202 13:57:16.253901 35511 x:0] [ 18] Interrupt queued D1202 13:57:16.263541 35511 x:0] [ 19] Interrupt queued D1202 13:57:16.270927 35511 x:0] [ 20] Interrupt queued D1202 13:57:16.330658 35511 x:0] [ 21] Interrupt queued D1202 13:57:16.443770 35511 x:0] [ 22] Interrupt queued D1202 13:57:16.454302 35511 x:0] [ 23] Interrupt queued D1202 13:57:16.467383 35511 x:0] Clock(Monotonic): error: 190 ns, adjusted frequency from 2299806331 Hz to 2299806351 Hz D1202 13:57:16.468925 35511 x:0] Clock(Realtime): error: -64 ns, adjusted frequency from 2299806285 Hz to 2299805768 Hz D1202 13:57:16.469014 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398538600937118 monotonicBaseRef:3000187440 monotonicFrequency:2299806351 realtimeReady:1 realtimeBaseCycles:39398538604491824 realtimeBaseRef:1575295036468917447 realtimeFrequency:2299805768} D1202 13:57:16.490456 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D1202 13:57:16.491048 35511 x:0] recv [channel @0xc0003de600] Rlerror{Error: 2} D1202 13:57:16.552642 35511 x:0] [ 24] Interrupt queued 2019/12/02 13:57:16 fuzzer started D1202 13:57:16.620275 35511 x:0] [ 25] Interrupt queued D1202 13:57:16.693317 35511 x:0] [ 27] Interrupt queued D1202 13:57:16.693953 35511 x:0] [ 26] Interrupt queued D1202 13:57:16.704880 35511 x:0] [ 28] Interrupt queued D1202 13:57:16.728805 35511 x:0] [ 29] Interrupt queued D1202 13:57:16.754039 35511 x:0] [ 30] Interrupt queued D1202 13:57:16.784059 35511 x:0] [ 31] Interrupt queued D1202 13:57:16.795373 35511 x:0] [ 32] Interrupt queued D1202 13:57:16.837666 35511 x:0] [ 33] Interrupt queued D1202 13:57:16.854364 35511 x:0] [ 34] Interrupt queued 2019/12/02 13:57:16 connecting to host at stdin D1202 13:57:16.864858 35511 x:0] [ 35] Interrupt queued 2019/12/02 13:57:16 checking machine... 2019/12/02 13:57:16 checking revisions... D1202 13:57:16.873596 35511 x:0] [ 17] Entering internal stop (*kernel.vforkStop)(nil) D1202 13:57:16.874224 35511 x:0] [ 36] Interrupt queued D1202 13:57:16.881748 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 7, Names: [syz-executor]} D1202 13:57:16.883560 35511 x:0] recv [channel @0xc0003de600] 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: 1550176, BlockSize: 4096, Blocks: 3032, ATime: {Sec: 1575226829, NanoSec: 635921298}, MTime: {Sec: 1575226829, NanoSec: 635921298}, CTime: {Sec: 1575295035, NanoSec: 691800992}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124502}]} D1202 13:57:16.883995 35511 x:0] send [channel @0xc0003de600] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1202 13:57:16.885186 35511 x:0] recv [channel @0xc0003de600] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124502}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1550176, BlockSize: 4096, Blocks: 3032, ATime: {Sec: 1575226829, NanoSec: 635921298}, MTime: {Sec: 1575226829, NanoSec: 635921298}, CTime: {Sec: 1575295035, NanoSec: 691800992}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1202 13:57:16.888009 35511 x:0] send [channel @0xc0003de600] Twalk{FID: 7, NewFID: 8, Names: []} D1202 13:57:16.888630 35511 x:0] recv [channel @0xc0003de600] Rwalk{QIDs: []} D1202 13:57:16.888835 35511 x:0] send [channel @0xc0003de600] Tlopen{FID: 8, Flags: ReadOnly} D1202 13:57:16.889927 35511 x:0] recv [channel @0xc0003de600] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16124502}, IoUnit: 0, File: &{{27}}} D1202 13:57:16.893283 35511 x:0] [ 36] Allocating stack with size of 8388608 bytes D1202 13:57:16.894235 35511 x:0] [ 17] Leaving internal stop (*kernel.vforkStop)(nil) D1202 13:57:16.956675 35511 x:0] [ 37] Interrupt queued D1202 13:57:16.966456 35511 x:0] [ 38] Interrupt queued D1202 13:57:16.992463 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 9, Names: [etc]} D1202 13:57:16.992966 35511 x:0] recv [channel @0xc0003de600] Rlerror{Error: 2} D1202 13:57:16.997627 35511 x:0] [ 36] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:17.001031 35511 x:0] [ 36] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:17.001197 35511 x:0] [ 17] Notified of signal 17 D1202 13:57:17.001258 35511 x:0] [ 17] Interrupt queued D1202 13:57:17.001591 35511 x:0] [ 17] Interrupt queued D1202 13:57:17.001704 35511 x:0] [ 17] Restarting syscall 247 after errno 512: interrupted by signal 17 D1202 13:57:17.001778 35511 x:0] [ 17] Signal 17: delivering to handler D1202 13:57:17.003136 35511 x:0] [ 36] Transitioning from exit state TaskExitZombie to TaskExitDead 2019/12/02 13:57:17 testing simple program... D1202 13:57:17.027220 35511 x:0] [ 17] Entering internal stop (*kernel.vforkStop)(nil) D1202 13:57:17.027922 35511 x:0] [ 39] Interrupt queued D1202 13:57:17.033167 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D1202 13:57:17.044560 35511 x:0] recv [channel @0xc0003de600] 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: 1550176, BlockSize: 4096, Blocks: 3032, ATime: {Sec: 1575226829, NanoSec: 635921298}, MTime: {Sec: 1575226829, NanoSec: 635921298}, CTime: {Sec: 1575295035, NanoSec: 691800992}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124502}]} D1202 13:57:17.044861 35511 x:0] send [channel @0xc0003de600] Tclunk{FID: 9} D1202 13:57:17.046132 35511 x:0] recv [channel @0xc0003de600] Rclunk{} D1202 13:57:17.047016 35511 x:0] send [channel @0xc0003de600] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1202 13:57:17.049348 35511 x:0] recv [channel @0xc0003de600] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124502}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1550176, BlockSize: 4096, Blocks: 3032, ATime: {Sec: 1575226829, NanoSec: 635921298}, MTime: {Sec: 1575226829, NanoSec: 635921298}, CTime: {Sec: 1575295035, NanoSec: 691800992}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1202 13:57:17.051030 35511 x:0] [ 39] Allocating stack with size of 8388608 bytes D1202 13:57:17.055892 35511 x:0] [ 17] Leaving internal stop (*kernel.vforkStop)(nil) D1202 13:57:17.104314 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 9, Names: [etc]} D1202 13:57:17.104927 35511 x:0] recv [channel @0xc0003de600] Rlerror{Error: 2} D1202 13:57:17.110265 35511 x:0] [ 39] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:17.111270 35511 x:0] [ 39] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:17.111372 35511 x:0] [ 17] Notified of signal 17 D1202 13:57:17.111414 35511 x:0] [ 17] Interrupt queued D1202 13:57:17.112070 35511 x:0] [ 17] Interrupt queued D1202 13:57:17.112180 35511 x:0] [ 17] Restarting syscall 247 after errno 512: interrupted by signal 17 D1202 13:57:17.112253 35511 x:0] [ 17] Signal 17: delivering to handler D1202 13:57:17.114136 35511 x:0] [ 39] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:17.122795 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D1202 13:57:17.123989 35511 x:0] recv [channel @0xc0003de600] 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: 1550176, BlockSize: 4096, Blocks: 3032, ATime: {Sec: 1575226829, NanoSec: 635921298}, MTime: {Sec: 1575226829, NanoSec: 635921298}, CTime: {Sec: 1575295035, NanoSec: 691800992}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124502}]} D1202 13:57:17.124483 35511 x:0] send [channel @0xc0003de600] Tclunk{FID: 9} D1202 13:57:17.124921 35511 x:0] recv [channel @0xc0003de600] Rclunk{} D1202 13:57:17.125129 35511 x:0] send [channel @0xc0003de600] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1202 13:57:17.125827 35511 x:0] recv [channel @0xc0003de600] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124502}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1550176, BlockSize: 4096, Blocks: 3032, ATime: {Sec: 1575226829, NanoSec: 635921298}, MTime: {Sec: 1575226829, NanoSec: 635921298}, CTime: {Sec: 1575295035, NanoSec: 691800992}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1202 13:57:17.152723 35511 x:0] [ 17] Entering internal stop (*kernel.vforkStop)(nil) D1202 13:57:17.153521 35511 x:0] [ 40] Interrupt queued D1202 13:57:17.161195 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D1202 13:57:17.166841 35511 x:0] recv [channel @0xc0003de600] 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: 1550176, BlockSize: 4096, Blocks: 3032, ATime: {Sec: 1575226829, NanoSec: 635921298}, MTime: {Sec: 1575226829, NanoSec: 635921298}, CTime: {Sec: 1575295035, NanoSec: 691800992}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124502}]} D1202 13:57:17.168978 35511 x:0] send [channel @0xc0003de600] Tclunk{FID: 9} D1202 13:57:17.169384 35511 x:0] recv [channel @0xc0003de600] Rclunk{} D1202 13:57:17.169621 35511 x:0] send [channel @0xc0003de600] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1202 13:57:17.171039 35511 x:0] recv [channel @0xc0003de600] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124502}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1550176, BlockSize: 4096, Blocks: 3032, ATime: {Sec: 1575226829, NanoSec: 635921298}, MTime: {Sec: 1575226829, NanoSec: 635921298}, CTime: {Sec: 1575295035, NanoSec: 691800992}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1202 13:57:17.172198 35511 x:0] [ 40] Allocating stack with size of 8388608 bytes D1202 13:57:17.173345 35511 x:0] [ 17] Leaving internal stop (*kernel.vforkStop)(nil) D1202 13:57:17.187541 35511 x:0] send [channel @0xc0003de600] Twalkgetattr{FID: 1, NewFID: 9, Names: [etc]} D1202 13:57:17.188026 35511 x:0] recv [channel @0xc0003de600] Rlerror{Error: 2} D1202 13:57:17.222374 35511 x:0] [ 41] Interrupt queued D1202 13:57:17.342407 35511 x:0] [ 42] Interrupt queued D1202 13:57:17.370773 35511 x:0] [ 43] Interrupt queued D1202 13:57:17.467994 35511 x:0] Clock(Monotonic): error: 6 ns, adjusted frequency from 2299806351 Hz to 2299805979 Hz D1202 13:57:17.468298 35511 x:0] Clock(Realtime): error: 248 ns, adjusted frequency from 2299805768 Hz to 2299806554 Hz D1202 13:57:17.468978 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398540902101587 monotonicBaseRef:4000777975 monotonicFrequency:2299805979 realtimeReady:1 realtimeBaseCycles:39398540902852941 realtimeBaseRef:1575295037468289284 realtimeFrequency:2299806554} D1202 13:57:18.182041 35511 x:0] [ 44] Interrupt queued D1202 13:57:18.185107 35511 x:0] [ 43] Interrupt queued D1202 13:57:18.185213 35511 x:0] [ 44] Interrupt queued D1202 13:57:18.185280 35511 x:0] [ 42] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.185410 35511 x:0] [ 42] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.185494 35511 x:0] [ 43] Interrupt queued D1202 13:57:18.185669 35511 x:0] [ 43] Signal 9: terminating thread group I1202 13:57:18.185889 35511 x:0] Uncaught signal: "killed" (9), PID: 42, TID: 43, fault addr: 0x0 D1202 13:57:18.186102 35511 x:0] [ 43] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.186297 35511 x:0] [ 43] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.186375 35511 x:0] [ 43] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.187683 35511 x:0] [ 44] Interrupt queued D1202 13:57:18.187935 35511 x:0] [ 44] Signal 9: terminating thread group I1202 13:57:18.189132 35511 x:0] Uncaught signal: "killed" (9), PID: 42, TID: 44, fault addr: 0x0 D1202 13:57:18.189338 35511 x:0] [ 44] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.190809 35511 x:0] [ 44] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.190983 35511 x:0] [ 44] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.191324 35511 x:0] [ 41] Discarding ignored signal 17 D1202 13:57:18.191648 35511 x:0] [ 42] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.194617 35511 x:0] [ 40] Interrupt queued D1202 13:57:18.194722 35511 x:0] [ 40] Notified of signal 9 D1202 13:57:18.194787 35511 x:0] [ 40] Interrupt queued D1202 13:57:18.195274 35511 x:0] [ 40] Dropping duplicate interrupt D1202 13:57:18.195418 35511 x:0] [ 40] Signal 9: terminating thread group I1202 13:57:18.195518 35511 x:0] Uncaught signal: "killed" (9), PID: 40, TID: 40, fault addr: 0x0 D1202 13:57:18.195621 35511 x:0] [ 40] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.196487 35511 x:0] [ 41] Interrupt queued D1202 13:57:18.196580 35511 x:0] [ 41] No task notified of signal 9 D1202 13:57:18.196650 35511 x:0] [ 40] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.196764 35511 x:0] [ 17] Notified of signal 17 D1202 13:57:18.196825 35511 x:0] [ 17] Interrupt queued D1202 13:57:18.197408 35511 x:0] [ 17] Interrupt queued D1202 13:57:18.197535 35511 x:0] [ 17] Restarting syscall 202 after errno 512: interrupted by signal 17 D1202 13:57:18.197617 35511 x:0] [ 17] Signal 17: delivering to handler D1202 13:57:18.198235 35511 x:0] [ 41] Signal 9: terminating thread group I1202 13:57:18.198431 35511 x:0] Uncaught signal: "killed" (9), PID: 41, TID: 41, fault addr: 0x0 D1202 13:57:18.198657 35511 x:0] [ 41] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.201138 35511 x:0] [ 40] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.206338 35511 x:0] [ 41] Init process terminating, killing namespace D1202 13:57:18.206455 35511 x:0] [ 41] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.206567 35511 x:0] [ 1] Notified of signal 17 D1202 13:57:18.206625 35511 x:0] [ 1] Interrupt queued D1202 13:57:18.207178 35511 x:0] [ 1] Interrupt queued D1202 13:57:18.207773 35511 x:0] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 17 D1202 13:57:18.207929 35511 x:0] [ 1] Signal 17: delivering to handler 2019/12/02 13:57:18 BUG: simple call failed: {Flags:5 Signal:[65484552] Cover:[] Comps: Errno:999} D1202 13:57:18.232802 35511 x:0] [ 17] Interrupt queued D1202 13:57:18.232921 35511 x:0] [ 18] Interrupt queued D1202 13:57:18.232985 35511 x:0] [ 19] Interrupt queued D1202 13:57:18.233038 35511 x:0] [ 20] Interrupt queued D1202 13:57:18.233112 35511 x:0] [ 21] Interrupt queued D1202 13:57:18.233161 35511 x:0] [ 22] Interrupt queued D1202 13:57:18.233133 35511 x:0] [ 20] Interrupt queued D1202 13:57:18.233219 35511 x:0] [ 23] Interrupt queued D1202 13:57:18.233281 35511 x:0] [ 24] Interrupt queued D1202 13:57:18.233272 35511 x:0] [ 18] Interrupt queued D1202 13:57:18.233362 35511 x:0] [ 25] Interrupt queued D1202 13:57:18.233442 35511 x:0] [ 26] Interrupt queued D1202 13:57:18.233457 35511 x:0] [ 19] Interrupt queued D1202 13:57:18.233503 35511 x:0] [ 27] Interrupt queued D1202 13:57:18.233551 35511 x:0] [ 21] Interrupt queued D1202 13:57:18.233572 35511 x:0] [ 28] Interrupt queued D1202 13:57:18.233649 35511 x:0] [ 29] Interrupt queued D1202 13:57:18.233702 35511 x:0] [ 30] Interrupt queued D1202 13:57:18.233759 35511 x:0] [ 31] Interrupt queued D1202 13:57:18.233744 35511 x:0] [ 27] Interrupt queued D1202 13:57:18.233853 35511 x:0] [ 28] Interrupt queued D1202 13:57:18.233819 35511 x:0] [ 32] Interrupt queued D1202 13:57:18.233916 35511 x:0] [ 22] Interrupt queued D1202 13:57:18.233936 35511 x:0] [ 30] Interrupt queued D1202 13:57:18.233983 35511 x:0] [ 29] Interrupt queued D1202 13:57:18.233996 35511 x:0] [ 24] Interrupt queued D1202 13:57:18.234090 35511 x:0] [ 33] Interrupt queued D1202 13:57:18.237187 35511 x:0] [ 34] Interrupt queued D1202 13:57:18.237283 35511 x:0] [ 35] Interrupt queued D1202 13:57:18.237351 35511 x:0] [ 38] Interrupt queued D1202 13:57:18.237430 35511 x:0] [ 37] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.237638 35511 x:0] [ 34] Interrupt queued D1202 13:57:18.237542 35511 x:0] [ 37] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.237740 35511 x:0] [ 37] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.237784 35511 x:0] [ 34] Signal 9: terminating thread group D1202 13:57:18.237906 35511 x:0] [ 38] Interrupt queued I1202 13:57:18.237916 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 34, fault addr: 0x0 D1202 13:57:18.234081 35511 x:0] [ 31] Interrupt queued D1202 13:57:18.238048 35511 x:0] [ 35] Interrupt queued D1202 13:57:18.234126 35511 x:0] [ 26] Interrupt queued D1202 13:57:18.234140 35511 x:0] [ 33] Interrupt queued D1202 13:57:18.238239 35511 x:0] [ 20] Signal 9: terminating thread group D1202 13:57:18.234095 35511 x:0] [ 25] Interrupt queued D1202 13:57:18.238161 35511 x:0] [ 35] Signal 9: terminating thread group D1202 13:57:18.234161 35511 x:0] [ 32] Interrupt queued D1202 13:57:18.238126 35511 x:0] [ 31] Signal 9: terminating thread group D1202 13:57:18.238128 35511 x:0] [ 17] Signal 9: terminating thread group D1202 13:57:18.238047 35511 x:0] [ 38] Signal 9: terminating thread group D1202 13:57:18.238239 35511 x:0] [ 34] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.234152 35511 x:0] [ 23] Interrupt queued D1202 13:57:18.238238 35511 x:0] [ 26] Signal 9: terminating thread group D1202 13:57:18.238694 35511 x:0] [ 23] Signal 9: terminating thread group I1202 13:57:18.238667 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 17, fault addr: 0x0 I1202 13:57:18.238837 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 23, fault addr: 0x0 D1202 13:57:18.238942 35511 x:0] [ 34] Transitioning from exit state TaskExitInitiated to TaskExitZombie I1202 13:57:18.239011 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 20, fault addr: 0x0 D1202 13:57:18.239050 35511 x:0] [ 34] Transitioning from exit state TaskExitZombie to TaskExitDead I1202 13:57:18.239125 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 26, fault addr: 0x0 D1202 13:57:18.239240 35511 x:0] [ 26] Transitioning from exit state TaskExitNone to TaskExitInitiated I1202 13:57:18.239257 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 38, fault addr: 0x0 D1202 13:57:18.239385 35511 x:0] [ 19] Signal 9: terminating thread group I1202 13:57:18.239391 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 35, fault addr: 0x0 D1202 13:57:18.239470 35511 x:0] [ 21] Signal 9: terminating thread group D1202 13:57:18.238811 35511 x:0] [ 18] Signal 9: terminating thread group I1202 13:57:18.239502 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 31, fault addr: 0x0 D1202 13:57:18.239574 35511 x:0] [ 27] Signal 9: terminating thread group D1202 13:57:18.239680 35511 x:0] [ 28] Signal 9: terminating thread group D1202 13:57:18.239749 35511 x:0] [ 30] Signal 9: terminating thread group D1202 13:57:18.239818 35511 x:0] [ 29] Signal 9: terminating thread group D1202 13:57:18.239904 35511 x:0] [ 24] Signal 9: terminating thread group D1202 13:57:18.239994 35511 x:0] [ 25] Signal 9: terminating thread group D1202 13:57:18.240069 35511 x:0] [ 32] Signal 9: terminating thread group D1202 13:57:18.240130 35511 x:0] [ 17] Transitioning from exit state TaskExitNone to TaskExitInitiated I1202 13:57:18.240254 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 25, fault addr: 0x0 I1202 13:57:18.240461 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 19, fault addr: 0x0 I1202 13:57:18.240587 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 21, fault addr: 0x0 I1202 13:57:18.240709 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 27, fault addr: 0x0 I1202 13:57:18.240859 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 28, fault addr: 0x0 D1202 13:57:18.241026 35511 x:0] [ 33] Signal 9: terminating thread group I1202 13:57:18.241192 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 18, fault addr: 0x0 I1202 13:57:18.241297 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 30, fault addr: 0x0 I1202 13:57:18.241443 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 24, fault addr: 0x0 I1202 13:57:18.241662 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 29, fault addr: 0x0 I1202 13:57:18.241814 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 33, fault addr: 0x0 I1202 13:57:18.241951 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 32, fault addr: 0x0 D1202 13:57:18.242060 35511 x:0] [ 25] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242139 35511 x:0] [ 20] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242222 35511 x:0] [ 38] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242320 35511 x:0] [ 19] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242421 35511 x:0] [ 21] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242509 35511 x:0] [ 27] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242589 35511 x:0] [ 28] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242675 35511 x:0] [ 23] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242758 35511 x:0] [ 35] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242832 35511 x:0] [ 31] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242911 35511 x:0] [ 18] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.242975 35511 x:0] [ 30] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.243059 35511 x:0] [ 24] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.243128 35511 x:0] [ 29] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.243199 35511 x:0] [ 17] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.243362 35511 x:0] [ 33] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.243483 35511 x:0] [ 32] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.243859 35511 x:0] [ 35] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.243960 35511 x:0] [ 35] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.244146 35511 x:0] [ 31] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.244224 35511 x:0] [ 31] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.244396 35511 x:0] [ 18] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.244463 35511 x:0] [ 18] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.244644 35511 x:0] [ 30] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.244755 35511 x:0] [ 30] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.245257 35511 x:0] [ 24] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.245354 35511 x:0] [ 24] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.245553 35511 x:0] [ 29] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.245623 35511 x:0] [ 29] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.245845 35511 x:0] [ 33] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.245919 35511 x:0] [ 33] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.246109 35511 x:0] [ 32] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.246192 35511 x:0] [ 32] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.246350 35511 x:0] [ 25] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.246420 35511 x:0] [ 25] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.246549 35511 x:0] [ 20] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.246621 35511 x:0] [ 20] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.246760 35511 x:0] [ 26] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.246843 35511 x:0] [ 26] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.247000 35511 x:0] [ 38] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.247071 35511 x:0] [ 38] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.247295 35511 x:0] [ 19] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.247389 35511 x:0] [ 19] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.247548 35511 x:0] [ 21] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.247614 35511 x:0] [ 21] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.247792 35511 x:0] [ 27] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.247862 35511 x:0] [ 27] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.248014 35511 x:0] [ 28] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.248086 35511 x:0] [ 28] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.248241 35511 x:0] [ 23] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.248339 35511 x:0] [ 23] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.239835 35511 x:0] [ 22] Signal 9: terminating thread group I1202 13:57:18.248640 35511 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 22, fault addr: 0x0 D1202 13:57:18.248759 35511 x:0] [ 22] Transitioning from exit state TaskExitNone to TaskExitInitiated D1202 13:57:18.266520 35511 x:0] [ 22] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1202 13:57:18.266774 35511 x:0] [ 22] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.267004 35511 x:0] [ 17] Transitioning from exit state TaskExitZombie to TaskExitDead D1202 13:57:18.267279 35511 x:0] updated processes (removal): map[{ci-gvisor-ptrace-proxy-sandbox-race-test-2 0}:0xc0002fc3e0] D1202 13:57:18.268310 35744 x:0] urpc: unmarshal success. I1202 13:57:18.268411 35744 x:0] Exiting with status: 256 DIAGNOSIS: I1202 13:57:18.351941 36106 x:0] *************************** I1202 13:57:18.352122 36106 x:0] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks ci-gvisor-ptrace-proxy-sandbox-race-test-2] I1202 13:57:18.353218 36106 x:0] Version release-20191114.0-43-gaa70523da215 I1202 13:57:18.354536 36106 x:0] PID: 36106 I1202 13:57:18.355599 36106 x:0] UID: 0, GID: 0 I1202 13:57:18.355827 36106 x:0] Configuration: I1202 13:57:18.356029 36106 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root I1202 13:57:18.356152 36106 x:0] Platform: ptrace I1202 13:57:18.356280 36106 x:0] FileAccess: shared, overlay: false I1202 13:57:18.356519 36106 x:0] Network: sandbox, logging: false I1202 13:57:18.356714 36106 x:0] Strace: false, max size: 1024, syscalls: [] I1202 13:57:18.357007 36106 x:0] *************************** W1202 13:57:18.357218 36106 x:0] Block the TERM signal. This is only safe in tests! D1202 13:57:18.357540 36106 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:18.371052 36106 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-test-2": signal 0 D1202 13:57:18.371279 36106 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:18.371420 36106 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:18.372344 36106 x:0] urpc: successfully marshalled 122 bytes. D1202 13:57:18.374293 36106 x:0] urpc: unmarshal success. I1202 13:57:18.374591 36106 x:0] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2", PID: 35511 I1202 13:57:18.374763 36106 x:0] Retrieving sandbox stacks D1202 13:57:18.374854 36106 x:0] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:18.375009 36106 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2" D1202 13:57:18.381404 36106 x:0] urpc: successfully marshalled 36 bytes. D1202 13:57:18.404872 36106 x:0] urpc: unmarshal success. I1202 13:57:18.405165 36106 x:0] *** Stack dump *** goroutine 871 [running]: gvisor.dev/gvisor/pkg/log.Stacks(0x460d01, 0xc000382240, 0xc0002fe3c0, 0xc0006c8380) pkg/log/log.go:272 +0xb6 gvisor.dev/gvisor/runsc/boot.(*debug).Stacks(0x244d5e8, 0x244d5e8, 0xc0000f20d0, 0x0, 0x0) runsc/boot/debug.go:26 +0x38 reflect.Value.call(0xc000382240, 0xc000146af8, 0x13, 0x1255f53, 0x4, 0xc0003a9e78, 0x3, 0x3, 0x10bd701, 0xc0000f20d0, ...) GOROOT/src/reflect/value.go:460 +0x62b reflect.Value.Call(0xc000382240, 0xc000146af8, 0x13, 0xc0003a9e78, 0x3, 0x3, 0x0, 0x244d5e8, 0x16) GOROOT/src/reflect/value.go:321 +0xd4 gvisor.dev/gvisor/pkg/urpc.(*Server).handleOne(0xc0002e9b90, 0xc00049e750, 0x0, 0x0) pkg/urpc/urpc.go:325 +0x688 gvisor.dev/gvisor/pkg/urpc.(*Server).handleRegistered(0xc0002e9b90, 0xc00049e750, 0xc0002a9520, 0x0) pkg/urpc/urpc.go:420 +0x43 gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling.func1(0xc0002e9b90, 0xc00049e750) pkg/urpc/urpc.go:440 +0x81 created by gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling pkg/urpc/urpc.go:438 +0x6f goroutine 1 [semacquire]: sync.runtime_Semacquire(0xc00026e3a4) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc00026e3a4) GOROOT/src/sync/waitgroup.go:130 +0xb1 gvisor.dev/gvisor/pkg/sentry/kernel.(*Kernel).WaitExited(...) pkg/sentry/kernel/kernel.go:1059 gvisor.dev/gvisor/runsc/boot.(*Loader).WaitExit(0xc000158420, 0x0, 0x0) runsc/boot/loader.go:902 +0x73 gvisor.dev/gvisor/runsc/cmd.(*Boot).Execute(0xc0001bc360, 0x13cb940, 0xc000136000, 0xc0002945a0, 0xc000244be0, 0x2, 0x2, 0x0) runsc/cmd/boot.go:254 +0x149e github.com/google/subcommands.(*Commander).Execute(0xc00014c000, 0x13cb940, 0xc000136000, 0xc000244be0, 0x2, 0x2, 0x0) external/com_github_google_subcommands/subcommands.go:200 +0x51d github.com/google/subcommands.Execute(...) external/com_github_google_subcommands/subcommands.go:481 main.main() runsc/main.go:316 +0x2c86 goroutine 19 [syscall]: os/signal.signal_recv(0x462a81) GOROOT/src/runtime/sigqueue.go:147 +0x9c os/signal.loop() GOROOT/src/os/signal/signal_unix.go:23 +0x30 created by os/signal.init.0 GOROOT/src/os/signal/signal_unix.go:29 +0x4f goroutine 20 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0x1289450, 0xc000122900, 0xc000122960) pkg/sentry/platform/ptrace/subprocess.go:174 +0x21e created by gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess pkg/sentry/platform/ptrace/subprocess.go:158 +0x217 goroutine 21 [sync.Cond.Wait]: runtime.goparkunlock(...) GOROOT/src/runtime/proc.go:310 sync.runtime_notifyListWait(0xc000298948, 0x9) GOROOT/src/runtime/sema.go:510 +0xf8 sync.(*Cond).Wait(0xc000298938) GOROOT/src/sync/cond.go:56 +0x8e gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).findReclaimable(0xc000298500, 0x0, 0x0, 0x0) pkg/sentry/pgalloc/pgalloc.go:1090 +0xbb gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).runReclaim(0xc000298500) pkg/sentry/pgalloc/pgalloc.go:1019 +0x7b created by gvisor.dev/gvisor/pkg/sentry/pgalloc.NewMemoryFile pkg/sentry/pgalloc/pgalloc.go:330 +0x2f4 goroutine 23 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Timekeeper).startUpdater.func1(0xc000295440, 0xc00026e2d0) pkg/sentry/kernel/timekeeper.go:216 +0x204 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Timekeeper).startUpdater pkg/sentry/kernel/timekeeper.go:184 +0xfe goroutine 24 [chan receive]: gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).waitForStart(0xc00014c700) pkg/sentry/watchdog/watchdog.go:216 +0x9b created by gvisor.dev/gvisor/pkg/sentry/watchdog.New pkg/sentry/watchdog/watchdog.go:168 +0x334 goroutine 26 [select]: reflect.rselect(0xc000156900, 0x22, 0x22, 0xc000156900, 0x0) GOROOT/src/runtime/select.go:542 +0x3bd reflect.Select(0xc0002d5000, 0x22, 0x49, 0x0, 0x1118e40, 0x244d5e8, 0x99, 0x0) GOROOT/src/reflect/value.go:2229 +0x1f0 gvisor.dev/gvisor/pkg/sentry/sighandling.handleSignals(0xc0002aa200, 0x21, 0x40, 0xc0002dd4d0, 0xc000122e40, 0xc000122ea0, 0xc000122f00) pkg/sentry/sighandling/sighandling.go:46 +0x46c created by gvisor.dev/gvisor/pkg/sentry/sighandling.PrepareHandler pkg/sentry/sighandling/sighandling.go:131 +0x29d goroutine 27 [syscall]: syscall.Syscall6(0x10f, 0xc0000f0e68, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:44 +0x5 gvisor.dev/gvisor/pkg/unet.(*Socket).wait(0xc0002e9b00, 0xffffffffffffff00, 0x0, 0x0) pkg/unet/unet_unsafe.go:55 +0xda gvisor.dev/gvisor/pkg/unet.(*ServerSocket).Accept(0xc000146938, 0x0, 0x0, 0x0) pkg/unet/unet.go:539 +0x189 gvisor.dev/gvisor/pkg/control/server.(*Server).serve(0xc0002fc420) pkg/control/server/server.go:99 +0x50 gvisor.dev/gvisor/pkg/control/server.(*Server).StartServing.func1(0xc0002fc420) pkg/control/server/server.go:87 +0x39 created by gvisor.dev/gvisor/pkg/control/server.(*Server).StartServing pkg/control/server/server.go:86 +0xc9 goroutine 34 [syscall]: syscall.Syscall6(0x119, 0x10, 0xc0000ebae0, 0x64, 0xffffffffffffffff, 0x0, 0x0, 0xc000410de8, 0x4645ec, 0x474baf) src/syscall/asm_linux_amd64.s:44 +0x5 gvisor.dev/gvisor/pkg/fdnotifier.epollWait(0x10, 0xc0000ebae0, 0x64, 0x64, 0xffffffffffffffff, 0x1, 0x0, 0x0) pkg/fdnotifier/poll_unsafe.go:77 +0x83 gvisor.dev/gvisor/pkg/fdnotifier.(*notifier).waitAndNotify(0xc000410de0, 0x0, 0xf7eda0) pkg/fdnotifier/fdnotifier.go:149 +0x9e created by gvisor.dev/gvisor/pkg/fdnotifier.newNotifier pkg/fdnotifier/fdnotifier.go:64 +0x114 goroutine 35 [syscall]: syscall.Syscall6(0x10f, 0xc0000340c8, 0x1, 0x0, 0x0, 0x0, 0x0, 0x12747d2, 0x28, 0xc0000f2080) src/syscall/asm_linux_amd64.s:44 +0x5 golang.org/x/sys/unix.ppoll(0xc0000340c8, 0x1, 0x0, 0x0, 0x1aa4440, 0x12747d2, 0x100000000000028) external/org_golang_x_sys/unix/zsyscall_linux_amd64.go:79 +0xa2 golang.org/x/sys/unix.Ppoll(0xc0000340c8, 0x1, 0x1, 0x0, 0x0, 0x0, 0x0, 0x448f80) external/org_golang_x_sys/unix/syscall_linux.go:133 +0xda gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor.func1.1(0xe51a5a, 0x8, 0x1, 0x0) runsc/boot/loader.go:710 +0x60 gvisor.dev/gvisor/runsc/specutils.RetryEintr(0xc0000eff80, 0x8, 0x0, 0x0, 0x1) runsc/specutils/specutils.go:475 +0x39 gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor.func1(0x7ffcb0e32f97, 0x2a, 0xc0002ac058, 0x1, 0x1, 0xc000158420) runsc/boot/loader.go:708 +0x25a created by gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor runsc/boot/loader.go:699 +0x7f goroutine 36 [syscall]: syscall.Syscall6(0x10f, 0xc000482f20, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:44 +0x5 golang.org/x/sys/unix.ppoll(0xc000482f20, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0) external/org_golang_x_sys/unix/zsyscall_linux_amd64.go:79 +0xa2 golang.org/x/sys/unix.Ppoll(0xc000482f20, 0x1, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0) external/org_golang_x_sys/unix/syscall_linux.go:133 +0xda gvisor.dev/gvisor/pkg/p9.(*Client).watch(0xc000490000, 0xc000391ad0) pkg/p9/client.go:251 +0xda created by gvisor.dev/gvisor/pkg/p9.NewClient pkg/p9/client.go:231 +0x75e goroutine 114 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0003aa000) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 115 [select]: gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).loop(0xc00014c700) pkg/sentry/watchdog/watchdog.go:232 +0x149 created by gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).Start pkg/sentry/watchdog/watchdog.go:191 +0x315 goroutine 116 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0003aa090) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 117 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000238000, 0xc0001228a0, 0x0, 0x69470b, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000238000, 0xc0001228a0, 0x13ccd00, 0x0, 0x392f1e8, 0x54d600, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000238000, 0x0, 0xc420009c01, 0x392f1e8, 0x0, 0xffffffff, 0xf7ed00, 0x7f3ea4030e88, 0xc00012ccb0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000238000, 0x392f1e8, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b61, 0xc00054d948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000238000, 0xca, 0x392f1e8, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000238000, 0xca, 0x392f1e8, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0004660f0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000238000, 0xca, 0x392f1e8, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00054dce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000238000, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000238000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000238000, 0x1) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 101 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0002dc000) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 102 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc0003b0000, 0xc00023c0c0, 0xc00023c120) pkg/sentry/platform/ptrace/subprocess.go:174 +0x21e created by gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess pkg/sentry/platform/ptrace/subprocess.go:158 +0x217 goroutine 119 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0004ac000, 0xc000123560, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0004ac000, 0xc000123560, 0x13ccd00, 0x0, 0xc4200cb948, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0004ac000, 0x0, 0xc0004ac001, 0xc4200cb948, 0x430200, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0004ac000, 0xc4200cb948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b59, 0xc0003bf948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0004ac000, 0xca, 0xc4200cb948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0004ac000, 0xca, 0xc4200cb948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0002463c0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0004ac000, 0xca, 0xc4200cb948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0003bfce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0004ac000, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0004ac000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0004ac000, 0x5) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 118 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0003aa240) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 106 [semacquire]: sync.runtime_Semacquire(0xc0001a26b8) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc0001a26b0) GOROOT/src/sync/waitgroup.go:130 +0xb1 gvisor.dev/gvisor/pkg/sentry/kernel.(*ThreadGroup).WaitExited(...) pkg/sentry/kernel/task_run.go:333 gvisor.dev/gvisor/runsc/boot.(*Loader).wait(0xc000158420, 0xc0001a2000, 0x2a) runsc/boot/loader.go:890 +0x43 gvisor.dev/gvisor/runsc/boot.(*Loader).waitContainer(0xc000158420, 0xc00015e900, 0x2a, 0xc0003940b8, 0x0, 0xc00051d1d0) runsc/boot/loader.go:845 +0x1af gvisor.dev/gvisor/runsc/boot.(*containerManager).Wait(0xc0002fc440, 0xc00012d930, 0xc0003940b8, 0x0, 0x0) runsc/boot/controller.go:424 +0xb9 reflect.Value.call(0xc000382120, 0xc000146a90, 0x13, 0x1255f53, 0x4, 0xc000235e78, 0x3, 0x3, 0x10ba701, 0xc0003940b8, ...) GOROOT/src/reflect/value.go:460 +0x62b reflect.Value.Call(0xc000382120, 0xc000146a90, 0x13, 0xc000235e78, 0x3, 0x3, 0x0, 0xc0002fc440, 0x16) GOROOT/src/reflect/value.go:321 +0xd4 gvisor.dev/gvisor/pkg/urpc.(*Server).handleOne(0xc0002e9b90, 0xc0003841b0, 0x0, 0x0) pkg/urpc/urpc.go:325 +0x688 gvisor.dev/gvisor/pkg/urpc.(*Server).handleRegistered(0xc0002e9b90, 0xc0003841b0, 0x0, 0x0) pkg/urpc/urpc.go:420 +0x43 gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling.func1(0xc0002e9b90, 0xc0003841b0) pkg/urpc/urpc.go:440 +0x81 created by gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling pkg/urpc/urpc.go:438 +0x6f goroutine 131 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0003f2000, 0xc00039c1e0, 0xc0003ca240, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithDeadline(0xc0003f2000, 0xc00039c1e0, 0xc0003f2801, 0xe6d9d924c, 0x0, 0xc0004156c8) pkg/sentry/kernel/task_block.go:79 +0xda gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0003f2000, 0xc00039c1e0, 0x13ccd01, 0xdf8475800, 0x392e430, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:41 +0x11d gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0003f2000, 0xdf8475800, 0x0, 0x392e430, 0x0, 0xffffffff, 0xae0803, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0003f2000, 0x392e430, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x8b5a, 0xc000231948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0003f2000, 0xca, 0x392e430, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0003f2000, 0xca, 0x392e430, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x13babc0, 0xc0002464b0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0003f2000, 0xca, 0x392e430, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0xc000231ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0003f2000, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0003f2000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0003f2000, 0x2) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 62 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0001bc090) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 146 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000442000, 0xc0003fe1e0, 0x0, 0x4645ec, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000442000, 0xc0003fe1e0, 0x13ccd00, 0x0, 0xc4200ca548, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000442000, 0x0, 0xc000442001, 0xc4200ca548, 0x0, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000442000, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b59, 0xc00059b948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000442000, 0xca, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000442000, 0xca, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0002463c0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000442000, 0xca, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00059bce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000442000, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000442000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000442000, 0x3) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 132 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0003f2a80, 0xc00039c840, 0x0, 0x4645ec, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0003f2a80, 0xc00039c840, 0x13ccd00, 0x0, 0x394d0c0, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0003f2a80, 0x0, 0xc0003f2a01, 0x394d0c0, 0x0, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0003f2a80, 0x394d0c0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b55, 0xc0003c1948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0003f2a80, 0xca, 0x394d0c0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0003f2a80, 0xca, 0x394d0c0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0002f63c0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0003f2a80, 0xca, 0x394d0c0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0003c1ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0003f2a80, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0003f2a80, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0003f2a80, 0x4) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 148 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000450000) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 120 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0003aa360) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 107 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0005aa000, 0xc00023ccc0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0005aa000, 0xc00023ccc0, 0x13ccd00, 0x0, 0xc42020c148, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0005aa000, 0x0, 0xc0005aa001, 0xc42020c148, 0x0, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0005aa000, 0xc42020c148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b3c, 0xc0003c5948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0005aa000, 0xca, 0xc42020c148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0005aa000, 0xca, 0xc42020c148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0002462d0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0005aa000, 0xca, 0xc42020c148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0003c5ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0005aa000, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0005aa000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0005aa000, 0x6) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 63 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0001bc120) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 154 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0002dccf0) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 64 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000442a80, 0xc0001225a0, 0x0, 0xc0001a47e0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000442a80, 0xc0001225a0, 0x13ccd00, 0x0, 0xc420460148, 0x295500, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000442a80, 0x0, 0x1, 0xc420460148, 0x100, 0xffffffff, 0x0, 0x0, 0x8) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000442a80, 0xc420460148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b3c, 0xc000537948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000442a80, 0xca, 0xc420460148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000442a80, 0xca, 0xc420460148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0002462d0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000442a80, 0xca, 0xc420460148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000537ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000442a80, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000442a80, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000442a80, 0xc) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 65 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000450090) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 162 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000443500, 0xc000122ba0, 0x0, 0x4645ec, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000443500, 0xc000122ba0, 0x13ccd00, 0x0, 0xc420460548, 0x0, 0xc0ffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000443500, 0x0, 0xc000443501, 0xc420460548, 0x489200, 0xffffffff, 0x0, 0x13bc9a0, 0x1a82c40) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000443500, 0xc420460548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b8f, 0xc0003a5948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000443500, 0xca, 0xc420460548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000443500, 0xca, 0xc420460548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc000560000) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000443500, 0xca, 0xc420460548, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0003a5ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000443500, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000443500, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000443500, 0xd) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 113 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0004b4000, 0xc0003cae40, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0004b4000, 0xc0003cae40, 0x13ccd00, 0x0, 0xc4204a0148, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0004b4000, 0x0, 0xc0004b4001, 0xc4204a0148, 0x489200, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0004b4000, 0xc4204a0148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b3c, 0xc00053b948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0004b4000, 0xca, 0xc4204a0148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0004b4000, 0xca, 0xc4204a0148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0002462d0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0004b4000, 0xca, 0xc4204a0148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00053bce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0004b4000, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0004b4000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0004b4000, 0xe) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 178 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0001bc480) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 163 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc00053c000, 0xc000123440, 0x0, 0x4645ec, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00053c000, 0xc000123440, 0x13ccd00, 0x0, 0xc4204a0948, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00053c000, 0x0, 0xc00053c001, 0xc4204a0948, 0xd0524e00, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00053c000, 0xc4204a0948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b59, 0xc000551948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00053c000, 0xca, 0xc4204a0948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc00053c000, 0xca, 0xc4204a0948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0002463c0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00053c000, 0xca, 0xc4204a0948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000551ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00053c000, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00053c000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00053c000, 0xf) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 164 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000450120) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 165 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc00053ca80, 0xc000123c20, 0x0, 0xc0001a47e0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00053ca80, 0xc000123c20, 0x13ccd00, 0x0, 0xc4204a0d48, 0x55f600, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00053ca80, 0x0, 0x1, 0xc4204a0d48, 0x204a0100, 0xffffffff, 0x0, 0x0, 0x8) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00053ca80, 0xc4204a0d48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8b3c, 0xc00022f948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00053ca80, 0xca, 0xc4204a0d48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc00053ca80, 0xca, 0xc4204a0d48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0002462d0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00053ca80, 0xca, 0xc4204a0d48, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00022fce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00053ca80, 0x2, 0xc000120080) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00053ca80, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00053ca80, 0x10) pkg/sentry/kernel/task_run.go:91 +0x309 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:300 +0x19e goroutine 166 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0004501b0) pkg/sentry/kernel/time/time.go:515 +0x149 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x246 goroutine 181 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc00012cc50, 0xc0003cb500, 0xc0003cb560) pkg/sentry/platform/ptrace/subprocess.go:174 +0x21e created by gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess pkg/sentry/platform/ptrace/subprocess.go:158 +0x217 goroutine 274 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc000366150, 0xc0001224e0, 0xc0001226c0) pkg/sentry/platform/ptrace/subprocess.go:174 +0x21e created by gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess pkg/sentry/platform/ptrace/subprocess.go:158 +0x217 goroutine 143 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc000380890, 0xc000463e60, 0xc000463ec0) pkg/sentry/platform/ptrace/subprocess.go:174 +0x21e created by gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess pkg/sentry/platform/ptrace/subprocess.go:158 +0x217 goroutine 238 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc00046c6c0, 0xc00019fc20, 0xc00019fc80) pkg/sentry/platform/ptrace/subprocess.go:174 +0x21e created by gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess pkg/sentry/platform/ptrace/subprocess.go:158 +0x217 I1202 13:57:18.406293 36106 x:0] Exiting with status: 0 D1202 13:57:18.275244 35511 x:0] urpc: successfully marshalled 38 bytes. D1202 13:57:18.372575 35511 x:0] urpc: unmarshal success. D1202 13:57:18.372745 35511 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-test-2 Signo:0 PID:0 Mode:Process} D1202 13:57:18.372940 35511 x:0] urpc: successfully marshalled 37 bytes. D1202 13:57:18.375710 35511 x:0] urpc: unmarshal success. D1202 13:57:18.380410 35511 x:0] urpc: successfully marshalled 34064 bytes. D1202 13:57:18.467418 35511 x:0] Clock(Monotonic): error: 50 ns, adjusted frequency from 2299805979 Hz to 2299806101 Hz D1202 13:57:18.467613 35511 x:0] Clock(Realtime): error: 53 ns, adjusted frequency from 2299806554 Hz to 2299806137 Hz D1202 13:57:18.467719 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398543200615448 monotonicBaseRef:5000216137 monotonicFrequency:2299806101 realtimeReady:1 realtimeBaseCycles:39398543201092122 realtimeBaseRef:1575295038467607760 realtimeFrequency:2299806137} D1202 13:57:19.468631 35511 x:0] Clock(Monotonic): error: -21 ns, adjusted frequency from 2299806101 Hz to 2299805954 Hz D1202 13:57:19.468827 35511 x:0] Clock(Realtime): error: -36 ns, adjusted frequency from 2299806137 Hz to 2299805939 Hz D1202 13:57:19.468901 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398545503174114 monotonicBaseRef:6001413005 monotonicFrequency:2299805954 realtimeReady:1 realtimeBaseCycles:39398545503687195 realtimeBaseRef:1575295039468820443 realtimeFrequency:2299805939} D1202 13:57:20.467519 35511 x:0] Clock(Monotonic): error: 167 ns, adjusted frequency from 2299805954 Hz to 2299806414 Hz D1202 13:57:20.467706 35511 x:0] Clock(Realtime): error: 105 ns, adjusted frequency from 2299805939 Hz to 2299806281 Hz D1202 13:57:20.467815 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398547800448406 monotonicBaseRef:7000312189 monotonicFrequency:2299806414 realtimeReady:1 realtimeBaseCycles:39398547800914812 realtimeBaseRef:1575295040467699338 realtimeFrequency:2299806281} D1202 13:57:21.468751 35511 x:0] Clock(Monotonic): error: -26 ns, adjusted frequency from 2299806414 Hz to 2299805969 Hz D1202 13:57:21.468946 35511 x:0] Clock(Realtime): error: -40 ns, adjusted frequency from 2299806281 Hz to 2299805947 Hz D1202 13:57:21.469038 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398550103092857 monotonicBaseRef:8001546221 monotonicFrequency:2299805969 realtimeReady:1 realtimeBaseCycles:39398550103569951 realtimeBaseRef:1575295041468938076 realtimeFrequency:2299805947} D1202 13:57:22.467494 35511 x:0] Clock(Monotonic): error: 46 ns, adjusted frequency from 2299805969 Hz to 2299806143 Hz D1202 13:57:22.467701 35511 x:0] Clock(Realtime): error: 14 ns, adjusted frequency from 2299805947 Hz to 2299806071 Hz D1202 13:57:22.467780 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398552400001468 monotonicBaseRef:9000286393 monotonicFrequency:2299806143 realtimeReady:1 realtimeBaseCycles:39398552400506467 realtimeBaseRef:1575295042467690392 realtimeFrequency:2299806071} D1202 13:57:23.468469 35511 x:0] Clock(Monotonic): error: -107 ns, adjusted frequency from 2299806143 Hz to 2299805843 Hz D1202 13:57:23.468588 35511 x:0] Clock(Realtime): error: -74 ns, adjusted frequency from 2299806071 Hz to 2299805920 Hz D1202 13:57:23.468677 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398554702040157 monotonicBaseRef:10001257146 monotonicFrequency:2299805843 realtimeReady:1 realtimeBaseCycles:39398554702366359 realtimeBaseRef:1575295043468583432 realtimeFrequency:2299805920} D1202 13:57:24.467508 35511 x:0] Clock(Monotonic): error: 204 ns, adjusted frequency from 2299805843 Hz to 2299806578 Hz D1202 13:57:24.467664 35511 x:0] Clock(Realtime): error: 123 ns, adjusted frequency from 2299805920 Hz to 2299806352 Hz D1202 13:57:24.467767 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398556999656729 monotonicBaseRef:11000305208 monotonicFrequency:2299806578 realtimeReady:1 realtimeBaseCycles:39398557000044329 realtimeBaseRef:1575295044467658158 realtimeFrequency:2299806352} D1202 13:57:25.467641 35511 x:0] Clock(Monotonic): error: -115 ns, adjusted frequency from 2299806578 Hz to 2299805821 Hz D1202 13:57:25.467812 35511 x:0] Clock(Realtime): error: 39 ns, adjusted frequency from 2299806352 Hz to 2299806203 Hz D1202 13:57:25.467888 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398559299771199 monotonicBaseRef:12000439085 monotonicFrequency:2299805821 realtimeReady:1 realtimeBaseCycles:39398559300188760 realtimeBaseRef:1575295045467805161 realtimeFrequency:2299806203} D1202 13:57:26.469539 35511 x:0] Clock(Monotonic): error: -56 ns, adjusted frequency from 2299805821 Hz to 2299805968 Hz D1202 13:57:26.469655 35511 x:0] Clock(Realtime): error: -128 ns, adjusted frequency from 2299806203 Hz to 2299805802 Hz D1202 13:57:26.469712 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398561603923309 monotonicBaseRef:13002328935 monotonicFrequency:2299805968 realtimeReady:1 realtimeBaseCycles:39398561604238250 realtimeBaseRef:1575295046469650223 realtimeFrequency:2299805802} D1202 13:57:27.468251 35511 x:0] Clock(Monotonic): error: 18 ns, adjusted frequency from 2299805968 Hz to 2299806126 Hz D1202 13:57:27.468399 35511 x:0] Clock(Realtime): error: -18 ns, adjusted frequency from 2299805802 Hz to 2299806048 Hz D1202 13:57:27.468470 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398563900791788 monotonicBaseRef:14001051658 monotonicFrequency:2299806126 realtimeReady:1 realtimeBaseCycles:39398563901154178 realtimeBaseRef:1575295047468393649 realtimeFrequency:2299806048} D1202 13:57:28.467581 35511 x:0] Clock(Monotonic): error: 105 ns, adjusted frequency from 2299806126 Hz to 2299806340 Hz D1202 13:57:28.467764 35511 x:0] Clock(Realtime): error: 53 ns, adjusted frequency from 2299806048 Hz to 2299806213 Hz D1202 13:57:28.467839 35511 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:39398566199018168 monotonicBaseRef:15000364754 monotonicFrequency:2299806340 realtimeReady:1 realtimeBaseCycles:39398566199496299 realtimeBaseRef:1575295048467757105 realtimeFrequency:2299806213}