I1023 20:22:37.568471 40172 x:0] *************************** I1023 20:22:37.568752 40172 x:0] Args: [/syzkaller/managers/ptrace-direct-overlay-host-race/latest.tmp/image -root /syzkaller/managers/ptrace-direct-overlay-host-race/latest.tmp/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=exclusive -overlay -network=host -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-direct-overlay-host-race-test-2 /syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true] I1023 20:22:37.569180 40172 x:0] Version release-20190806.1-307-gc0065e296f6e I1023 20:22:37.569281 40172 x:0] PID: 40172 I1023 20:22:37.569368 40172 x:0] UID: 0, GID: 0 I1023 20:22:37.569465 40172 x:0] Configuration: I1023 20:22:37.569570 40172 x:0] RootDir: /syzkaller/managers/ptrace-direct-overlay-host-race/latest.tmp/workdir/gvisor_root I1023 20:22:37.579804 40172 x:0] Platform: ptrace I1023 20:22:37.579869 40172 x:0] FileAccess: exclusive, overlay: true I1023 20:22:37.579968 40172 x:0] Network: host, logging: false I1023 20:22:37.580096 40172 x:0] Strace: false, max size: 1024, syscalls: [] I1023 20:22:37.580193 40172 x:0] *************************** W1023 20:22:37.580224 40172 x:0] Block the TERM signal. This is only safe in tests! D1023 20:22:37.581065 40172 x:0] Load container "/syzkaller/managers/ptrace-direct-overlay-host-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:37.588161 40172 x:0] Signal container "ci-gvisor-ptrace-direct-overlay-host-race-test-2": signal 0 D1023 20:22:37.588387 40172 x:0] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:37.588494 40172 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:37.596774 35554 x:0] urpc: unmarshal success. D1023 20:22:37.597066 35554 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-race-test-2 Signo:0 PID:0 Mode:Process} D1023 20:22:37.597337 35554 x:0] urpc: successfully marshalled 37 bytes. D1023 20:22:37.597531 40172 x:0] urpc: successfully marshalled 128 bytes. D1023 20:22:37.597885 40172 x:0] urpc: unmarshal success. D1023 20:22:37.598158 40172 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 D1023 20:22:37.598262 40172 x:0] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D1023 20:22:37.598379 40172 x:0] Execute in container "ci-gvisor-ptrace-direct-overlay-host-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 D1023 20:22:37.598473 40172 x:0] Executing new process in container "ci-gvisor-ptrace-direct-overlay-host-race-test-2" in sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:37.598671 40172 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:37.600271 35554 x:0] urpc: unmarshal success. D1023 20:22:37.601424 35554 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 I1023 20:22:37.602439 35554 x:0] EXEC: [/syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true] D1023 20:22:37.603055 35554 x:0] send [channel @0xc0003d2780] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-fuzzer]} D1023 20:22:37.605112 35554 x:0] recv [channel @0xc0003d2780] 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: 21087968, BlockSize: 4096, Blocks: 41192, ATime: {Sec: 1571796376, NanoSec: 509132765}, MTime: {Sec: 1571796376, NanoSec: 509132765}, CTime: {Sec: 1571862157, NanoSec: 523711337}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 13896373}]} D1023 20:22:37.605693 35554 x:0] send [channel @0xc0003d2780] Twalk{FID: 5, NewFID: 6, Names: []} D1023 20:22:37.606314 35554 x:0] recv [channel @0xc0003d2780] Rwalk{QIDs: []} D1023 20:22:37.606489 35554 x:0] send [channel @0xc0003d2780] Tlopen{FID: 6, Flags: ReadOnly} D1023 20:22:37.607079 40172 x:0] urpc: successfully marshalled 579 bytes. D1023 20:22:37.607170 35554 x:0] recv [channel @0xc0003d2780] Rlopen{QID: QID{Type: 0, Version: 0, Path: 13896373}, IoUnit: 0, File: &{{33}}} D1023 20:22:37.611730 35554 x:0] Allocating stack with size of 8388608 bytes D1023 20:22:37.622502 35554 x:0] updated processes: map[{ci-gvisor-ptrace-direct-overlay-host-race-test-2 0}:0xc0002dfa40 {ci-gvisor-ptrace-direct-overlay-host-race-test-2 17}:0xc00064d580] D1023 20:22:37.624540 35554 x:0] urpc: unmarshal success. D1023 20:22:37.624755 35554 x:0] containerManager.Wait D1023 20:22:37.623121 40172 x:0] urpc: unmarshal success. D1023 20:22:37.623236 40172 x:0] Wait on PID 17 in container "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:37.623353 40172 x:0] Waiting for PID 17 in sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:37.623517 40172 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:37.623800 40172 x:0] urpc: successfully marshalled 111 bytes. D1023 20:22:37.626292 35554 x:0] urpc: successfully marshalled 37 bytes. D1023 20:22:37.665622 35554 x:0] [ 17] Interrupt queued D1023 20:22:38.033666 35554 x:0] [ 18] Interrupt queued D1023 20:22:38.041470 35554 x:0] [ 19] Interrupt queued D1023 20:22:38.058841 35554 x:0] [ 20] Interrupt queued D1023 20:22:38.092437 35554 x:0] [ 21] Interrupt queued D1023 20:22:38.180543 35554 x:0] [ 22] Interrupt queued D1023 20:22:38.188574 35554 x:0] [ 23] Interrupt queued D1023 20:22:38.281763 35554 x:0] Clock(Monotonic): error: 221 ns, adjusted frequency from 2299806853 Hz to 2299806844 Hz D1023 20:22:38.281944 35554 x:0] Clock(Realtime): error: 186 ns, adjusted frequency from 2299806734 Hz to 2299806815 Hz D1023 20:22:38.282033 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503583488881685 monotonicBaseRef:3000411224 monotonicFrequency:2299806844 realtimeReady:1 realtimeBaseCycles:31503583489314079 realtimeBaseRef:1571862158281938399 realtimeFrequency:2299806815} D1023 20:22:38.387948 35554 x:0] [ 24] Interrupt queued 2019/10/23 20:22:38 fuzzer started D1023 20:22:38.426630 35554 x:0] [ 25] Interrupt queued D1023 20:22:38.455274 35554 x:0] [ 26] Interrupt queued D1023 20:22:38.509586 35554 x:0] [ 27] Interrupt queued D1023 20:22:38.536697 35554 x:0] [ 28] Interrupt queued D1023 20:22:38.601989 35554 x:0] [ 29] Interrupt queued D1023 20:22:38.674023 35554 x:0] [ 30] Interrupt queued D1023 20:22:38.721494 35554 x:0] [ 31] Interrupt queued D1023 20:22:38.727615 35554 x:0] [ 32] Interrupt queued 2019/10/23 20:22:38 connecting to host at stdin D1023 20:22:38.759927 35554 x:0] [ 33] Interrupt queued D1023 20:22:38.761630 35554 x:0] [ 34] Interrupt queued 2019/10/23 20:22:38 checking machine... 2019/10/23 20:22:38 checking revisions... D1023 20:22:38.785074 35554 x:0] [ 26] Entering internal stop (*kernel.vforkStop)(nil) D1023 20:22:38.785772 35554 x:0] [ 35] Interrupt queued D1023 20:22:38.800322 35554 x:0] send [channel @0xc0003d2780] Twalkgetattr{FID: 1, NewFID: 7, Names: [syz-executor]} D1023 20:22:38.801422 35554 x:0] recv [channel @0xc0003d2780] 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: 1537320, BlockSize: 4096, Blocks: 3008, ATime: {Sec: 1571796374, NanoSec: 900997497}, MTime: {Sec: 1571796374, NanoSec: 900997497}, CTime: {Sec: 1571862157, NanoSec: 523711337}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 13896376}]} D1023 20:22:38.801766 35554 x:0] send [channel @0xc0003d2780] Twalk{FID: 7, NewFID: 8, Names: []} D1023 20:22:38.802291 35554 x:0] recv [channel @0xc0003d2780] Rwalk{QIDs: []} D1023 20:22:38.802406 35554 x:0] send [channel @0xc0003d2780] Tlopen{FID: 8, Flags: ReadOnly} D1023 20:22:38.803017 35554 x:0] recv [channel @0xc0003d2780] Rlopen{QID: QID{Type: 0, Version: 0, Path: 13896376}, IoUnit: 0, File: &{{25}}} D1023 20:22:38.804200 35554 x:0] [ 35] Allocating stack with size of 8388608 bytes D1023 20:22:38.807955 35554 x:0] [ 26] Leaving internal stop (*kernel.vforkStop)(nil) D1023 20:22:38.890469 35554 x:0] [ 35] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:38.893086 35554 x:0] [ 35] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:38.893287 35554 x:0] [ 17] Notified of signal 17 D1023 20:22:38.893347 35554 x:0] [ 17] Interrupt queued D1023 20:22:38.893684 35554 x:0] [ 17] Interrupt queued D1023 20:22:38.893814 35554 x:0] [ 17] Restarting syscall 202 after errno 512: interrupted by signal 17 D1023 20:22:38.893932 35554 x:0] [ 17] Signal 17: delivering to handler D1023 20:22:38.896982 35554 x:0] [ 35] Transitioning from exit state TaskExitZombie to TaskExitDead 2019/10/23 20:22:38 testing simple program... D1023 20:22:38.906590 35554 x:0] [ 26] Entering internal stop (*kernel.vforkStop)(nil) D1023 20:22:38.907255 35554 x:0] [ 36] Interrupt queued D1023 20:22:38.928726 35554 x:0] [ 36] Allocating stack with size of 8388608 bytes D1023 20:22:38.933813 35554 x:0] [ 26] Leaving internal stop (*kernel.vforkStop)(nil) D1023 20:22:38.985387 35554 x:0] [ 36] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:38.986448 35554 x:0] [ 36] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:38.986555 35554 x:0] [ 17] Notified of signal 17 D1023 20:22:38.986639 35554 x:0] [ 17] Interrupt queued D1023 20:22:38.986723 35554 x:0] [ 17] Interrupt queued D1023 20:22:38.986837 35554 x:0] [ 17] Restarting syscall 202 after errno 512: interrupted by signal 17 D1023 20:22:38.986906 35554 x:0] [ 17] Signal 17: delivering to handler D1023 20:22:38.989350 35554 x:0] [ 36] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:39.098661 35554 x:0] [ 26] Entering internal stop (*kernel.vforkStop)(nil) D1023 20:22:39.099873 35554 x:0] [ 37] Interrupt queued D1023 20:22:39.133890 35554 x:0] [ 37] Allocating stack with size of 8388608 bytes D1023 20:22:39.135006 35554 x:0] [ 26] Leaving internal stop (*kernel.vforkStop)(nil) D1023 20:22:39.242822 35554 x:0] [ 38] Interrupt queued D1023 20:22:39.281599 35554 x:0] Clock(Monotonic): error: -11 ns, adjusted frequency from 2299806844 Hz to 2299806327 Hz D1023 20:22:39.281885 35554 x:0] Clock(Realtime): error: 36 ns, adjusted frequency from 2299806815 Hz to 2299806478 Hz D1023 20:22:39.281984 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503585788266628 monotonicBaseRef:4000227773 monotonicFrequency:2299806327 realtimeReady:1 realtimeBaseCycles:31503585788969048 realtimeBaseRef:1571862159281872373 realtimeFrequency:2299806478} D1023 20:22:39.380162 35554 x:0] [ 39] Interrupt queued D1023 20:22:39.429242 35554 x:0] [ 40] Interrupt queued D1023 20:22:40.253853 35554 x:0] [ 41] Interrupt queued D1023 20:22:40.263855 35554 x:0] [ 40] Interrupt queued D1023 20:22:40.263958 35554 x:0] [ 41] Interrupt queued D1023 20:22:40.264039 35554 x:0] [ 39] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.264197 35554 x:0] [ 39] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.264355 35554 x:0] [ 40] Interrupt queued D1023 20:22:40.264481 35554 x:0] [ 40] Signal 9: terminating thread group I1023 20:22:40.264573 35554 x:0] Uncaught signal: "killed" (9), PID: 39, TID: 40, fault addr: 0x0 D1023 20:22:40.264710 35554 x:0] [ 40] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.264855 35554 x:0] [ 40] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.264941 35554 x:0] [ 40] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.265215 35554 x:0] [ 41] Interrupt queued D1023 20:22:40.265358 35554 x:0] [ 41] Signal 9: terminating thread group I1023 20:22:40.265437 35554 x:0] Uncaught signal: "killed" (9), PID: 39, TID: 41, fault addr: 0x0 D1023 20:22:40.265512 35554 x:0] [ 41] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.266797 35554 x:0] [ 41] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.266874 35554 x:0] [ 41] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.267072 35554 x:0] [ 38] Discarding ignored signal 17 D1023 20:22:40.269636 35554 x:0] [ 39] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.272025 35554 x:0] [ 37] Interrupt queued D1023 20:22:40.272133 35554 x:0] [ 37] Notified of signal 9 D1023 20:22:40.272230 35554 x:0] [ 37] Interrupt queued D1023 20:22:40.273962 35554 x:0] [ 37] Dropping duplicate interrupt D1023 20:22:40.274104 35554 x:0] [ 37] Signal 9: terminating thread group I1023 20:22:40.274195 35554 x:0] Uncaught signal: "killed" (9), PID: 37, TID: 37, fault addr: 0x0 D1023 20:22:40.274333 35554 x:0] [ 37] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.275155 35554 x:0] [ 38] Interrupt queued D1023 20:22:40.275239 35554 x:0] [ 38] No task notified of signal 9 D1023 20:22:40.275304 35554 x:0] [ 37] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.275375 35554 x:0] [ 17] Notified of signal 17 D1023 20:22:40.275424 35554 x:0] [ 17] Interrupt queued D1023 20:22:40.275573 35554 x:0] [ 17] Interrupt queued D1023 20:22:40.275712 35554 x:0] [ 17] Restarting syscall 202 after errno 512: interrupted by signal 17 D1023 20:22:40.275785 35554 x:0] [ 17] Signal 17: delivering to handler D1023 20:22:40.287004 35554 x:0] Clock(Monotonic): error: 139 ns, adjusted frequency from 2299806327 Hz to 2299806719 Hz D1023 20:22:40.287302 35554 x:0] Clock(Realtime): error: 108 ns, adjusted frequency from 2299806478 Hz to 2299806686 Hz D1023 20:22:40.287423 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503588100540443 monotonicBaseRef:5005648876 monotonicFrequency:2299806719 realtimeReady:1 realtimeBaseCycles:31503588101244256 realtimeBaseRef:1571862160287294016 realtimeFrequency:2299806686} D1023 20:22:40.288802 35554 x:0] [ 38] Signal 9: terminating thread group I1023 20:22:40.293764 35554 x:0] Uncaught signal: "killed" (9), PID: 38, TID: 38, fault addr: 0x0 D1023 20:22:40.294538 35554 x:0] [ 38] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.296472 35554 x:0] [ 38] Init process terminating, killing namespace D1023 20:22:40.296540 35554 x:0] [ 38] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.296664 35554 x:0] [ 1] Notified of signal 17 D1023 20:22:40.296750 35554 x:0] [ 1] Interrupt queued D1023 20:22:40.296969 35554 x:0] [ 37] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.296991 35554 x:0] [ 1] Interrupt queued D1023 20:22:40.297042 35554 x:0] [ 42] Interrupt queued D1023 20:22:40.297173 35554 x:0] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 17 D1023 20:22:40.297279 35554 x:0] [ 1] Signal 17: delivering to handler D1023 20:22:40.347545 35554 x:0] [ 43] Interrupt queued 2019/10/23 20:22:40 BUG: simple call failed: {Flags:5 Signal:[65483616] Cover:[] Comps: Errno:999} D1023 20:22:40.381802 35554 x:0] [ 17] Interrupt queued D1023 20:22:40.381871 35554 x:0] [ 17] Interrupt queued D1023 20:22:40.382058 35554 x:0] [ 18] Interrupt queued D1023 20:22:40.382026 35554 x:0] [ 18] Interrupt queued D1023 20:22:40.382327 35554 x:0] [ 19] Interrupt queued D1023 20:22:40.382571 35554 x:0] [ 19] Interrupt queued D1023 20:22:40.382657 35554 x:0] [ 20] Interrupt queued D1023 20:22:40.382695 35554 x:0] [ 20] Interrupt queued D1023 20:22:40.382772 35554 x:0] [ 21] Interrupt queued D1023 20:22:40.382732 35554 x:0] [ 21] Interrupt queued D1023 20:22:40.382997 35554 x:0] [ 22] Interrupt queued D1023 20:22:40.383073 35554 x:0] [ 24] Interrupt queued D1023 20:22:40.383171 35554 x:0] [ 25] Interrupt queued D1023 20:22:40.383251 35554 x:0] [ 26] Interrupt queued D1023 20:22:40.383257 35554 x:0] [ 25] Interrupt queued D1023 20:22:40.383259 35554 x:0] [ 22] Interrupt queued D1023 20:22:40.383378 35554 x:0] [ 24] Interrupt queued D1023 20:22:40.383311 35554 x:0] [ 27] Interrupt queued D1023 20:22:40.383435 35554 x:0] [ 27] Interrupt queued D1023 20:22:40.383376 35554 x:0] [ 26] Interrupt queued D1023 20:22:40.383494 35554 x:0] [ 28] Interrupt queued D1023 20:22:40.383560 35554 x:0] [ 29] Interrupt queued D1023 20:22:40.383572 35554 x:0] [ 28] Interrupt queued D1023 20:22:40.383611 35554 x:0] [ 30] Interrupt queued D1023 20:22:40.383653 35554 x:0] [ 29] Interrupt queued D1023 20:22:40.383672 35554 x:0] [ 30] Interrupt queued D1023 20:22:40.383672 35554 x:0] [ 31] Interrupt queued D1023 20:22:40.383714 35554 x:0] [ 31] Interrupt queued D1023 20:22:40.383873 35554 x:0] [ 32] Interrupt queued D1023 20:22:40.383989 35554 x:0] [ 33] Interrupt queued D1023 20:22:40.383898 35554 x:0] [ 32] Interrupt queued D1023 20:22:40.384089 35554 x:0] [ 34] Interrupt queued D1023 20:22:40.384159 35554 x:0] [ 42] Interrupt queued D1023 20:22:40.384177 35554 x:0] [ 33] Interrupt queued D1023 20:22:40.384211 35554 x:0] [ 43] Interrupt queued D1023 20:22:40.384312 35554 x:0] [ 33] Signal 9: terminating thread group D1023 20:22:40.384507 35554 x:0] [ 42] Interrupt queued D1023 20:22:40.384606 35554 x:0] [ 23] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.384730 35554 x:0] [ 43] Interrupt queued D1023 20:22:40.384768 35554 x:0] [ 17] Signal 9: terminating thread group D1023 20:22:40.384836 35554 x:0] [ 43] Signal 9: terminating thread group I1023 20:22:40.384904 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 17, fault addr: 0x0 D1023 20:22:40.384662 35554 x:0] [ 34] Interrupt queued D1023 20:22:40.385029 35554 x:0] [ 19] Signal 9: terminating thread group D1023 20:22:40.385061 35554 x:0] [ 20] Signal 9: terminating thread group I1023 20:22:40.385046 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 43, fault addr: 0x0 D1023 20:22:40.384981 35554 x:0] [ 18] Signal 9: terminating thread group D1023 20:22:40.385181 35554 x:0] [ 34] Signal 9: terminating thread group D1023 20:22:40.385198 35554 x:0] [ 23] Transitioning from exit state TaskExitInitiated to TaskExitZombie I1023 20:22:40.385262 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 19, fault addr: 0x0 D1023 20:22:40.385305 35554 x:0] [ 21] Signal 9: terminating thread group D1023 20:22:40.385323 35554 x:0] [ 23] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.385401 35554 x:0] [ 22] Signal 9: terminating thread group D1023 20:22:40.385544 35554 x:0] [ 27] Signal 9: terminating thread group D1023 20:22:40.385524 35554 x:0] [ 24] Signal 9: terminating thread group D1023 20:22:40.385650 35554 x:0] [ 26] Signal 9: terminating thread group D1023 20:22:40.385760 35554 x:0] [ 28] Signal 9: terminating thread group D1023 20:22:40.385862 35554 x:0] [ 29] Signal 9: terminating thread group D1023 20:22:40.385918 35554 x:0] [ 30] Signal 9: terminating thread group D1023 20:22:40.386065 35554 x:0] [ 31] Signal 9: terminating thread group D1023 20:22:40.386509 35554 x:0] [ 32] Signal 9: terminating thread group D1023 20:22:40.386617 35554 x:0] [ 42] Signal 9: terminating thread group D1023 20:22:40.386706 35554 x:0] [ 25] Signal 9: terminating thread group I1023 20:22:40.386938 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 20, fault addr: 0x0 I1023 20:22:40.387107 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 18, fault addr: 0x0 I1023 20:22:40.387261 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 34, fault addr: 0x0 I1023 20:22:40.387407 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 21, fault addr: 0x0 I1023 20:22:40.387540 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 22, fault addr: 0x0 I1023 20:22:40.387685 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 27, fault addr: 0x0 I1023 20:22:40.387820 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 33, fault addr: 0x0 D1023 20:22:40.387982 35554 x:0] [ 20] Transitioning from exit state TaskExitNone to TaskExitInitiated I1023 20:22:40.387945 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 31, fault addr: 0x0 I1023 20:22:40.388087 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 42, fault addr: 0x0 I1023 20:22:40.388250 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 24, fault addr: 0x0 D1023 20:22:40.388327 35554 x:0] [ 17] Transitioning from exit state TaskExitNone to TaskExitInitiated I1023 20:22:40.388409 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 25, fault addr: 0x0 D1023 20:22:40.388474 35554 x:0] [ 18] Transitioning from exit state TaskExitNone to TaskExitInitiated I1023 20:22:40.388520 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 28, fault addr: 0x0 D1023 20:22:40.388594 35554 x:0] [ 43] Transitioning from exit state TaskExitNone to TaskExitInitiated I1023 20:22:40.388664 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 29, fault addr: 0x0 D1023 20:22:40.388739 35554 x:0] [ 19] Transitioning from exit state TaskExitNone to TaskExitInitiated I1023 20:22:40.388803 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 30, fault addr: 0x0 D1023 20:22:40.388863 35554 x:0] [ 34] Transitioning from exit state TaskExitNone to TaskExitInitiated I1023 20:22:40.388913 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 32, fault addr: 0x0 D1023 20:22:40.388951 35554 x:0] [ 21] Transitioning from exit state TaskExitNone to TaskExitInitiated I1023 20:22:40.389024 35554 x:0] Uncaught signal: "killed" (9), PID: 17, TID: 26, fault addr: 0x0 D1023 20:22:40.389062 35554 x:0] [ 22] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.389152 35554 x:0] [ 27] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.389296 35554 x:0] [ 33] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.389433 35554 x:0] [ 31] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.389545 35554 x:0] [ 42] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.389727 35554 x:0] [ 24] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.389835 35554 x:0] [ 25] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.389946 35554 x:0] [ 28] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.390089 35554 x:0] [ 29] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.390433 35554 x:0] [ 32] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.390694 35554 x:0] [ 26] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.390825 35554 x:0] [ 30] Transitioning from exit state TaskExitNone to TaskExitInitiated D1023 20:22:40.391315 35554 x:0] [ 22] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.391451 35554 x:0] [ 22] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.391869 35554 x:0] [ 32] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.391963 35554 x:0] [ 32] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.392175 35554 x:0] [ 27] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.392260 35554 x:0] [ 27] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.392568 35554 x:0] [ 26] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.392676 35554 x:0] [ 26] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.393119 35554 x:0] [ 33] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.396845 35554 x:0] [ 33] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.398267 35554 x:0] [ 20] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.398376 35554 x:0] [ 20] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.398755 35554 x:0] [ 31] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.398841 35554 x:0] [ 31] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.399032 35554 x:0] [ 42] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.399107 35554 x:0] [ 42] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.399258 35554 x:0] [ 30] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.399352 35554 x:0] [ 30] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.399480 35554 x:0] [ 17] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.399612 35554 x:0] [ 24] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.399681 35554 x:0] [ 24] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.399798 35554 x:0] [ 18] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.399858 35554 x:0] [ 18] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.400001 35554 x:0] [ 25] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.400066 35554 x:0] [ 25] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.400281 35554 x:0] [ 43] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.400339 35554 x:0] [ 43] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.400474 35554 x:0] [ 28] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.400533 35554 x:0] [ 28] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.400667 35554 x:0] [ 19] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.400726 35554 x:0] [ 19] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.400919 35554 x:0] [ 29] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.400981 35554 x:0] [ 29] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.401109 35554 x:0] [ 34] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.401173 35554 x:0] [ 34] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.401309 35554 x:0] [ 21] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1023 20:22:40.401370 35554 x:0] [ 21] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.401425 35554 x:0] [ 17] Transitioning from exit state TaskExitZombie to TaskExitDead D1023 20:22:40.401596 35554 x:0] updated processes (removal): map[{ci-gvisor-ptrace-direct-overlay-host-race-test-2 0}:0xc0002dfa40] D1023 20:22:40.401832 35554 x:0] urpc: successfully marshalled 38 bytes. D1023 20:22:40.402035 40172 x:0] urpc: unmarshal success. I1023 20:22:40.402168 40172 x:0] Exiting with status: 256 DIAGNOSIS: I1023 20:22:40.456863 42632 x:0] *************************** I1023 20:22:40.457030 42632 x:0] Args: [/syzkaller/managers/ptrace-direct-overlay-host-race/latest.tmp/image -root /syzkaller/managers/ptrace-direct-overlay-host-race/latest.tmp/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=exclusive -overlay -network=host -TESTONLY-unsafe-nonroot debug -stacks ci-gvisor-ptrace-direct-overlay-host-race-test-2] I1023 20:22:40.457219 42632 x:0] Version release-20190806.1-307-gc0065e296f6e I1023 20:22:40.457260 42632 x:0] PID: 42632 I1023 20:22:40.457329 42632 x:0] UID: 0, GID: 0 I1023 20:22:40.457373 42632 x:0] Configuration: I1023 20:22:40.457395 42632 x:0] RootDir: /syzkaller/managers/ptrace-direct-overlay-host-race/latest.tmp/workdir/gvisor_root I1023 20:22:40.457447 42632 x:0] Platform: ptrace I1023 20:22:40.457486 42632 x:0] FileAccess: exclusive, overlay: true I1023 20:22:40.457548 42632 x:0] Network: host, logging: false I1023 20:22:40.457584 42632 x:0] Strace: false, max size: 1024, syscalls: [] I1023 20:22:40.457637 42632 x:0] *************************** W1023 20:22:40.457662 42632 x:0] Block the TERM signal. This is only safe in tests! D1023 20:22:40.457802 42632 x:0] Load container "/syzkaller/managers/ptrace-direct-overlay-host-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:40.466660 42632 x:0] Signal container "ci-gvisor-ptrace-direct-overlay-host-race-test-2": signal 0 D1023 20:22:40.466840 42632 x:0] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:40.466923 42632 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:40.468712 42632 x:0] urpc: successfully marshalled 128 bytes. D1023 20:22:40.469155 42632 x:0] urpc: unmarshal success. I1023 20:22:40.469759 42632 x:0] Found sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2", PID: 35554 I1023 20:22:40.471272 42632 x:0] Retrieving sandbox stacks D1023 20:22:40.471412 42632 x:0] Stacks sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:40.471535 42632 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-test-2" D1023 20:22:40.476182 42632 x:0] urpc: successfully marshalled 36 bytes. D1023 20:22:40.493064 42632 x:0] urpc: unmarshal success. I1023 20:22:40.493188 42632 x:0] *** Stack dump *** goroutine 868 [running]: gvisor.dev/gvisor/pkg/log.Stacks(0x460d01, 0xc00036c240, 0xc0004d1110, 0xc0003edc00) pkg/log/log.go:272 +0xb6 gvisor.dev/gvisor/runsc/boot.(*debug).Stacks(0x2406368, 0x2406368, 0xc0002b0340, 0x0, 0x0) runsc/boot/debug.go:26 +0x38 reflect.Value.call(0xc00036c240, 0xc000144ab0, 0x13, 0x122ed2a, 0x4, 0xc000637e78, 0x3, 0x3, 0x109aa01, 0xc0002b0340, ...) GOROOT/src/reflect/value.go:460 +0x62b reflect.Value.Call(0xc00036c240, 0xc000144ab0, 0x13, 0xc000637e78, 0x3, 0x3, 0x0, 0x2406368, 0x16) GOROOT/src/reflect/value.go:321 +0xd4 gvisor.dev/gvisor/pkg/urpc.(*Server).handleOne(0xc0002e7230, 0xc0004164b0, 0x0, 0x0) pkg/urpc/urpc.go:325 +0x688 gvisor.dev/gvisor/pkg/urpc.(*Server).handleRegistered(0xc0002e7230, 0xc0004164b0, 0x0, 0x0) pkg/urpc/urpc.go:420 +0x43 gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling.func1(0xc0002e7230, 0xc0004164b0) 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(0xc00026a2b4) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc00026a2b4) GOROOT/src/sync/waitgroup.go:130 +0xb1 gvisor.dev/gvisor/pkg/sentry/kernel.(*Kernel).WaitExited(...) pkg/sentry/kernel/kernel.go:1046 gvisor.dev/gvisor/runsc/boot.(*Loader).WaitExit(0xc0001562c0, 0x0, 0x0) runsc/boot/loader.go:903 +0x73 gvisor.dev/gvisor/runsc/cmd.(*Boot).Execute(0xc0001bc2d0, 0x1397700, 0xc000132000, 0xc000292540, 0xc000240ba0, 0x2, 0x2, 0x0) runsc/cmd/boot.go:254 +0x149e github.com/google/subcommands.(*Commander).Execute(0xc00014a000, 0x1397700, 0xc000132000, 0xc000240ba0, 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:295 +0x2c2b 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(0x12618b8, 0xc0001209c0, 0xc000120a20) 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(0xc00029a948, 0xa) GOROOT/src/runtime/sema.go:510 +0xf8 sync.(*Cond).Wait(0xc00029a938) GOROOT/src/sync/cond.go:56 +0x8e gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).findReclaimable(0xc00029a500, 0x0, 0x0, 0x0) pkg/sentry/pgalloc/pgalloc.go:1090 +0xbb gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).runReclaim(0xc00029a500) 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(0xc000292f00, 0xc00026a230) 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 25 [select]: reflect.rselect(0xc0002a0000, 0x22, 0x22, 0xc0002a0000, 0x1a67800) GOROOT/src/runtime/select.go:542 +0x3bd reflect.Select(0xc00037e000, 0x22, 0x49, 0x0, 0x10f5260, 0x2406368, 0x99, 0x0) GOROOT/src/reflect/value.go:2229 +0x1f0 gvisor.dev/gvisor/pkg/sentry/sighandling.handleSignals(0xc0002a8200, 0x21, 0x40, 0xc0002db290, 0xc000120d20, 0xc000120d80, 0xc000120de0) pkg/sentry/sighandling/sighandling.go:46 +0x46c created by gvisor.dev/gvisor/pkg/sentry/sighandling.PrepareHandler pkg/sentry/sighandling/sighandling.go:131 +0x29d goroutine 26 [syscall]: syscall.Syscall6(0x10f, 0xc000372e68, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:44 +0x5 gvisor.dev/gvisor/pkg/unet.(*Socket).wait(0xc0002e71a0, 0xffffffffffffff00, 0x0, 0x0) pkg/unet/unet_unsafe.go:55 +0xda gvisor.dev/gvisor/pkg/unet.(*ServerSocket).Accept(0xc000144918, 0x0, 0x0, 0x0) pkg/unet/unet.go:539 +0x189 gvisor.dev/gvisor/pkg/control/server.(*Server).serve(0xc0002dfaa0) pkg/control/server/server.go:99 +0x50 gvisor.dev/gvisor/pkg/control/server.(*Server).StartServing.func1(0xc0002dfaa0) 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 136 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000454000, 0xc000121a40, 0xc000432060, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithDeadline(0xc000454000, 0xc000121a40, 0xc000454801, 0xe7b0cd2cd, 0x0, 0xc000404360) pkg/sentry/kernel/task_block.go:79 +0xda gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000454000, 0xc000121a40, 0x1398b01, 0xdf8475800, 0x36d8a50, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:41 +0x11d gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000454000, 0xdf8475800, 0x0, 0x36d8a50, 0x0, 0xffffffff, 0xa8fda3, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000454000, 0x36d8a50, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x9afa, 0xc0004c7948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000454000, 0xca, 0x36d8a50, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000454000, 0xca, 0x36d8a50, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x1386e20, 0xc00055a2d0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000454000, 0xca, 0x36d8a50, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0xc0004c7ce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000454000, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000454000, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000454000, 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 92 [syscall]: syscall.Syscall6(0x119, 0x10, 0xc000379ae0, 0x64, 0xffffffffffffffff, 0x0, 0x0, 0xc00000e088, 0x4645ec, 0x474baf) src/syscall/asm_linux_amd64.s:44 +0x5 gvisor.dev/gvisor/pkg/fdnotifier.epollWait(0x10, 0xc000379ae0, 0x64, 0x64, 0xffffffffffffffff, 0x1, 0x0, 0x0) pkg/fdnotifier/poll_unsafe.go:77 +0x83 gvisor.dev/gvisor/pkg/fdnotifier.(*notifier).waitAndNotify(0xc00000e080, 0x0, 0xf5da30) pkg/fdnotifier/fdnotifier.go:149 +0x9e created by gvisor.dev/gvisor/pkg/fdnotifier.newNotifier pkg/fdnotifier/fdnotifier.go:64 +0x114 goroutine 93 [syscall]: syscall.Syscall6(0x10f, 0xc000132010, 0x1, 0x0, 0x0, 0x0, 0x0, 0x200, 0x1a663f8, 0x7f0d37f3a878) src/syscall/asm_linux_amd64.s:44 +0x5 golang.org/x/sys/unix.ppoll(0xc000132010, 0x1, 0x0, 0x0, 0xc000132010, 0x10, 0x101000000000028) external/org_golang_x_sys/unix/zsyscall_linux_amd64.go:79 +0xa2 golang.org/x/sys/unix.Ppoll(0xc000132010, 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(0xe311fa, 0x8, 0x1, 0x0) runsc/boot/loader.go:711 +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(0x7ffc4dacefb8, 0x30, 0xc0002aa058, 0x1, 0x1, 0xc0001562c0) runsc/boot/loader.go:709 +0x25a created by gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor runsc/boot/loader.go:700 +0x7f goroutine 94 [syscall]: syscall.Syscall6(0x10f, 0xc000013720, 0x1, 0x0, 0x0, 0x0, 0x0, 0x13a78a0, 0x0, 0x1397f00) src/syscall/asm_linux_amd64.s:44 +0x5 golang.org/x/sys/unix.ppoll(0xc000013720, 0x1, 0x0, 0x0, 0x1398340, 0x0, 0x1386420) external/org_golang_x_sys/unix/zsyscall_linux_amd64.go:79 +0xa2 golang.org/x/sys/unix.Ppoll(0xc000013720, 0x1, 0x1, 0x0, 0x0, 0x1398b00, 0x138ad40, 0x0) external/org_golang_x_sys/unix/syscall_linux.go:133 +0xda gvisor.dev/gvisor/pkg/p9.(*Client).watch(0xc000256100, 0xc0002628d0) pkg/p9/client.go:251 +0xda created by gvisor.dev/gvisor/pkg/p9.NewClient pkg/p9/client.go:231 +0x75e goroutine 96 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0001bc360) 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 97 [select]: gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).loop(0xc00025d3b0) pkg/sentry/watchdog/watchdog.go:181 +0x144 created by gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).Start pkg/sentry/watchdog/watchdog.go:155 +0x305 goroutine 130 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0001bc3f0) 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 131 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000234000, 0xc000121500, 0x0, 0x69090b, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000234000, 0xc000121500, 0x1398b00, 0x0, 0x36d9808, 0x4c3600, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000234000, 0x0, 0xc420009c01, 0x36d9808, 0x0, 0xffffffff, 0xf5d990, 0x7f0d10461bb8, 0xc000464920) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000234000, 0x36d9808, 0x0, 0x0, 0x0, 0x0, 0x0, 0x9c2f, 0xc0004c3948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000234000, 0xca, 0x36d9808, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000234000, 0xca, 0x36d9808, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc0004485a0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000234000, 0xca, 0x36d9808, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0004c3ce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000234000, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000234000, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000234000, 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 132 [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 133 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc000464cf0, 0xc000121680, 0xc0001216e0) 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 135 [semacquire]: sync.runtime_Semacquire(0xc0003c96b8) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc0003c96b0) 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(0xc0001562c0, 0xc0003c9000, 0x30) runsc/boot/loader.go:891 +0x43 gvisor.dev/gvisor/runsc/boot.(*Loader).waitContainer(0xc0001562c0, 0xc00015c210, 0x30, 0xc000132780, 0x0, 0xc000416330) runsc/boot/loader.go:846 +0x1af gvisor.dev/gvisor/runsc/boot.(*containerManager).Wait(0xc0002dfac0, 0xc0002b0140, 0xc000132780, 0x0, 0x0) runsc/boot/controller.go:422 +0xb9 reflect.Value.call(0xc00036c180, 0xc000144a70, 0x13, 0x122ed2a, 0x4, 0xc000231e78, 0x3, 0x3, 0x1097b01, 0xc000132780, ...) GOROOT/src/reflect/value.go:460 +0x62b reflect.Value.Call(0xc00036c180, 0xc000144a70, 0x13, 0xc000231e78, 0x3, 0x3, 0x0, 0xc0002dfac0, 0x16) GOROOT/src/reflect/value.go:321 +0xd4 gvisor.dev/gvisor/pkg/urpc.(*Server).handleOne(0xc0002e7230, 0xc0002e74a0, 0x0, 0x0) pkg/urpc/urpc.go:325 +0x688 gvisor.dev/gvisor/pkg/urpc.(*Server).handleRegistered(0xc0002e7230, 0xc0002e74a0, 0xc000297860, 0x204128203d2b2063) pkg/urpc/urpc.go:420 +0x43 gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling.func1(0xc0002e7230, 0xc0002e74a0) pkg/urpc/urpc.go:440 +0x81 created by gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling pkg/urpc/urpc.go:438 +0x6f goroutine 115 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0002da000) 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 27 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0004e4000, 0xc0004be180, 0x0, 0xc0002d37e0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0004e4000, 0xc0004be180, 0x1398b00, 0x0, 0xc4200ca548, 0x293000, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0004e4000, 0x0, 0x1, 0xc4200ca548, 0x100, 0xffffffff, 0x0, 0x0, 0x8) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0004e4000, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x9c10, 0xc00022f948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0004e4000, 0xca, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0004e4000, 0xca, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc0004484b0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0004e4000, 0xca, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00022fce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0004e4000, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0004e4000, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0004e4000, 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 116 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0002da090) 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(0xc00051a000, 0xc000432480, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00051a000, 0xc000432480, 0x1398b00, 0x0, 0xc4200ca948, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00051a000, 0x0, 0xc00051a001, 0xc4200ca948, 0x0, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00051a000, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x99c4, 0xc0004c1948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00051a000, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc00051a000, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc0002f84b0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00051a000, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0004c1ce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00051a000, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00051a000, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00051a000, 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 147 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00051e000) 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 28 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0004e4a80, 0xc0004be480, 0x0, 0x4645ec, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0004e4a80, 0xc0004be480, 0x1398b00, 0x0, 0x36f7680, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0004e4a80, 0x0, 0xc0004e4a01, 0x36f7680, 0x0, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0004e4a80, 0x36f7680, 0x0, 0x0, 0x0, 0x0, 0x0, 0x9183, 0xc000573948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0004e4a80, 0xca, 0x36f7680, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0004e4a80, 0xca, 0x36f7680, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc0004482d0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0004e4a80, 0xca, 0x36f7680, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000573ce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0004e4a80, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0004e4a80, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0004e4a80, 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 29 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00053a000) 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 77 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000454a80, 0xc000174360, 0x0, 0x4645ec, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000454a80, 0xc000174360, 0x1398b00, 0x0, 0xc4200cad48, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000454a80, 0x0, 0xc000454a01, 0xc4200cad48, 0x0, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000454a80, 0xc4200cad48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x99c4, 0xc00022b948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000454a80, 0xca, 0xc4200cad48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000454a80, 0xca, 0xc4200cad48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc0002f84b0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000454a80, 0xca, 0xc4200cad48, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00022bce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000454a80, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000454a80, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000454a80, 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 137 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00051e090) 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 81 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc00051aa80, 0xc000174ba0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00051aa80, 0xc000174ba0, 0x1398b00, 0x0, 0xc4200f7148, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00051aa80, 0x0, 0xc00051aa01, 0xc4200f7148, 0x0, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00051aa80, 0xc4200f7148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8f72, 0xc000571948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00051aa80, 0xca, 0xc4200f7148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc00051aa80, 0xca, 0xc4200f7148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc00032c2d0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00051aa80, 0xca, 0xc4200f7148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000571ce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00051aa80, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00051aa80, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00051aa80, 0x8) 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 163 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00053a090) 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 138 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000455500, 0xc0006528a0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000455500, 0xc0006528a0, 0x1398b00, 0x0, 0xc42049a548, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000455500, 0x0, 0x1, 0xc42049a548, 0x20100100, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000455500, 0xc42049a548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x9c10, 0xc0003e7948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000455500, 0xca, 0xc42049a548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000455500, 0xca, 0xc42049a548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc0004484b0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000455500, 0xca, 0xc42049a548, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0003e7ce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000455500, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000455500, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000455500, 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 164 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0005ec090) 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 151 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0004e5500, 0xc0001746c0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0004e5500, 0xc0001746c0, 0x1398b00, 0x0, 0xc420488948, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0004e5500, 0x0, 0xc0004e5501, 0xc420488948, 0x20490100, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0004e5500, 0xc420488948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x902e, 0xc000575948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0004e5500, 0xca, 0xc420488948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0004e5500, 0xca, 0xc420488948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc00032c3c0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0004e5500, 0xca, 0xc420488948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000575ce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0004e5500, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0004e5500, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0004e5500, 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 210 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc00051b500, 0xc000175c80, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00051b500, 0xc000175c80, 0x1398b00, 0x0, 0xc42049a148, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00051b500, 0x0, 0xc00051b501, 0xc42049a148, 0x20490100, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00051b500, 0xc42049a148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8bd8, 0xc000635948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00051b500, 0xca, 0xc42049a148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc00051b500, 0xca, 0xc42049a148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc0004480f0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00051b500, 0xca, 0xc42049a148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000635ce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00051b500, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00051b500, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00051b500, 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.(*Task).block(0xc0005e2000, 0xc0005ca120, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0005e2000, 0xc0005ca120, 0x1398b00, 0x0, 0xc420488148, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0005e2000, 0x0, 0xc0005e2001, 0xc420488148, 0x0, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0005e2000, 0xc420488148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x902e, 0xc0003e5948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0005e2000, 0xca, 0xc420488148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x122d700, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0005e2000, 0xca, 0xc420488148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1386e20, 0xc00032c3c0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0005e2000, 0xca, 0xc420488148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0003e5ce8, 0xdfd555) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0005e2000, 0x2, 0xc00032e040) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0005e2000, 0x1386e20, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0005e2000, 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 194 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0002ae3f0) 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 180 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0005ec000) 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 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0001bc000) 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 155 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc0002b0040, 0xc0001747e0, 0xc000174840) 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 203 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc0000f2720, 0xc000586ae0, 0xc000586b40) 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 186 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc00067c030, 0xc0001202a0, 0xc000120300) 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 206 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc0000f2000, 0xc000433e00, 0xc000433e60) 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 I1023 20:22:40.493474 42632 x:0] Exiting with status: 0 D1023 20:22:40.468164 35554 x:0] urpc: unmarshal success. D1023 20:22:40.468376 35554 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-race-test-2 Signo:0 PID:0 Mode:Process} D1023 20:22:40.468613 35554 x:0] urpc: successfully marshalled 37 bytes. D1023 20:22:40.476337 35554 x:0] urpc: unmarshal success. D1023 20:22:40.481100 35554 x:0] urpc: successfully marshalled 33818 bytes. D1023 20:22:41.282154 35554 x:0] Clock(Monotonic): error: 80 ns, adjusted frequency from 2299806719 Hz to 2299806642 Hz D1023 20:22:41.282372 35554 x:0] Clock(Realtime): error: 111 ns, adjusted frequency from 2299806686 Hz to 2299806727 Hz D1023 20:22:41.282468 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503590389185724 monotonicBaseRef:6000795669 monotonicFrequency:2299806642 realtimeReady:1 realtimeBaseCycles:31503590389717086 realtimeBaseRef:1571862161282365838 realtimeFrequency:2299806727} D1023 20:22:42.281778 35554 x:0] Clock(Monotonic): error: 52 ns, adjusted frequency from 2299806642 Hz to 2299806574 Hz D1023 20:22:42.281922 35554 x:0] Clock(Realtime): error: 4 ns, adjusted frequency from 2299806727 Hz to 2299806486 Hz D1023 20:22:42.282003 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503592688114317 monotonicBaseRef:7000413876 monotonicFrequency:2299806574 realtimeReady:1 realtimeBaseCycles:31503592688492378 realtimeBaseRef:1571862162281917350 realtimeFrequency:2299806486} D1023 20:22:43.281719 35554 x:0] Clock(Monotonic): error: 78 ns, adjusted frequency from 2299806574 Hz to 2299806654 Hz D1023 20:22:43.281863 35554 x:0] Clock(Realtime): error: -81 ns, adjusted frequency from 2299806486 Hz to 2299806296 Hz D1023 20:22:43.281953 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503594987740296 monotonicBaseRef:8000335349 monotonicFrequency:2299806654 realtimeReady:1 realtimeBaseCycles:31503594988159604 realtimeBaseRef:1571862163281856797 realtimeFrequency:2299806296} D1023 20:22:44.281776 35554 x:0] Clock(Monotonic): error: -90 ns, adjusted frequency from 2299806654 Hz to 2299806267 Hz D1023 20:22:44.281971 35554 x:0] Clock(Realtime): error: 27 ns, adjusted frequency from 2299806296 Hz to 2299806551 Hz D1023 20:22:44.282056 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503597287745183 monotonicBaseRef:9000421544 monotonicFrequency:2299806267 realtimeReady:1 realtimeBaseCycles:31503597288190845 realtimeBaseRef:1571862164281954607 realtimeFrequency:2299806551} D1023 20:22:45.281864 35554 x:0] Clock(Monotonic): error: 144 ns, adjusted frequency from 2299806267 Hz to 2299806880 Hz D1023 20:22:45.282061 35554 x:0] Clock(Realtime): error: 111 ns, adjusted frequency from 2299806551 Hz to 2299806794 Hz D1023 20:22:45.282183 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503599587750112 monotonicBaseRef:10000507926 monotonicFrequency:2299806880 realtimeReady:1 realtimeBaseCycles:31503599588226409 realtimeBaseRef:1571862165282054186 realtimeFrequency:2299806794} D1023 20:22:46.281648 35554 x:0] Clock(Monotonic): error: -26 ns, adjusted frequency from 2299806880 Hz to 2299806487 Hz D1023 20:22:46.281814 35554 x:0] Clock(Realtime): error: 14 ns, adjusted frequency from 2299806794 Hz to 2299806580 Hz D1023 20:22:46.281887 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503601887066786 monotonicBaseRef:11000294775 monotonicFrequency:2299806487 realtimeReady:1 realtimeBaseCycles:31503601887468546 realtimeBaseRef:1571862166281808662 realtimeFrequency:2299806580} D1023 20:22:47.281638 35554 x:0] Clock(Monotonic): error: 18 ns, adjusted frequency from 2299806487 Hz to 2299806590 Hz D1023 20:22:47.281787 35554 x:0] Clock(Realtime): error: -112 ns, adjusted frequency from 2299806580 Hz to 2299806283 Hz D1023 20:22:47.281850 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503604186845997 monotonicBaseRef:12000282914 monotonicFrequency:2299806590 realtimeReady:1 realtimeBaseCycles:31503604187213507 realtimeBaseRef:1571862167281781868 realtimeFrequency:2299806283} D1023 20:22:48.281772 35554 x:0] Clock(Monotonic): error: -12 ns, adjusted frequency from 2299806590 Hz to 2299806540 Hz D1023 20:22:48.281907 35554 x:0] Clock(Realtime): error: 119 ns, adjusted frequency from 2299806283 Hz to 2299806828 Hz D1023 20:22:48.281999 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503606486928783 monotonicBaseRef:13000403009 monotonicFrequency:2299806540 realtimeReady:1 realtimeBaseCycles:31503606487293407 realtimeBaseRef:1571862168281900841 realtimeFrequency:2299806828} D1023 20:22:49.281908 35554 x:0] Time: Adjusting syscall overhead up to 8000 D1023 20:22:49.282105 35554 x:0] Clock(Monotonic): error: 512 ns, adjusted frequency from 2299806540 Hz to 2299807727 Hz D1023 20:22:49.282297 35554 x:0] Clock(Realtime): error: 26 ns, adjusted frequency from 2299806828 Hz to 2299806617 Hz D1023 20:22:49.282396 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503608787539944 monotonicBaseRef:14000752873 monotonicFrequency:2299807727 realtimeReady:1 realtimeBaseCycles:31503608787993471 realtimeBaseRef:1571862169282289237 realtimeFrequency:2299806617} D1023 20:22:50.281623 35554 x:0] Clock(Monotonic): error: -516 ns, adjusted frequency from 2299807727 Hz to 2299805354 Hz D1023 20:22:50.281764 35554 x:0] Clock(Realtime): error: -33 ns, adjusted frequency from 2299806617 Hz to 2299806465 Hz D1023 20:22:50.281854 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503611086229393 monotonicBaseRef:15000266624 monotonicFrequency:2299805354 realtimeReady:1 realtimeBaseCycles:31503611086578118 realtimeBaseRef:1571862170281757901 realtimeFrequency:2299806465} D1023 20:22:51.281707 35554 x:0] Clock(Monotonic): error: 32 ns, adjusted frequency from 2299805354 Hz to 2299806627 Hz D1023 20:22:51.281885 35554 x:0] Clock(Realtime): error: -6 ns, adjusted frequency from 2299806465 Hz to 2299806532 Hz D1023 20:22:51.281978 35554 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:31503613386176247 monotonicBaseRef:16000328150 monotonicFrequency:2299806627 realtimeReady:1 realtimeBaseCycles:31503613386647491 realtimeBaseRef:1571862171281872218 realtimeFrequency:2299806532}