I1213 22:56:10.723853 17318 x:0] *************************** I1213 22:56:10.723882 17318 x:0] Args: [/syzkaller/managers/ptrace-direct-overlay-host/current/image -root /syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root -watchdog-action=panic -trace-signal=12 -network=none -debug -platform=ptrace -file-access=shared -network=host 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-2 /syz-fuzzer -executor=/syz-executor -name=vm-2 -arch=amd64 -manager=stdin -sandbox=none -procs=4 -v=0 -cover=false -debug=false -test=false] I1213 22:56:10.723930 17318 x:0] Git Revision: 6253d32cc932e76608be5c57a4870b3d61464487 I1213 22:56:10.723933 17318 x:0] PID: 17318 I1213 22:56:10.723938 17318 x:0] UID: 0, GID: 0 I1213 22:56:10.723942 17318 x:0] Configuration: I1213 22:56:10.723944 17318 x:0] RootDir: /syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root I1213 22:56:10.723947 17318 x:0] Platform: ptrace I1213 22:56:10.723950 17318 x:0] FileAccess: shared, overlay: false I1213 22:56:10.723955 17318 x:0] Network: host, logging: false I1213 22:56:10.723994 17318 x:0] Strace: false, max size: 1024, syscalls: [] I1213 22:56:10.723997 17318 x:0] *************************** D1213 22:56:10.724062 17318 x:0] Load container "/syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:10.724426 17318 x:0] Signal container "ci-gvisor-ptrace-direct-overlay-host-2": signal 0 D1213 22:56:10.724452 17318 x:0] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:10.724457 17318 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:10.724619 17318 x:0] urpc: successfully marshalled 118 bytes. D1213 22:56:10.724727 17059 x:0] urpc: unmarshal success. D1213 22:56:10.724788 17059 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-2 Signo:0 PID:0 Mode:Process} D1213 22:56:10.724859 17059 x:0] urpc: successfully marshalled 37 bytes. D1213 22:56:10.724908 17318 x:0] urpc: unmarshal success. D1213 22:56:10.724984 17318 x:0] Execute in container "ci-gvisor-ptrace-direct-overlay-host-2", args: /syz-fuzzer -executor=/syz-executor -name=vm-2 -arch=amd64 -manager=stdin -sandbox=none -procs=4 -v=0 -cover=false -debug=false -test=false D1213 22:56:10.725000 17318 x:0] Executing new process in container "ci-gvisor-ptrace-direct-overlay-host-2" in sandbox "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:10.725005 17318 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:10.725302 17318 x:0] urpc: successfully marshalled 547 bytes. D1213 22:56:10.725366 17059 x:0] urpc: unmarshal success. D1213 22:56:10.725465 17059 x:0] containerManager.ExecuteAsync: /syz-fuzzer -executor=/syz-executor -name=vm-2 -arch=amd64 -manager=stdin -sandbox=none -procs=4 -v=0 -cover=false -debug=false -test=false I1213 22:56:10.725554 17059 x:0] EXEC: [/syz-fuzzer -executor=/syz-executor -name=vm-2 -arch=amd64 -manager=stdin -sandbox=none -procs=4 -v=0 -cover=false -debug=false -test=false] D1213 22:56:10.725688 17059 x:0] send [FD 6] [Tag 000001] Twalkgetattr{FID: 1, NewFID: 4, Names: [syz-fuzzer]} D1213 22:56:10.725956 17059 x:0] recv [FD 6] [Tag 000001] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 18290144, BlockSize: 4096, Blocks: 35728, ATime: {Sec: 1544716303, NanoSec: 827895343}, MTime: {Sec: 1544716303, NanoSec: 827895343}, CTime: {Sec: 1544741770, NanoSec: 707486735}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 9569146}]} D1213 22:56:10.726004 17059 x:0] send [FD 6] [Tag 000001] Tgetattr{FID: 4, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1213 22:56:10.726210 17059 x:0] recv [FD 6] [Tag 000001] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 9569146}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 18290144, BlockSize: 4096, Blocks: 35728, ATime: {Sec: 1544716303, NanoSec: 827895343}, MTime: {Sec: 1544716303, NanoSec: 827895343}, CTime: {Sec: 1544741770, NanoSec: 707486735}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1213 22:56:10.726220 17059 x:0] send [FD 6] [Tag 000001] Twalk{FID: 4, NewFID: 5, Names: []} D1213 22:56:10.726328 17059 x:0] recv [FD 6] [Tag 000001] Rwalk{QIDs: [QID{Type: 0, Version: 0, Path: 9569146}]} D1213 22:56:10.726340 17059 x:0] send [FD 6] [Tag 000001] Tlopen{FID: 5, Flags: ReadOnly} D1213 22:56:10.726529 17059 x:0] recv [FD 6] [Tag 000001] Rlopen{QID: QID{Type: 0, Version: 0, Path: 9569146}, IoUnit: 0, File: &{{29}}} D1213 22:56:10.732462 17059 x:0] Allocating stack with size of 8388608 bytes D1213 22:56:10.732611 17059 x:0] updated processes: map[{ci-gvisor-ptrace-direct-overlay-host-2 0}:0xc0000ef790 {ci-gvisor-ptrace-direct-overlay-host-2 14}:0xc0002be880] D1213 22:56:10.732677 17059 x:0] urpc: successfully marshalled 37 bytes. D1213 22:56:10.732726 17318 x:0] urpc: unmarshal success. D1213 22:56:10.732752 17318 x:0] Wait on PID 14 in container "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:10.732764 17318 x:0] Waiting for PID 14 in sandbox "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:10.732768 17318 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:10.732837 17318 x:0] urpc: successfully marshalled 120 bytes. D1213 22:56:10.732959 17059 x:0] urpc: unmarshal success. D1213 22:56:10.733017 17059 x:0] containerManager.Wait D1213 22:56:10.734047 17059 x:0] [ 14] Interrupt queued D1213 22:56:10.861442 17059 x:0] [ 15] Interrupt queued D1213 22:56:10.862360 17059 x:0] [ 16] Interrupt queued D1213 22:56:10.863565 17059 x:0] [ 17] Interrupt queued D1213 22:56:10.867000 17059 x:0] [ 18] Interrupt queued D1213 22:56:10.881807 17059 x:0] [ 19] Interrupt queued D1213 22:56:10.883016 17059 x:0] [ 20] Interrupt queued D1213 22:56:10.885940 17059 x:0] [ 21] Interrupt queued D1213 22:56:10.890516 17059 x:0] [ 22] Interrupt queued D1213 22:56:10.900086 17059 x:0] send [FD 6] [Tag 000001] Twalkgetattr{FID: 1, NewFID: 6, Names: [etc]} D1213 22:56:10.900316 17059 x:0] recv [FD 6] [Tag 000001] Rlerror{Error: 2} 2018/12/13 22:56:10 fuzzer started D1213 22:56:10.918581 17059 x:0] [ 23] Interrupt queued D1213 22:56:10.919842 17059 x:0] [ 24] Interrupt queued D1213 22:56:10.925489 17059 x:0] [ 25] Interrupt queued D1213 22:56:10.932188 17059 x:0] [ 26] Interrupt queued D1213 22:56:10.938782 17059 x:0] [ 27] Interrupt queued D1213 22:56:10.948840 17059 x:0] [ 28] Interrupt queued D1213 22:56:10.964623 17059 x:0] [ 29] Interrupt queued D1213 22:56:10.972064 17059 x:0] [ 30] Interrupt queued D1213 22:56:10.983812 17059 x:0] [ 31] Interrupt queued D1213 22:56:10.990629 17059 x:0] [ 32] Interrupt queued D1213 22:56:11.004371 17059 x:0] [ 33] Interrupt queued D1213 22:56:11.013442 17059 x:0] [ 34] Interrupt queued D1213 22:56:11.029039 17059 x:0] [ 35] Interrupt queued D1213 22:56:11.036506 17059 x:0] [ 36] Interrupt queued D1213 22:56:11.050181 17059 x:0] [ 37] Interrupt queued D1213 22:56:11.058802 17059 x:0] [ 38] Interrupt queued D1213 22:56:11.075402 17059 x:0] [ 39] Interrupt queued D1213 22:56:11.081777 17059 x:0] [ 40] Interrupt queued D1213 22:56:11.093684 17059 x:0] [ 41] Interrupt queued D1213 22:56:11.100946 17059 x:0] [ 42] Interrupt queued D1213 22:56:11.112760 17059 x:0] [ 43] Interrupt queued D1213 22:56:11.118866 17059 x:0] [ 44] Interrupt queued D1213 22:56:11.133231 17059 x:0] [ 45] Interrupt queued D1213 22:56:11.141409 17059 x:0] [ 46] Interrupt queued D1213 22:56:11.157112 17059 x:0] [ 47] Interrupt queued D1213 22:56:11.167070 17059 x:0] [ 48] Interrupt queued D1213 22:56:11.181335 17059 x:0] [ 49] Interrupt queued D1213 22:56:11.202251 17059 x:0] [ 50] Interrupt queued D1213 22:56:11.223169 17059 x:0] [ 51] Interrupt queued D1213 22:56:11.242890 17059 x:0] [ 52] Interrupt queued D1213 22:56:11.261969 17059 x:0] [ 53] Interrupt queued D1213 22:56:11.291769 17059 x:0] Time: Adjusting syscall overhead up to 4000 I1213 22:56:11.291807 17059 x:0] CalibratedClock(Monotonic): ready D1213 22:56:11.291839 17059 x:0] Time: Adjusting syscall overhead up to 4000 I1213 22:56:11.291845 17059 x:0] CalibratedClock(Realtime): ready D1213 22:56:11.291850 17059 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:14632069946609196 monotonicBaseRef:1000206064 monotonicFrequency:2299798610 realtimeReady:1 realtimeBaseCycles:14632069946703410 realtimeBaseRef:1544741771291844059 realtimeFrequency:2299798689} D1213 22:56:11.294818 17059 x:0] [ 54] Interrupt queued D1213 22:56:11.306201 17059 x:0] [ 55] Interrupt queued D1213 22:56:11.318302 17059 x:0] [ 56] Interrupt queued D1213 22:56:11.329249 17059 x:0] [ 57] Interrupt queued D1213 22:56:11.340593 17059 x:0] [ 58] Interrupt queued D1213 22:56:11.351484 17059 x:0] [ 59] Interrupt queued D1213 22:56:11.364172 17059 x:0] [ 60] Interrupt queued D1213 22:56:11.376416 17059 x:0] [ 61] Interrupt queued D1213 22:56:11.388415 17059 x:0] [ 62] Interrupt queued D1213 22:56:11.400039 17059 x:0] [ 63] Interrupt queued D1213 22:56:11.410516 17059 x:0] [ 64] Interrupt queued D1213 22:56:11.421604 17059 x:0] [ 65] Interrupt queued D1213 22:56:11.433289 17059 x:0] [ 66] Interrupt queued D1213 22:56:11.445362 17059 x:0] [ 67] Interrupt queued D1213 22:56:11.458584 17059 x:0] [ 68] Interrupt queued D1213 22:56:11.474177 17059 x:0] [ 69] Interrupt queued D1213 22:56:11.495912 17059 x:0] [ 70] Interrupt queued D1213 22:56:11.509484 17059 x:0] [ 71] Interrupt queued D1213 22:56:11.521032 17059 x:0] [ 72] Interrupt queued D1213 22:56:11.533983 17059 x:0] [ 73] Interrupt queued D1213 22:56:11.546610 17059 x:0] [ 74] Interrupt queued D1213 22:56:11.569325 17059 x:0] [ 75] Interrupt queued D1213 22:56:11.642310 17059 x:0] [ 76] Interrupt queued D1213 22:56:11.667605 17059 x:0] [ 77] Interrupt queued 2018/12/13 22:56:11 dialing manager at stdin D1213 22:56:11.834781 17059 x:0] [ 78] Interrupt queued 2018/12/13 22:56:11 syscalls: 1 2018/12/13 22:56:11 code coverage: debugfs is not enabled or not mounted 2018/12/13 22:56:11 comparison tracing: debugfs is not enabled or not mounted 2018/12/13 22:56:11 setuid sandbox: enabled 2018/12/13 22:56:11 namespace sandbox: enabled 2018/12/13 22:56:11 Android sandbox: /sys/fs/selinux/policy does not exist 2018/12/13 22:56:11 fault injection: CONFIG_FAULT_INJECTION is not enabled 2018/12/13 22:56:11 leak checking: debugfs is not enabled or not mounted 2018/12/13 22:56:11 net packet injection: /dev/net/tun does not exist 2018/12/13 22:56:11 net device setup: ip command is not found D1213 22:56:11.854395 17059 x:0] [ 79] Interrupt queued D1213 22:56:11.892479 17059 x:0] [ 80] Interrupt queued D1213 22:56:11.914428 17059 x:0] [ 81] Interrupt queued D1213 22:56:12.033602 17059 x:0] [ 82] Interrupt queued D1213 22:56:12.061696 17059 x:0] [ 83] Interrupt queued D1213 22:56:12.095428 17059 x:0] [ 84] Interrupt queued D1213 22:56:12.136763 17059 x:0] [ 85] Interrupt queued D1213 22:56:12.171109 17059 x:0] [ 86] Interrupt queued D1213 22:56:12.263509 17059 x:0] [ 87] Interrupt queued D1213 22:56:12.291772 17059 x:0] Clock(Monotonic): error: 401 ns, adjusted frequency from 2299798610 Hz to 2299799807 Hz D1213 22:56:12.291827 17059 x:0] Clock(Realtime): error: 270 ns, adjusted frequency from 2299798689 Hz to 2299799513 Hz D1213 22:56:12.291834 17059 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:14632072246328791 monotonicBaseRef:2000171706 monotonicFrequency:2299799807 realtimeReady:1 realtimeBaseCycles:14632072246460871 realtimeBaseRef:1544741772291826132 realtimeFrequency:2299799513} D1213 22:56:12.319168 17059 x:0] [ 88] Interrupt queued D1213 22:56:12.501077 17059 x:0] [ 89] Interrupt queued D1213 22:56:12.736448 17059 x:0] [ 90] Interrupt queued D1213 22:56:13.081265 17059 x:0] [ 91] Interrupt queued D1213 22:56:13.223659 17059 x:0] [ 92] Interrupt queued D1213 22:56:13.291761 17059 x:0] Clock(Monotonic): error: -41 ns, adjusted frequency from 2299799807 Hz to 2299798938 Hz D1213 22:56:13.291807 17059 x:0] Clock(Realtime): error: 115 ns, adjusted frequency from 2299799513 Hz to 2299799233 Hz D1213 22:56:13.291812 17059 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:14632074546102746 monotonicBaseRef:3000160465 monotonicFrequency:2299798938 realtimeReady:1 realtimeBaseCycles:14632074546213805 realtimeBaseRef:1544741773291805878 realtimeFrequency:2299799233} W1213 22:56:13.445172 17318 x:0] FATAL ERROR: error waiting on pid 14: error waiting on PID 14 in sandbox "ci-gvisor-ptrace-direct-overlay-host-2": urpc method "containerManager.WaitPID" failed: EOF error waiting on pid 14: error waiting on PID 14 in sandbox "ci-gvisor-ptrace-direct-overlay-host-2": urpc method "containerManager.WaitPID" failed: EOF W1213 22:56:13.452717 17000 x:0] Wait RPC to container "ci-gvisor-ptrace-direct-overlay-host-2" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D1213 22:56:13.452785 17000 x:0] Destroy container "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:13.452798 17000 x:0] Destroying container "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:13.452802 17000 x:0] Destroying root container "ci-gvisor-ptrace-direct-overlay-host-2" by destroying sandbox D1213 22:56:13.452808 17000 x:0] Destroy sandbox "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:13.452812 17000 x:0] Killing sandbox "ci-gvisor-ptrace-direct-overlay-host-2" D1213 22:56:13.452882 17000 x:0] Killing gofer for container "ci-gvisor-ptrace-direct-overlay-host-2", PID: 17036 I1213 22:56:18.453065 17000 x:0] Exiting with status: 9