I1129 01:42:35.081370 57751 x:0] *************************** I1129 01:42:35.081590 57751 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-1 /syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true] I1129 01:42:35.082091 57751 x:0] Version release-20191114.0-42-g10bbcf97d25b I1129 01:42:35.082128 57751 x:0] PID: 57751 I1129 01:42:35.082163 57751 x:0] UID: 0, GID: 0 I1129 01:42:35.082206 57751 x:0] Configuration: I1129 01:42:35.082234 57751 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root I1129 01:42:35.082262 57751 x:0] Platform: ptrace I1129 01:42:35.082297 57751 x:0] FileAccess: shared, overlay: false I1129 01:42:35.082353 57751 x:0] Network: sandbox, logging: false I1129 01:42:35.082392 57751 x:0] Strace: false, max size: 1024, syscalls: [] I1129 01:42:35.082473 57751 x:0] *************************** W1129 01:42:35.082506 57751 x:0] Block the TERM signal. This is only safe in tests! D1129 01:42:35.082918 57751 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:35.091017 57751 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-test-1": signal 0 D1129 01:42:35.091161 57751 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:35.091210 57751 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:35.102600 57751 x:0] urpc: successfully marshalled 122 bytes. D1129 01:42:35.102825 57751 x:0] urpc: unmarshal success. D1129 01:42:35.102983 57751 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 D1129 01:42:35.103045 57751 x:0] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D1129 01:42:35.103137 57751 x:0] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-test-1", args: /syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true D1129 01:42:35.103219 57751 x:0] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-test-1" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:35.103311 57751 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:35.101770 56590 x:0] urpc: unmarshal success. D1129 01:42:35.102279 56590 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-test-1 Signo:0 PID:0 Mode:Process} D1129 01:42:35.102513 56590 x:0] urpc: successfully marshalled 37 bytes. D1129 01:42:35.105577 56590 x:0] urpc: unmarshal success. D1129 01:42:35.107115 56590 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 D1129 01:42:35.107288 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D1129 01:42:35.107687 56590 x:0] recv [channel @0xc0002ee540] Rlerror{Error: 2} I1129 01:42:35.109512 56590 x:0] EXEC: [/syz-fuzzer -executor=/syz-executor -name=test -arch=amd64 -manager=stdin -sandbox=none -procs=0 -cover=false -debug=false -test=true] D1129 01:42:35.110077 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-fuzzer]} D1129 01:42:35.111660 56590 x:0] recv [channel @0xc0002ee540] 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: 21759776, BlockSize: 4096, Blocks: 42504, ATime: {Sec: 1574972149, NanoSec: 66139376}, MTime: {Sec: 1574972149, NanoSec: 66139376}, CTime: {Sec: 1574991754, NanoSec: 869643051}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124464}]} D1129 01:42:35.112601 56590 x:0] send [channel @0xc0002ee540] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1129 01:42:35.113252 56590 x:0] recv [channel @0xc0002ee540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124464}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 21759776, BlockSize: 4096, Blocks: 42504, ATime: {Sec: 1574972149, NanoSec: 66139376}, MTime: {Sec: 1574972149, NanoSec: 66139376}, CTime: {Sec: 1574991754, NanoSec: 869643051}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1129 01:42:35.113347 56590 x:0] send [channel @0xc0002ee540] Twalk{FID: 5, NewFID: 6, Names: []} D1129 01:42:35.113659 56590 x:0] recv [channel @0xc0002ee540] Rwalk{QIDs: []} D1129 01:42:35.113737 56590 x:0] send [channel @0xc0002ee540] Tlopen{FID: 6, Flags: ReadOnly} D1129 01:42:35.114185 56590 x:0] recv [channel @0xc0002ee540] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16124464}, IoUnit: 0, File: &{{33}}} D1129 01:42:35.122511 56590 x:0] Allocating stack with size of 8388608 bytes D1129 01:42:35.133390 57751 x:0] urpc: successfully marshalled 573 bytes. D1129 01:42:35.148447 56590 x:0] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-test-1 0}:0xc0003382e0 {ci-gvisor-ptrace-proxy-sandbox-race-test-1 16}:0xc0005d3ba0] D1129 01:42:35.149897 57751 x:0] urpc: unmarshal success. D1129 01:42:35.150143 57751 x:0] Wait on PID 16 in container "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:35.150221 57751 x:0] Waiting for PID 16 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:35.150338 57751 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:35.150789 57751 x:0] urpc: successfully marshalled 105 bytes. D1129 01:42:35.149610 56590 x:0] urpc: successfully marshalled 37 bytes. D1129 01:42:35.151274 56590 x:0] urpc: unmarshal success. D1129 01:42:35.151587 56590 x:0] containerManager.Wait D1129 01:42:35.272251 56590 x:0] [ 16] Interrupt queued D1129 01:42:35.328013 56590 x:0] Clock(Monotonic): error: 242 ns, adjusted frequency from 2299806274 Hz to 2299806515 Hz D1129 01:42:35.328291 56590 x:0] Clock(Realtime): error: 200 ns, adjusted frequency from 2299806015 Hz to 2299806522 Hz D1129 01:42:35.328355 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701050754904416 monotonicBaseRef:3001056014 monotonicFrequency:2299806515 realtimeReady:1 realtimeBaseCycles:38701050755579937 realtimeBaseRef:1574991755328284681 realtimeFrequency:2299806522} D1129 01:42:35.686257 56590 x:0] [ 17] Interrupt queued D1129 01:42:35.711412 56590 x:0] [ 18] Interrupt queued D1129 01:42:35.736491 56590 x:0] [ 19] Interrupt queued D1129 01:42:35.788981 56590 x:0] [ 20] Interrupt queued D1129 01:42:35.976045 56590 x:0] [ 21] Interrupt queued D1129 01:42:35.992345 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D1129 01:42:35.995657 56590 x:0] recv [channel @0xc0002ee540] Rlerror{Error: 2} D1129 01:42:36.002867 56590 x:0] [ 22] Interrupt queued D1129 01:42:36.050911 56590 x:0] [ 23] Interrupt queued 2019/11/29 01:42:36 fuzzer started D1129 01:42:36.212447 56590 x:0] [ 24] Interrupt queued D1129 01:42:36.225797 56590 x:0] [ 25] Interrupt queued D1129 01:42:36.322247 56590 x:0] [ 26] Interrupt queued D1129 01:42:36.327232 56590 x:0] Clock(Monotonic): error: 80 ns, adjusted frequency from 2299806515 Hz to 2299806183 Hz D1129 01:42:36.327397 56590 x:0] Clock(Realtime): error: -66 ns, adjusted frequency from 2299806522 Hz to 2299805914 Hz D1129 01:42:36.327473 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701053052814071 monotonicBaseRef:4000231222 monotonicFrequency:2299806183 realtimeReady:1 realtimeBaseCycles:38701053053332439 realtimeBaseRef:1574991756327391553 realtimeFrequency:2299805914} D1129 01:42:36.423921 56590 x:0] [ 27] Interrupt queued D1129 01:42:36.426855 56590 x:0] [ 28] Interrupt queued D1129 01:42:36.474252 56590 x:0] [ 29] Interrupt queued D1129 01:42:36.482613 56590 x:0] [ 30] Interrupt queued D1129 01:42:36.517720 56590 x:0] [ 31] Interrupt queued 2019/11/29 01:42:36 connecting to host at stdin D1129 01:42:36.527091 56590 x:0] [ 32] Interrupt queued 2019/11/29 01:42:36 checking machine... 2019/11/29 01:42:36 checking revisions... D1129 01:42:36.548566 56590 x:0] [ 26] Entering internal stop (*kernel.vforkStop)(nil) D1129 01:42:36.549407 56590 x:0] [ 33] Interrupt queued D1129 01:42:36.568846 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 7, Names: [syz-executor]} D1129 01:42:36.572006 56590 x:0] recv [channel @0xc0002ee540] 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: 1546080, BlockSize: 4096, Blocks: 3024, ATime: {Sec: 1574972131, NanoSec: 228636349}, MTime: {Sec: 1574972131, NanoSec: 228636349}, CTime: {Sec: 1574991754, NanoSec: 889644737}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124467}]} D1129 01:42:36.573034 56590 x:0] send [channel @0xc0002ee540] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1129 01:42:36.573719 56590 x:0] recv [channel @0xc0002ee540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124467}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1546080, BlockSize: 4096, Blocks: 3024, ATime: {Sec: 1574972131, NanoSec: 228636349}, MTime: {Sec: 1574972131, NanoSec: 228636349}, CTime: {Sec: 1574991754, NanoSec: 889644737}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1129 01:42:36.573883 56590 x:0] send [channel @0xc0002ee540] Twalk{FID: 7, NewFID: 8, Names: []} D1129 01:42:36.574315 56590 x:0] recv [channel @0xc0002ee540] Rwalk{QIDs: []} D1129 01:42:36.574450 56590 x:0] send [channel @0xc0002ee540] Tlopen{FID: 8, Flags: ReadOnly} D1129 01:42:36.600260 56590 x:0] recv [channel @0xc0002ee540] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16124467}, IoUnit: 0, File: &{{27}}} D1129 01:42:36.601316 56590 x:0] [ 33] Allocating stack with size of 8388608 bytes D1129 01:42:36.602794 56590 x:0] [ 26] Leaving internal stop (*kernel.vforkStop)(nil) D1129 01:42:36.758717 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 9, Names: [etc]} D1129 01:42:36.761892 56590 x:0] recv [channel @0xc0002ee540] Rlerror{Error: 2} D1129 01:42:36.772563 56590 x:0] [ 33] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:36.775290 56590 x:0] [ 33] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:36.775402 56590 x:0] [ 16] Notified of signal 17 D1129 01:42:36.775458 56590 x:0] [ 16] Interrupt queued D1129 01:42:36.778435 56590 x:0] [ 33] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:36.778828 56590 x:0] [ 16] Interrupt queued D1129 01:42:36.778973 56590 x:0] [ 16] Restarting syscall 202 after errno 512: interrupted by signal 17 D1129 01:42:36.779064 56590 x:0] [ 16] Signal 17: delivering to handler 2019/11/29 01:42:36 testing simple program... D1129 01:42:36.839721 56590 x:0] [ 26] Entering internal stop (*kernel.vforkStop)(nil) D1129 01:42:36.840436 56590 x:0] [ 34] Interrupt queued D1129 01:42:36.848202 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D1129 01:42:36.849235 56590 x:0] recv [channel @0xc0002ee540] 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: 1546080, BlockSize: 4096, Blocks: 3024, ATime: {Sec: 1574972131, NanoSec: 228636349}, MTime: {Sec: 1574972131, NanoSec: 228636349}, CTime: {Sec: 1574991754, NanoSec: 889644737}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124467}]} D1129 01:42:36.849378 56590 x:0] send [channel @0xc0002ee540] Tclunk{FID: 9} D1129 01:42:36.849736 56590 x:0] recv [channel @0xc0002ee540] Rclunk{} D1129 01:42:36.849967 56590 x:0] send [channel @0xc0002ee540] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1129 01:42:36.850534 56590 x:0] recv [channel @0xc0002ee540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124467}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1546080, BlockSize: 4096, Blocks: 3024, ATime: {Sec: 1574972131, NanoSec: 228636349}, MTime: {Sec: 1574972131, NanoSec: 228636349}, CTime: {Sec: 1574991754, NanoSec: 889644737}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1129 01:42:36.851471 56590 x:0] [ 34] Allocating stack with size of 8388608 bytes D1129 01:42:36.852437 56590 x:0] [ 26] Leaving internal stop (*kernel.vforkStop)(nil) D1129 01:42:36.857848 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 9, Names: [etc]} D1129 01:42:36.858266 56590 x:0] recv [channel @0xc0002ee540] Rlerror{Error: 2} D1129 01:42:36.861467 56590 x:0] [ 34] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:36.862472 56590 x:0] [ 34] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:36.862643 56590 x:0] [ 16] Notified of signal 17 D1129 01:42:36.862713 56590 x:0] [ 16] Interrupt queued D1129 01:42:36.863136 56590 x:0] [ 16] Interrupt queued D1129 01:42:36.863278 56590 x:0] [ 16] Restarting syscall 202 after errno 512: interrupted by signal 17 D1129 01:42:36.863360 56590 x:0] [ 16] Signal 17: delivering to handler D1129 01:42:36.863802 56590 x:0] [ 34] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:36.881770 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D1129 01:42:36.885303 56590 x:0] recv [channel @0xc0002ee540] 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: 1546080, BlockSize: 4096, Blocks: 3024, ATime: {Sec: 1574972131, NanoSec: 228636349}, MTime: {Sec: 1574972131, NanoSec: 228636349}, CTime: {Sec: 1574991754, NanoSec: 889644737}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124467}]} D1129 01:42:36.891603 56590 x:0] send [channel @0xc0002ee540] Tclunk{FID: 9} D1129 01:42:36.893016 56590 x:0] recv [channel @0xc0002ee540] Rclunk{} D1129 01:42:36.894198 56590 x:0] send [channel @0xc0002ee540] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1129 01:42:36.895380 56590 x:0] recv [channel @0xc0002ee540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124467}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1546080, BlockSize: 4096, Blocks: 3024, ATime: {Sec: 1574972131, NanoSec: 228636349}, MTime: {Sec: 1574972131, NanoSec: 228636349}, CTime: {Sec: 1574991754, NanoSec: 889644737}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1129 01:42:36.973505 56590 x:0] [ 26] Entering internal stop (*kernel.vforkStop)(nil) D1129 01:42:36.974390 56590 x:0] [ 35] Interrupt queued D1129 01:42:36.980257 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D1129 01:42:36.981327 56590 x:0] recv [channel @0xc0002ee540] 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: 1546080, BlockSize: 4096, Blocks: 3024, ATime: {Sec: 1574972131, NanoSec: 228636349}, MTime: {Sec: 1574972131, NanoSec: 228636349}, CTime: {Sec: 1574991754, NanoSec: 889644737}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16124467}]} D1129 01:42:36.981493 56590 x:0] send [channel @0xc0002ee540] Tclunk{FID: 9} D1129 01:42:36.981949 56590 x:0] recv [channel @0xc0002ee540] Rclunk{} D1129 01:42:36.982253 56590 x:0] send [channel @0xc0002ee540] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1129 01:42:36.983198 56590 x:0] recv [channel @0xc0002ee540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16124467}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1546080, BlockSize: 4096, Blocks: 3024, ATime: {Sec: 1574972131, NanoSec: 228636349}, MTime: {Sec: 1574972131, NanoSec: 228636349}, CTime: {Sec: 1574991754, NanoSec: 889644737}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1129 01:42:36.984463 56590 x:0] [ 35] Allocating stack with size of 8388608 bytes D1129 01:42:36.985693 56590 x:0] [ 26] Leaving internal stop (*kernel.vforkStop)(nil) D1129 01:42:37.050372 56590 x:0] send [channel @0xc0002ee540] Twalkgetattr{FID: 1, NewFID: 9, Names: [etc]} D1129 01:42:37.062210 56590 x:0] recv [channel @0xc0002ee540] Rlerror{Error: 2} D1129 01:42:37.158456 56590 x:0] [ 36] Interrupt queued D1129 01:42:37.279661 56590 x:0] [ 37] Interrupt queued D1129 01:42:37.327457 56590 x:0] [ 38] Interrupt queued D1129 01:42:37.327957 56590 x:0] Clock(Monotonic): error: 105 ns, adjusted frequency from 2299806183 Hz to 2299806276 Hz D1129 01:42:37.328314 56590 x:0] Clock(Realtime): error: 122 ns, adjusted frequency from 2299805914 Hz to 2299806366 Hz D1129 01:42:37.328490 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701055354409612 monotonicBaseRef:5001009269 monotonicFrequency:2299806276 realtimeReady:1 realtimeBaseCycles:38701055355241886 realtimeBaseRef:1574991757328306209 realtimeFrequency:2299806366} D1129 01:42:38.129195 56590 x:0] [ 39] Interrupt queued D1129 01:42:38.181732 56590 x:0] [ 38] Interrupt queued D1129 01:42:38.181986 56590 x:0] [ 39] Interrupt queued D1129 01:42:38.182051 56590 x:0] [ 37] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.182232 56590 x:0] [ 37] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.181862 56590 x:0] [ 38] Interrupt queued D1129 01:42:38.183528 56590 x:0] [ 38] Signal 9: terminating thread group D1129 01:42:38.183640 56590 x:0] [ 39] Interrupt queued I1129 01:42:38.183639 56590 x:0] Uncaught signal: "killed" (9), PID: 37, TID: 38, fault addr: 0x0 D1129 01:42:38.183801 56590 x:0] [ 39] Signal 9: terminating thread group I1129 01:42:38.183892 56590 x:0] Uncaught signal: "killed" (9), PID: 37, TID: 39, fault addr: 0x0 D1129 01:42:38.183961 56590 x:0] [ 38] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.184122 56590 x:0] [ 38] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.184187 56590 x:0] [ 38] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.184350 56590 x:0] [ 39] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.185480 56590 x:0] [ 39] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.185581 56590 x:0] [ 39] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.185703 56590 x:0] [ 36] Discarding ignored signal 17 D1129 01:42:38.191238 56590 x:0] [ 37] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.208953 56590 x:0] [ 35] Interrupt queued D1129 01:42:38.209069 56590 x:0] [ 35] Notified of signal 9 D1129 01:42:38.209136 56590 x:0] [ 35] Interrupt queued D1129 01:42:38.209591 56590 x:0] [ 35] Dropping duplicate interrupt D1129 01:42:38.209714 56590 x:0] [ 35] Signal 9: terminating thread group I1129 01:42:38.209797 56590 x:0] Uncaught signal: "killed" (9), PID: 35, TID: 35, fault addr: 0x0 D1129 01:42:38.209874 56590 x:0] [ 35] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.210637 56590 x:0] [ 36] Interrupt queued D1129 01:42:38.210706 56590 x:0] [ 36] Notified of signal 9 D1129 01:42:38.210751 56590 x:0] [ 36] Interrupt queued D1129 01:42:38.210828 56590 x:0] [ 35] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.210902 56590 x:0] [ 16] Notified of signal 17 D1129 01:42:38.210946 56590 x:0] [ 16] Interrupt queued D1129 01:42:38.211410 56590 x:0] [ 36] Dropping duplicate interrupt D1129 01:42:38.211587 56590 x:0] [ 36] Signal 9: terminating thread group D1129 01:42:38.215102 56590 x:0] [ 16] Interrupt queued D1129 01:42:38.215263 56590 x:0] [ 16] Restarting syscall 202 after errno 512: interrupted by signal 17 D1129 01:42:38.215357 56590 x:0] [ 16] Signal 17: delivering to handler I1129 01:42:38.211693 56590 x:0] Uncaught signal: "killed" (9), PID: 36, TID: 36, fault addr: 0x0 D1129 01:42:38.222400 56590 x:0] [ 36] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.225951 56590 x:0] [ 35] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.227302 56590 x:0] [ 36] Init process terminating, killing namespace D1129 01:42:38.227425 56590 x:0] [ 36] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.227549 56590 x:0] [ 1] Notified of signal 17 D1129 01:42:38.227626 56590 x:0] [ 1] Interrupt queued D1129 01:42:38.227768 56590 x:0] [ 1] Interrupt queued D1129 01:42:38.228022 56590 x:0] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 17 D1129 01:42:38.228180 56590 x:0] [ 1] Signal 17: delivering to handler 2019/11/29 01:42:38 BUG: simple call failed: {Flags:5 Signal:[65483928] Cover:[] Comps: Errno:999} D1129 01:42:38.311371 56590 x:0] [ 16] Interrupt queued D1129 01:42:38.311490 56590 x:0] [ 17] Interrupt queued D1129 01:42:38.311783 56590 x:0] [ 18] Interrupt queued D1129 01:42:38.311979 56590 x:0] [ 19] Interrupt queued D1129 01:42:38.312150 56590 x:0] [ 20] Interrupt queued D1129 01:42:38.312546 56590 x:0] [ 21] Interrupt queued D1129 01:42:38.313212 56590 x:0] [ 22] Interrupt queued D1129 01:42:38.313458 56590 x:0] [ 23] Interrupt queued D1129 01:42:38.313832 56590 x:0] [ 24] Interrupt queued D1129 01:42:38.314044 56590 x:0] [ 25] Interrupt queued D1129 01:42:38.314253 56590 x:0] [ 26] Interrupt queued D1129 01:42:38.314441 56590 x:0] [ 27] Interrupt queued D1129 01:42:38.315211 56590 x:0] [ 29] Interrupt queued D1129 01:42:38.315578 56590 x:0] [ 30] Interrupt queued D1129 01:42:38.315951 56590 x:0] [ 31] Interrupt queued D1129 01:42:38.316188 56590 x:0] [ 32] Interrupt queued D1129 01:42:38.316427 56590 x:0] [ 28] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.316673 56590 x:0] [ 28] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.316973 56590 x:0] [ 28] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.311687 56590 x:0] [ 16] Interrupt queued D1129 01:42:38.314356 56590 x:0] [ 26] Interrupt queued D1129 01:42:38.318211 56590 x:0] [ 16] Signal 9: terminating thread group D1129 01:42:38.318333 56590 x:0] [ 26] Signal 9: terminating thread group I1129 01:42:38.318378 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 16, fault addr: 0x0 D1129 01:42:38.312075 56590 x:0] [ 19] Interrupt queued D1129 01:42:38.318567 56590 x:0] [ 16] Transitioning from exit state TaskExitNone to TaskExitInitiated I1129 01:42:38.318698 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 26, fault addr: 0x0 D1129 01:42:38.316064 56590 x:0] [ 31] Interrupt queued D1129 01:42:38.313574 56590 x:0] [ 23] Interrupt queued D1129 01:42:38.311891 56590 x:0] [ 18] Interrupt queued D1129 01:42:38.318981 56590 x:0] [ 23] Signal 9: terminating thread group D1129 01:42:38.312328 56590 x:0] [ 20] Interrupt queued D1129 01:42:38.319000 56590 x:0] [ 31] Signal 9: terminating thread group D1129 01:42:38.312675 56590 x:0] [ 21] Interrupt queued D1129 01:42:38.319031 56590 x:0] [ 18] Signal 9: terminating thread group D1129 01:42:38.313941 56590 x:0] [ 24] Interrupt queued D1129 01:42:38.319108 56590 x:0] [ 20] Signal 9: terminating thread group D1129 01:42:38.314142 56590 x:0] [ 25] Interrupt queued D1129 01:42:38.319161 56590 x:0] [ 21] Signal 9: terminating thread group D1129 01:42:38.314524 56590 x:0] [ 27] Interrupt queued D1129 01:42:38.319203 56590 x:0] [ 24] Signal 9: terminating thread group D1129 01:42:38.313341 56590 x:0] [ 22] Interrupt queued D1129 01:42:38.319230 56590 x:0] [ 25] Signal 9: terminating thread group D1129 01:42:38.316304 56590 x:0] [ 32] Interrupt queued D1129 01:42:38.319274 56590 x:0] [ 27] Signal 9: terminating thread group D1129 01:42:38.318775 56590 x:0] [ 16] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.319376 56590 x:0] [ 32] Signal 9: terminating thread group D1129 01:42:38.315438 56590 x:0] [ 29] Interrupt queued D1129 01:42:38.319365 56590 x:0] [ 22] Signal 9: terminating thread group I1129 01:42:38.319510 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 27, fault addr: 0x0 D1129 01:42:38.319589 56590 x:0] [ 29] Signal 9: terminating thread group I1129 01:42:38.319616 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 21, fault addr: 0x0 D1129 01:42:38.315823 56590 x:0] [ 30] Interrupt queued D1129 01:42:38.319671 56590 x:0] [ 27] Transitioning from exit state TaskExitNone to TaskExitInitiated I1129 01:42:38.319707 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 32, fault addr: 0x0 D1129 01:42:38.319783 56590 x:0] [ 30] Signal 9: terminating thread group I1129 01:42:38.319813 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 24, fault addr: 0x0 I1129 01:42:38.319929 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 22, fault addr: 0x0 D1129 01:42:38.319969 56590 x:0] [ 27] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.320042 56590 x:0] [ 27] Transitioning from exit state TaskExitZombie to TaskExitDead I1129 01:42:38.320023 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 31, fault addr: 0x0 D1129 01:42:38.320112 56590 x:0] [ 19] Signal 9: terminating thread group I1129 01:42:38.320166 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 23, fault addr: 0x0 D1129 01:42:38.320251 56590 x:0] [ 24] Transitioning from exit state TaskExitNone to TaskExitInitiated I1129 01:42:38.320308 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 25, fault addr: 0x0 D1129 01:42:38.320370 56590 x:0] [ 21] Transitioning from exit state TaskExitNone to TaskExitInitiated I1129 01:42:38.320431 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 29, fault addr: 0x0 D1129 01:42:38.320518 56590 x:0] [ 26] Transitioning from exit state TaskExitNone to TaskExitInitiated I1129 01:42:38.320539 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 20, fault addr: 0x0 D1129 01:42:38.320660 56590 x:0] [ 20] Transitioning from exit state TaskExitNone to TaskExitInitiated I1129 01:42:38.320730 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 18, fault addr: 0x0 D1129 01:42:38.320857 56590 x:0] [ 17] Signal 9: terminating thread group I1129 01:42:38.320870 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 30, fault addr: 0x0 D1129 01:42:38.321044 56590 x:0] [ 20] Transitioning from exit state TaskExitInitiated to TaskExitZombie I1129 01:42:38.321024 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 19, fault addr: 0x0 D1129 01:42:38.321144 56590 x:0] [ 20] Transitioning from exit state TaskExitZombie to TaskExitDead I1129 01:42:38.321188 56590 x:0] Uncaught signal: "killed" (9), PID: 16, TID: 17, fault addr: 0x0 D1129 01:42:38.321331 56590 x:0] [ 21] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.321402 56590 x:0] [ 21] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.321513 56590 x:0] [ 30] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.321682 56590 x:0] [ 30] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.321764 56590 x:0] [ 30] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.321954 56590 x:0] [ 29] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.322042 56590 x:0] [ 29] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.322097 56590 x:0] [ 29] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.322250 56590 x:0] [ 17] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.322338 56590 x:0] [ 17] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.322383 56590 x:0] [ 17] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.322519 56590 x:0] [ 23] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.322596 56590 x:0] [ 23] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.322645 56590 x:0] [ 23] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.322775 56590 x:0] [ 25] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.322864 56590 x:0] [ 25] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.322917 56590 x:0] [ 25] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.323024 56590 x:0] [ 22] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.323119 56590 x:0] [ 22] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.323213 56590 x:0] [ 22] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.323371 56590 x:0] [ 32] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.323462 56590 x:0] [ 32] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.323548 56590 x:0] [ 32] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.323725 56590 x:0] [ 31] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.323829 56590 x:0] [ 31] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.323883 56590 x:0] [ 31] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.324030 56590 x:0] [ 24] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.324086 56590 x:0] [ 24] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.324814 56590 x:0] [ 18] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.324943 56590 x:0] [ 18] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.325012 56590 x:0] [ 18] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.325217 56590 x:0] [ 26] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.325298 56590 x:0] [ 26] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.325417 56590 x:0] [ 19] Transitioning from exit state TaskExitNone to TaskExitInitiated D1129 01:42:38.328924 56590 x:0] Clock(Monotonic): error: 101 ns, adjusted frequency from 2299806276 Hz to 2299806309 Hz D1129 01:42:38.329072 56590 x:0] Clock(Realtime): error: 49 ns, adjusted frequency from 2299806366 Hz to 2299806210 Hz D1129 01:42:38.329189 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701057656382685 monotonicBaseRef:6001951434 monotonicFrequency:2299806309 realtimeReady:1 realtimeBaseCycles:38701057656786212 realtimeBaseRef:1574991758329061907 realtimeFrequency:2299806210} D1129 01:42:38.330680 56590 x:0] [ 19] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1129 01:42:38.330783 56590 x:0] [ 19] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.330865 56590 x:0] [ 16] Transitioning from exit state TaskExitZombie to TaskExitDead D1129 01:42:38.331105 56590 x:0] updated processes (removal): map[{ci-gvisor-ptrace-proxy-sandbox-race-test-1 0}:0xc0003382e0] D1129 01:42:38.331370 56590 x:0] urpc: successfully marshalled 38 bytes. D1129 01:42:38.332323 57751 x:0] urpc: unmarshal success. I1129 01:42:38.332432 57751 x:0] Exiting with status: 256 DIAGNOSIS: I1129 01:42:38.432055 59447 x:0] *************************** I1129 01:42:38.432125 59447 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-1] I1129 01:42:38.432248 59447 x:0] Version release-20191114.0-42-g10bbcf97d25b I1129 01:42:38.432296 59447 x:0] PID: 59447 I1129 01:42:38.432331 59447 x:0] UID: 0, GID: 0 I1129 01:42:38.432376 59447 x:0] Configuration: I1129 01:42:38.432404 59447 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root I1129 01:42:38.432432 59447 x:0] Platform: ptrace I1129 01:42:38.432454 59447 x:0] FileAccess: shared, overlay: false I1129 01:42:38.432517 59447 x:0] Network: sandbox, logging: false I1129 01:42:38.432565 59447 x:0] Strace: false, max size: 1024, syscalls: [] I1129 01:42:38.432615 59447 x:0] *************************** W1129 01:42:38.432654 59447 x:0] Block the TERM signal. This is only safe in tests! D1129 01:42:38.432788 59447 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:38.439756 59447 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-test-1": signal 0 D1129 01:42:38.439895 59447 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:38.439978 59447 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:38.462662 59447 x:0] urpc: successfully marshalled 122 bytes. D1129 01:42:38.462930 59447 x:0] urpc: unmarshal success. I1129 01:42:38.463136 59447 x:0] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1", PID: 56590 I1129 01:42:38.463212 59447 x:0] Retrieving sandbox stacks D1129 01:42:38.463238 59447 x:0] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:38.463286 59447 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1" D1129 01:42:38.463441 59447 x:0] urpc: successfully marshalled 36 bytes. D1129 01:42:38.479327 59447 x:0] urpc: unmarshal success. I1129 01:42:38.479458 59447 x:0] *** Stack dump *** goroutine 575 [running]: gvisor.dev/gvisor/pkg/log.Stacks(0x460d01, 0xc000345440, 0xc000330f30, 0xc000574700) pkg/log/log.go:272 +0xb6 gvisor.dev/gvisor/runsc/boot.(*debug).Stacks(0x244d5e8, 0x244d5e8, 0xc0003a40b0, 0x0, 0x0) runsc/boot/debug.go:26 +0x38 reflect.Value.call(0xc000345440, 0xc000348200, 0x13, 0x1255f53, 0x4, 0xc000669e78, 0x3, 0x3, 0x10bd701, 0xc0003a40b0, ...) GOROOT/src/reflect/value.go:460 +0x62b reflect.Value.Call(0xc000345440, 0xc000348200, 0x13, 0xc000669e78, 0x3, 0x3, 0x0, 0x244d5e8, 0x16) GOROOT/src/reflect/value.go:321 +0xd4 gvisor.dev/gvisor/pkg/urpc.(*Server).handleOne(0xc000330c30, 0xc0002e80f0, 0x0, 0x0) pkg/urpc/urpc.go:325 +0x688 gvisor.dev/gvisor/pkg/urpc.(*Server).handleRegistered(0xc000330c30, 0xc0002e80f0, 0xc00041fa00, 0x0) pkg/urpc/urpc.go:420 +0x43 gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling.func1(0xc000330c30, 0xc0002e80f0) 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(0xc000346174) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc000346174) 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(0xc0003aa000, 0x0, 0x0) runsc/boot/loader.go:902 +0x73 gvisor.dev/gvisor/runsc/cmd.(*Boot).Execute(0xc0001b81b0, 0x13cb940, 0xc000034018, 0xc00028a600, 0xc000230c40, 0x2, 0x2, 0x0) runsc/cmd/boot.go:254 +0x149e github.com/google/subcommands.(*Commander).Execute(0xc000146000, 0x13cb940, 0xc000034018, 0xc000230c40, 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 6 [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 7 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0x1289450, 0xc00012e960, 0xc00012e9c0) 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 8 [sync.Cond.Wait]: runtime.goparkunlock(...) GOROOT/src/runtime/proc.go:310 sync.runtime_notifyListWait(0xc00028e948, 0x9) GOROOT/src/runtime/sema.go:510 +0xf8 sync.(*Cond).Wait(0xc00028e938) GOROOT/src/sync/cond.go:56 +0x8e gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).findReclaimable(0xc00028e500, 0x0, 0x0, 0x0) pkg/sentry/pgalloc/pgalloc.go:1090 +0xbb gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).runReclaim(0xc00028e500) pkg/sentry/pgalloc/pgalloc.go:1019 +0x7b created by gvisor.dev/gvisor/pkg/sentry/pgalloc.NewMemoryFile pkg/sentry/pgalloc/pgalloc.go:330 +0x2f4 goroutine 19 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Timekeeper).startUpdater.func1(0xc000344000, 0xc0003460a0) 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 20 [chan receive]: gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).waitForStart(0xc00032e200) pkg/sentry/watchdog/watchdog.go:216 +0x9b created by gvisor.dev/gvisor/pkg/sentry/watchdog.New pkg/sentry/watchdog/watchdog.go:168 +0x334 goroutine 22 [select]: reflect.rselect(0xc000150000, 0x22, 0x22, 0xc000150000, 0x1aaea00) GOROOT/src/runtime/select.go:542 +0x3bd reflect.Select(0xc0003ce000, 0x22, 0x49, 0x0, 0x1118e40, 0x244d5e8, 0x99, 0x0) GOROOT/src/reflect/value.go:2229 +0x1f0 gvisor.dev/gvisor/pkg/sentry/sighandling.handleSignals(0xc0003b8000, 0x21, 0x40, 0xc000370240, 0xc00033a300, 0xc00033a360, 0xc00033a3c0) pkg/sentry/sighandling/sighandling.go:46 +0x46c created by gvisor.dev/gvisor/pkg/sentry/sighandling.PrepareHandler pkg/sentry/sighandling/sighandling.go:131 +0x29d goroutine 23 [syscall]: syscall.Syscall6(0x10f, 0xc000191e68, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:44 +0x5 gvisor.dev/gvisor/pkg/unet.(*Socket).wait(0xc000330ba0, 0xffffffffffffff00, 0x0, 0x0) pkg/unet/unet_unsafe.go:55 +0xda gvisor.dev/gvisor/pkg/unet.(*ServerSocket).Accept(0xc000348040, 0x0, 0x0, 0x0) pkg/unet/unet.go:539 +0x189 gvisor.dev/gvisor/pkg/control/server.(*Server).serve(0xc000338340) pkg/control/server/server.go:99 +0x50 gvisor.dev/gvisor/pkg/control/server.(*Server).StartServing.func1(0xc000338340) 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 9 [syscall]: syscall.Syscall6(0x119, 0x10, 0xc0003cbae0, 0x64, 0xffffffffffffffff, 0x0, 0x0, 0xc000411ec8, 0x4645ec, 0x474baf) src/syscall/asm_linux_amd64.s:44 +0x5 gvisor.dev/gvisor/pkg/fdnotifier.epollWait(0x10, 0xc0003cbae0, 0x64, 0x64, 0xffffffffffffffff, 0x1, 0x0, 0x0) pkg/fdnotifier/poll_unsafe.go:77 +0x83 gvisor.dev/gvisor/pkg/fdnotifier.(*notifier).waitAndNotify(0xc000411ec0, 0x0, 0xf7eda0) pkg/fdnotifier/fdnotifier.go:149 +0x9e created by gvisor.dev/gvisor/pkg/fdnotifier.newNotifier pkg/fdnotifier/fdnotifier.go:64 +0x114 goroutine 10 [syscall]: syscall.Syscall6(0x10f, 0xc00044a790, 0x1, 0x0, 0x0, 0x0, 0x0, 0x12747d2, 0x28, 0xc0003fbe60) src/syscall/asm_linux_amd64.s:44 +0x5 golang.org/x/sys/unix.ppoll(0xc00044a790, 0x1, 0x0, 0x0, 0xc00044a790, 0x10, 0x100000000000028) external/org_golang_x_sys/unix/zsyscall_linux_amd64.go:79 +0xa2 golang.org/x/sys/unix.Ppoll(0xc00044a790, 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(0x7fff347d0f97, 0x2a, 0xc0002a0058, 0x1, 0x1, 0xc0003aa000) runsc/boot/loader.go:708 +0x25a created by gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor runsc/boot/loader.go:699 +0x7f goroutine 35 [syscall]: syscall.Syscall6(0x10f, 0xc000480720, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:44 +0x5 golang.org/x/sys/unix.ppoll(0xc000480720, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0) external/org_golang_x_sys/unix/zsyscall_linux_amd64.go:79 +0xa2 golang.org/x/sys/unix.Ppoll(0xc000480720, 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(0xc00046e000, 0xc0002e8750) pkg/p9/client.go:251 +0xda created by gvisor.dev/gvisor/pkg/p9.NewClient pkg/p9/client.go:231 +0x75e goroutine 52 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0001b8000) 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 53 [select]: gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).loop(0xc00032e200) 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 54 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0001b8090) 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 55 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000224000, 0xc00012ec60, 0x0, 0x69470b, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000224000, 0xc00012ec60, 0x13ccd00, 0x0, 0x38bae08, 0x19d600, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000224000, 0x0, 0xc420009c01, 0x38bae08, 0x0, 0xffffffff, 0xf7ed00, 0x7f919c040da8, 0xc0003374b0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000224000, 0x38bae08, 0x0, 0x0, 0x0, 0x0, 0x0, 0xe717, 0xc00019d948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000224000, 0xca, 0x38bae08, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000224000, 0xca, 0x38bae08, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc000642870) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000224000, 0xca, 0x38bae08, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00019dce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000224000, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000224000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000224000, 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 103 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0002d4000) 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 104 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc0000f2bc0, 0xc00033a180, 0xc00033a1e0) 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 125 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc00028c000, 0xc0001ec180, 0x0, 0x4645ec, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00028c000, 0xc0001ec180, 0x13ccd00, 0x0, 0x38d8ce0, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00028c000, 0x0, 0xc00028c001, 0x38d8ce0, 0x0, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00028c000, 0x38d8ce0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xdfd3, 0xc0001c9948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00028c000, 0xca, 0x38d8ce0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc00028c000, 0xca, 0x38d8ce0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0001840f0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00028c000, 0xca, 0x38d8ce0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0001c9ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00028c000, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00028c000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00028c000, 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 107 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0003a9500, 0xc00033a960, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0003a9500, 0xc00033a960, 0x13ccd00, 0x0, 0xc420276148, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0003a9500, 0x0, 0xc0003a9501, 0xc420276148, 0x489200, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0003a9500, 0xc420276148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xdfe1, 0xc00021d948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0003a9500, 0xca, 0xc420276148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0003a9500, 0xca, 0xc420276148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0003284b0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0003a9500, 0xca, 0xc420276148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00021dce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0003a9500, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0003a9500, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0003a9500, 0xa) 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 56 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0001b8240) 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 134 [semacquire]: sync.runtime_Semacquire(0xc00001c6b8) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc00001c6b0) 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(0xc0003aa000, 0xc00001c000, 0x2a) runsc/boot/loader.go:890 +0x43 gvisor.dev/gvisor/runsc/boot.(*Loader).waitContainer(0xc0003aa000, 0xc00037a090, 0x2a, 0xc0002a0898, 0x0, 0xc000454840) runsc/boot/loader.go:845 +0x1af gvisor.dev/gvisor/runsc/boot.(*containerManager).Wait(0xc000338360, 0xc0002a6100, 0xc0002a0898, 0x0, 0x0) runsc/boot/controller.go:424 +0xb9 reflect.Value.call(0xc000345320, 0xc000348198, 0x13, 0x1255f53, 0x4, 0xc00019fe78, 0x3, 0x3, 0x10ba701, 0xc0002a0898, ...) GOROOT/src/reflect/value.go:460 +0x62b reflect.Value.Call(0xc000345320, 0xc000348198, 0x13, 0xc00019fe78, 0x3, 0x3, 0x0, 0xc000338360, 0x16) GOROOT/src/reflect/value.go:321 +0xd4 gvisor.dev/gvisor/pkg/urpc.(*Server).handleOne(0xc000330c30, 0xc0003300f0, 0x0, 0x0) pkg/urpc/urpc.go:325 +0x688 gvisor.dev/gvisor/pkg/urpc.(*Server).handleRegistered(0xc000330c30, 0xc0003300f0, 0x6570647020786e20, 0x7374647220626731) pkg/urpc/urpc.go:420 +0x43 gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling.func1(0xc000330c30, 0xc0003300f0) pkg/urpc/urpc.go:440 +0x81 created by gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling pkg/urpc/urpc.go:438 +0x6f goroutine 135 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0001de000, 0xc0001d2240, 0xc00012ede0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithDeadline(0xc0001de000, 0xc0001d2240, 0xc0001de801, 0xe7f55ede3, 0x0, 0xc0002149a8) pkg/sentry/kernel/task_block.go:79 +0xda gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0001de000, 0xc0001d2240, 0x13ccd01, 0xdf8475800, 0x38ba050, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:41 +0x11d gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0001de000, 0xdf8475800, 0x0, 0x38ba050, 0x0, 0xffffffff, 0xae0803, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0001de000, 0x38ba050, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0xdfd3, 0xc000199948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0001de000, 0xca, 0x38ba050, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0001de000, 0xca, 0x38ba050, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x13babc0, 0xc0001840f0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0001de000, 0xca, 0x38ba050, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0xc000199ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0001de000, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0001de000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0001de000, 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 126 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc00028ca80, 0xc0001ec3c0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00028ca80, 0xc0001ec3c0, 0x13ccd00, 0x0, 0xc4200ca948, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00028ca80, 0x0, 0xc00028ca01, 0xc4200ca948, 0x489200, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00028ca80, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xe054, 0xc00040d948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00028ca80, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc00028ca80, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0003143c0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00028ca80, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00040dce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00028ca80, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00028ca80, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00028ca80, 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 127 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000370120) 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 128 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc00028d500, 0xc0001ec780, 0x0, 0x4645ec, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00028d500, 0xc0001ec780, 0x13ccd00, 0x0, 0xc4200fa148, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00028d500, 0x0, 0xc00028d501, 0xc4200fa148, 0x0, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00028d500, 0xc4200fa148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xdfd3, 0xc000409948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00028d500, 0xca, 0xc4200fa148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc00028d500, 0xca, 0xc4200fa148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0001840f0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00028d500, 0xca, 0xc4200fa148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000409ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00028d500, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00028d500, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00028d500, 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 129 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0003702d0) 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 136 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00046c000) 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(0xc0003a8000, 0xc0001ed1a0, 0x0, 0xc00001e7e0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0003a8000, 0xc0001ed1a0, 0x13ccd00, 0x0, 0xc4200fa548, 0x344100, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0003a8000, 0x0, 0x1, 0xc4200fa548, 0x100, 0xffffffff, 0x0, 0x0, 0x8) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0003a8000, 0xc4200fa548, 0x0, 0x0, 0x0, 0x0, 0x0, 0xdfe8, 0xc0002b7948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0003a8000, 0xca, 0xc4200fa548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0003a8000, 0xca, 0xc4200fa548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0001841e0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0003a8000, 0xca, 0xc4200fa548, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0002b7ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0003a8000, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0003a8000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0003a8000, 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 147 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000356090) 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 108 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000370000) 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 195 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000356120) 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 38 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00046c1b0) 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 37 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc00054a000, 0xc0002e2660, 0x0, 0xc00001e7e0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00054a000, 0xc0002e2660, 0x13ccd00, 0x0, 0xc420276548, 0x2e0800, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00054a000, 0x0, 0x1, 0xc420276548, 0x204c0100, 0xffffffff, 0x0, 0x0, 0x8) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00054a000, 0xc420276548, 0x0, 0x0, 0x0, 0x0, 0x0, 0xdfe8, 0xc000421948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00054a000, 0xca, 0xc420276548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc00054a000, 0xca, 0xc420276548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0001841e0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00054a000, 0xca, 0xc420276548, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000421ce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00054a000, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00054a000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00054a000, 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 196 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000536000, 0xc0001634a0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000536000, 0xc0001634a0, 0x13ccd00, 0x0, 0xc4200cbd48, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000536000, 0x0, 0xc000536001, 0xc4200cbd48, 0x204c0100, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000536000, 0xc4200cbd48, 0x0, 0x0, 0x0, 0x0, 0x0, 0xdfd3, 0xc00041b948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000536000, 0xca, 0xc4200cbd48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x12548e0, 0x0, ...) pkg/sentry/kernel/task_syscall.go:165 +0x403 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000536000, 0xca, 0xc4200cbd48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0001840f0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000536000, 0xca, 0xc4200cbd48, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00041bce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000536000, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000536000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000536000, 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 143 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0002d4a20) 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 179 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc00051e000, 0xc000402ba0, 0x0, 0x4645ec, 0x0) pkg/sentry/kernel/task_block.go:136 +0x178 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc00051e000, 0xc000402ba0, 0x13ccd00, 0x0, 0xc4200cb948, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:36 +0x2f4 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc00051e000, 0x0, 0xc00051e001, 0xc4200cb948, 0x0, 0xffffffff, 0x0, 0x13bc9a0, 0x1a82c40) pkg/sentry/syscalls/linux/sys_futex.go:97 +0x13a gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc00051e000, 0xc4200cb948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xe054, 0xc00041f948, 0x40000002, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x125 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc00051e000, 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(0xc00051e000, 0xca, 0xc4200cb948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x13babc0, 0xc0003143c0) pkg/sentry/kernel/task_syscall.go:283 +0xb5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc00051e000, 0xca, 0xc4200cb948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00041fce8, 0xe1dcf5) pkg/sentry/kernel/task_syscall.go:244 +0x10a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc00051e000, 0x2, 0xc000106d40) pkg/sentry/kernel/task_syscall.go:219 +0x1b4 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc00051e000, 0x13babc0, 0x0) pkg/sentry/kernel/task_run.go:219 +0x153a gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc00051e000, 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 166 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc0002ec050, 0xc0001d29c0, 0xc0001d2a20) 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 169 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc0003a4390, 0xc0001ec900, 0xc0001ec9c0) 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 248 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc0002ec0a0, 0xc000403c20, 0xc000403c80) 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 171 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1(0xc0003a43b0, 0xc0001ecfc0, 0xc0001ed020) 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 I1129 01:42:38.479727 59447 x:0] Exiting with status: 0 D1129 01:42:38.462121 56590 x:0] urpc: unmarshal success. D1129 01:42:38.462359 56590 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-test-1 Signo:0 PID:0 Mode:Process} D1129 01:42:38.462565 56590 x:0] urpc: successfully marshalled 37 bytes. D1129 01:42:38.463828 56590 x:0] urpc: unmarshal success. D1129 01:42:38.468545 56590 x:0] urpc: successfully marshalled 32016 bytes. D1129 01:42:39.327238 56590 x:0] Clock(Monotonic): error: -136 ns, adjusted frequency from 2299806309 Hz to 2299805766 Hz D1129 01:42:39.327398 56590 x:0] Clock(Realtime): error: -39 ns, adjusted frequency from 2299806210 Hz to 2299806027 Hz D1129 01:42:39.327493 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701059952366644 monotonicBaseRef:7000289402 monotonicFrequency:2299805766 realtimeReady:1 realtimeBaseCycles:38701059952757354 realtimeBaseRef:1574991759327394345 realtimeFrequency:2299806027} D1129 01:42:40.329338 56590 x:0] Clock(Monotonic): error: 200 ns, adjusted frequency from 2299805766 Hz to 2299806556 Hz D1129 01:42:40.329484 56590 x:0] Clock(Realtime): error: 139 ns, adjusted frequency from 2299806027 Hz to 2299806443 Hz D1129 01:42:40.329593 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701062256993588 monotonicBaseRef:8002385743 monotonicFrequency:2299806556 realtimeReady:1 realtimeBaseCycles:38701062257337699 realtimeBaseRef:1574991760329470310 realtimeFrequency:2299806443} D1129 01:42:41.330516 56590 x:0] Clock(Monotonic): error: -41 ns, adjusted frequency from 2299806556 Hz to 2299806001 Hz D1129 01:42:41.330693 56590 x:0] Clock(Realtime): error: 3 ns, adjusted frequency from 2299806443 Hz to 2299806138 Hz D1129 01:42:41.330766 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701064559504198 monotonicBaseRef:9003561517 monotonicFrequency:2299806001 realtimeReady:1 realtimeBaseCycles:38701064559941327 realtimeBaseRef:1574991761330686579 realtimeFrequency:2299806138} D1129 01:42:42.327344 56590 x:0] Clock(Monotonic): error: -8 ns, adjusted frequency from 2299806001 Hz to 2299806134 Hz D1129 01:42:42.327487 56590 x:0] Clock(Realtime): error: 23 ns, adjusted frequency from 2299806138 Hz to 2299806217 Hz D1129 01:42:42.327620 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701066852022162 monotonicBaseRef:10000392537 monotonicFrequency:2299806134 realtimeReady:1 realtimeBaseCycles:38701066852377743 realtimeBaseRef:1574991762327482081 realtimeFrequency:2299806217} D1129 01:42:43.327643 56590 x:0] Clock(Monotonic): error: -34 ns, adjusted frequency from 2299806134 Hz to 2299806088 Hz D1129 01:42:43.327792 56590 x:0] Clock(Realtime): error: -136 ns, adjusted frequency from 2299806217 Hz to 2299805854 Hz D1129 01:42:43.327861 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701069152519882 monotonicBaseRef:11000693251 monotonicFrequency:2299806088 realtimeReady:1 realtimeBaseCycles:38701069152885418 realtimeBaseRef:1574991763327787088 realtimeFrequency:2299805854} D1129 01:42:44.327245 56590 x:0] Clock(Monotonic): error: 26 ns, adjusted frequency from 2299806088 Hz to 2299806228 Hz D1129 01:42:44.327394 56590 x:0] Clock(Realtime): error: 154 ns, adjusted frequency from 2299805854 Hz to 2299806524 Hz D1129 01:42:44.327509 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701071451413219 monotonicBaseRef:12000296369 monotonicFrequency:2299806228 realtimeReady:1 realtimeBaseCycles:38701071451775149 realtimeBaseRef:1574991764327388740 realtimeFrequency:2299806524} D1129 01:42:45.327317 56590 x:0] Clock(Monotonic): error: 71 ns, adjusted frequency from 2299806228 Hz to 2299806337 Hz D1129 01:42:45.327631 56590 x:0] Clock(Realtime): error: -69 ns, adjusted frequency from 2299806524 Hz to 2299806006 Hz D1129 01:42:45.327730 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701073751373331 monotonicBaseRef:13000363280 monotonicFrequency:2299806337 realtimeReady:1 realtimeBaseCycles:38701073752121456 realtimeBaseRef:1574991765327623448 realtimeFrequency:2299806006} D1129 01:42:46.329308 56590 x:0] Clock(Monotonic): error: -116 ns, adjusted frequency from 2299806337 Hz to 2299805903 Hz D1129 01:42:46.329473 56590 x:0] Clock(Realtime): error: -61 ns, adjusted frequency from 2299806006 Hz to 2299806032 Hz D1129 01:42:46.329530 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701076055748715 monotonicBaseRef:14002349989 monotonicFrequency:2299805903 realtimeReady:1 realtimeBaseCycles:38701076056169010 realtimeBaseRef:1574991766329467754 realtimeFrequency:2299806032} D1129 01:42:47.327475 56590 x:0] Clock(Monotonic): error: 109 ns, adjusted frequency from 2299805903 Hz to 2299806406 Hz D1129 01:42:47.327779 56590 x:0] Clock(Realtime): error: 97 ns, adjusted frequency from 2299806032 Hz to 2299806388 Hz D1129 01:42:47.327876 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701078351310265 monotonicBaseRef:15000504462 monotonicFrequency:2299806406 realtimeReady:1 realtimeBaseCycles:38701078352070955 realtimeBaseRef:1574991767327770181 realtimeFrequency:2299806388} D1129 01:42:48.329843 56590 x:0] Clock(Monotonic): error: -83 ns, adjusted frequency from 2299806406 Hz to 2299805976 Hz D1129 01:42:48.329959 56590 x:0] Clock(Realtime): error: -129 ns, adjusted frequency from 2299806388 Hz to 2299805866 Hz D1129 01:42:48.330029 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701080656577957 monotonicBaseRef:16002879134 monotonicFrequency:2299805976 realtimeReady:1 realtimeBaseCycles:38701080656898988 realtimeBaseRef:1574991768329953688 realtimeFrequency:2299805866} D1129 01:42:49.327203 56590 x:0] Clock(Monotonic): error: 98 ns, adjusted frequency from 2299805976 Hz to 2299806391 Hz D1129 01:42:49.327348 56590 x:0] Clock(Realtime): error: 1 ns, adjusted frequency from 2299805866 Hz to 2299806161 Hz D1129 01:42:49.327433 56590 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:38701082950344385 monotonicBaseRef:17000253022 monotonicFrequency:2299806391 realtimeReady:1 realtimeBaseCycles:38701082950702495 realtimeBaseRef:1574991769327343746 realtimeFrequency:2299806161}