I1213 22:56:10.724690 17319 x:0] *************************** I1213 22:56:10.724732 17319 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-0 /syz-fuzzer -executor=/syz-executor -name=vm-0 -arch=amd64 -manager=stdin -sandbox=none -procs=4 -v=0 -cover=false -debug=false -test=false] I1213 22:56:10.724827 17319 x:0] Git Revision: 6253d32cc932e76608be5c57a4870b3d61464487 I1213 22:56:10.724833 17319 x:0] PID: 17319 I1213 22:56:10.724843 17319 x:0] UID: 0, GID: 0 I1213 22:56:10.724848 17319 x:0] Configuration: I1213 22:56:10.724853 17319 x:0] RootDir: /syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root I1213 22:56:10.724857 17319 x:0] Platform: ptrace I1213 22:56:10.724864 17319 x:0] FileAccess: shared, overlay: false I1213 22:56:10.724871 17319 x:0] Network: host, logging: false I1213 22:56:10.724878 17319 x:0] Strace: false, max size: 1024, syscalls: [] I1213 22:56:10.724884 17319 x:0] *************************** D1213 22:56:10.724993 17319 x:0] Load container "/syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:10.725382 17319 x:0] Signal container "ci-gvisor-ptrace-direct-overlay-host-0": signal 0 D1213 22:56:10.725392 17319 x:0] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:10.725395 17319 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:10.725515 17319 x:0] urpc: successfully marshalled 118 bytes. D1213 22:56:10.725692 17042 x:0] urpc: unmarshal success. D1213 22:56:10.725783 17042 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-0 Signo:0 PID:0 Mode:Process} D1213 22:56:10.725832 17042 x:0] urpc: successfully marshalled 37 bytes. D1213 22:56:10.725900 17319 x:0] urpc: unmarshal success. D1213 22:56:10.725962 17319 x:0] Execute in container "ci-gvisor-ptrace-direct-overlay-host-0", args: /syz-fuzzer -executor=/syz-executor -name=vm-0 -arch=amd64 -manager=stdin -sandbox=none -procs=4 -v=0 -cover=false -debug=false -test=false D1213 22:56:10.725979 17319 x:0] Executing new process in container "ci-gvisor-ptrace-direct-overlay-host-0" in sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:10.725985 17319 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:10.726302 17319 x:0] urpc: successfully marshalled 547 bytes. D1213 22:56:10.726350 17042 x:0] urpc: unmarshal success. D1213 22:56:10.726428 17042 x:0] containerManager.ExecuteAsync: /syz-fuzzer -executor=/syz-executor -name=vm-0 -arch=amd64 -manager=stdin -sandbox=none -procs=4 -v=0 -cover=false -debug=false -test=false I1213 22:56:10.726505 17042 x:0] EXEC: [/syz-fuzzer -executor=/syz-executor -name=vm-0 -arch=amd64 -manager=stdin -sandbox=none -procs=4 -v=0 -cover=false -debug=false -test=false] D1213 22:56:10.726651 17042 x:0] send [FD 6] [Tag 000001] Twalkgetattr{FID: 1, NewFID: 4, Names: [syz-fuzzer]} D1213 22:56:10.727021 17042 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: 711487083}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 9569147}]} D1213 22:56:10.727073 17042 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.727288 17042 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: 9569147}, 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: 711487083}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1213 22:56:10.727299 17042 x:0] send [FD 6] [Tag 000001] Twalk{FID: 4, NewFID: 5, Names: []} D1213 22:56:10.727494 17042 x:0] recv [FD 6] [Tag 000001] Rwalk{QIDs: [QID{Type: 0, Version: 0, Path: 9569147}]} D1213 22:56:10.727511 17042 x:0] send [FD 6] [Tag 000001] Tlopen{FID: 5, Flags: ReadOnly} D1213 22:56:10.727671 17042 x:0] recv [FD 6] [Tag 000001] Rlopen{QID: QID{Type: 0, Version: 0, Path: 9569147}, IoUnit: 0, File: &{{29}}} D1213 22:56:10.733135 17042 x:0] Allocating stack with size of 8388608 bytes D1213 22:56:10.733265 17042 x:0] updated processes: map[{ci-gvisor-ptrace-direct-overlay-host-0 0}:0xc0002a23d0 {ci-gvisor-ptrace-direct-overlay-host-0 14}:0xc0003da880] D1213 22:56:10.733306 17042 x:0] urpc: successfully marshalled 37 bytes. D1213 22:56:10.733370 17319 x:0] urpc: unmarshal success. D1213 22:56:10.733428 17319 x:0] Wait on PID 14 in container "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:10.733443 17319 x:0] Waiting for PID 14 in sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:10.733449 17319 x:0] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:10.733520 17319 x:0] urpc: successfully marshalled 120 bytes. D1213 22:56:10.733680 17042 x:0] urpc: unmarshal success. D1213 22:56:10.733728 17042 x:0] containerManager.Wait D1213 22:56:10.734760 17042 x:0] [ 14] Interrupt queued D1213 22:56:10.859340 17042 x:0] [ 15] Interrupt queued D1213 22:56:10.859495 17042 x:0] [ 16] Interrupt queued D1213 22:56:10.861043 17042 x:0] [ 17] Interrupt queued D1213 22:56:10.862998 17042 x:0] [ 18] Interrupt queued D1213 22:56:10.874628 17042 x:0] [ 19] Interrupt queued D1213 22:56:10.880683 17042 x:0] [ 20] Interrupt queued D1213 22:56:10.883063 17042 x:0] [ 21] Interrupt queued D1213 22:56:10.883958 17042 x:0] [ 22] Interrupt queued D1213 22:56:10.886887 17042 x:0] [ 23] Interrupt queued D1213 22:56:10.894917 17042 x:0] send [FD 6] [Tag 000001] Twalkgetattr{FID: 1, NewFID: 6, Names: [etc]} D1213 22:56:10.895141 17042 x:0] recv [FD 6] [Tag 000001] Rlerror{Error: 2} 2018/12/13 22:56:10 fuzzer started D1213 22:56:10.913415 17042 x:0] [ 24] Interrupt queued D1213 22:56:10.914993 17042 x:0] [ 25] Interrupt queued D1213 22:56:10.922731 17042 x:0] [ 26] Interrupt queued D1213 22:56:10.931599 17042 x:0] [ 27] Interrupt queued D1213 22:56:10.939889 17042 x:0] [ 28] Interrupt queued D1213 22:56:10.949118 17042 x:0] [ 29] Interrupt queued D1213 22:56:10.965170 17042 x:0] [ 30] Interrupt queued D1213 22:56:10.972731 17042 x:0] [ 31] Interrupt queued D1213 22:56:10.987848 17042 x:0] [ 32] Interrupt queued D1213 22:56:10.995686 17042 x:0] [ 33] Interrupt queued D1213 22:56:11.010886 17042 x:0] [ 34] Interrupt queued D1213 22:56:11.017862 17042 x:0] [ 35] Interrupt queued D1213 22:56:11.035241 17042 x:0] [ 36] Interrupt queued D1213 22:56:11.041227 17042 x:0] [ 37] Interrupt queued D1213 22:56:11.055629 17042 x:0] [ 38] Interrupt queued D1213 22:56:11.063009 17042 x:0] [ 39] Interrupt queued D1213 22:56:11.073626 17042 x:0] [ 40] Interrupt queued D1213 22:56:11.079700 17042 x:0] [ 41] Interrupt queued D1213 22:56:11.096079 17042 x:0] [ 42] Interrupt queued D1213 22:56:11.103529 17042 x:0] [ 43] Interrupt queued D1213 22:56:11.116707 17042 x:0] [ 44] Interrupt queued D1213 22:56:11.124798 17042 x:0] [ 45] Interrupt queued D1213 22:56:11.140885 17042 x:0] [ 46] Interrupt queued D1213 22:56:11.150091 17042 x:0] [ 47] Interrupt queued D1213 22:56:11.162822 17042 x:0] [ 48] Interrupt queued D1213 22:56:11.186717 17042 x:0] [ 49] Interrupt queued D1213 22:56:11.206800 17042 x:0] [ 50] Interrupt queued D1213 22:56:11.229059 17042 x:0] [ 51] Interrupt queued D1213 22:56:11.252247 17042 x:0] [ 52] Interrupt queued D1213 22:56:11.275141 17042 x:0] [ 53] Interrupt queued D1213 22:56:11.288550 17042 x:0] Time: Adjusting syscall overhead up to 4000 I1213 22:56:11.288592 17042 x:0] CalibratedClock(Monotonic): ready D1213 22:56:11.288691 17042 x:0] Time: Adjusting syscall overhead up to 4000 I1213 22:56:11.288709 17042 x:0] CalibratedClock(Realtime): ready D1213 22:56:11.288714 17042 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:14632069939217123 monotonicBaseRef:1000171498 monotonicFrequency:2299798602 realtimeReady:1 realtimeBaseCycles:14632069939491862 realtimeBaseRef:1544741771288708323 realtimeFrequency:2299798649} D1213 22:56:11.289162 17042 x:0] [ 54] Interrupt queued D1213 22:56:11.314217 17042 x:0] [ 55] Interrupt queued D1213 22:56:11.327143 17042 x:0] [ 56] Interrupt queued D1213 22:56:11.337076 17042 x:0] [ 57] Interrupt queued D1213 22:56:11.346960 17042 x:0] [ 58] Interrupt queued D1213 22:56:11.359337 17042 x:0] [ 59] Interrupt queued D1213 22:56:11.370644 17042 x:0] [ 60] Interrupt queued D1213 22:56:11.382306 17042 x:0] [ 61] Interrupt queued D1213 22:56:11.395023 17042 x:0] [ 62] Interrupt queued D1213 22:56:11.405363 17042 x:0] [ 63] Interrupt queued D1213 22:56:11.417628 17042 x:0] [ 64] Interrupt queued D1213 22:56:11.430081 17042 x:0] [ 65] Interrupt queued D1213 22:56:11.442468 17042 x:0] [ 66] Interrupt queued D1213 22:56:11.454086 17042 x:0] [ 67] Interrupt queued D1213 22:56:11.467626 17042 x:0] [ 68] Interrupt queued D1213 22:56:11.480348 17042 x:0] [ 69] Interrupt queued D1213 22:56:11.493358 17042 x:0] [ 70] Interrupt queued D1213 22:56:11.505683 17042 x:0] [ 71] Interrupt queued D1213 22:56:11.518721 17042 x:0] [ 72] Interrupt queued D1213 22:56:11.531404 17042 x:0] [ 73] Interrupt queued D1213 22:56:11.571236 17042 x:0] [ 74] Interrupt queued D1213 22:56:11.683445 17042 x:0] [ 75] Interrupt queued D1213 22:56:11.766151 17042 x:0] [ 76] Interrupt queued D1213 22:56:11.800278 17042 x:0] [ 77] Interrupt queued 2018/12/13 22:56:11 dialing manager at stdin D1213 22:56:11.846556 17042 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.914779 17042 x:0] [ 79] Interrupt queued D1213 22:56:12.028884 17042 x:0] [ 80] Interrupt queued D1213 22:56:12.239435 17042 x:0] [ 81] Interrupt queued D1213 22:56:12.288596 17042 x:0] Clock(Monotonic): error: 249 ns, adjusted frequency from 2299798602 Hz to 2299799533 Hz D1213 22:56:12.288651 17042 x:0] Clock(Realtime): error: 294 ns, adjusted frequency from 2299798649 Hz to 2299799559 Hz D1213 22:56:12.288657 17042 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:14632072239025278 monotonicBaseRef:2000175651 monotonicFrequency:2299799533 realtimeReady:1 realtimeBaseCycles:14632072239158418 realtimeBaseRef:1544741772288650886 realtimeFrequency:2299799559} D1213 22:56:12.350352 17042 x:0] [ 82] Interrupt queued D1213 22:56:12.405829 17042 x:0] [ 83] Interrupt queued D1213 22:56:12.634389 17042 x:0] [ 84] Interrupt queued D1213 22:56:12.815251 17042 x:0] [ 85] Interrupt queued D1213 22:56:12.924109 17042 x:0] [ 86] Interrupt queued D1213 22:56:13.200554 17042 x:0] [ 87] Interrupt queued D1213 22:56:13.288541 17042 x:0] Clock(Monotonic): error: 198 ns, adjusted frequency from 2299799533 Hz to 2299799413 Hz D1213 22:56:13.288585 17042 x:0] Clock(Realtime): error: 72 ns, adjusted frequency from 2299799559 Hz to 2299799149 Hz D1213 22:56:13.288592 17042 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:14632074538700351 monotonicBaseRef:3000121533 monotonicFrequency:2299799413 realtimeReady:1 realtimeBaseCycles:14632074538805872 realtimeBaseRef:1544741773288584747 realtimeFrequency:2299799149} W1213 22:56:13.445173 17319 x:0] FATAL ERROR: error waiting on pid 14: error waiting on PID 14 in sandbox "ci-gvisor-ptrace-direct-overlay-host-0": urpc method "containerManager.WaitPID" failed: EOF error waiting on pid 14: error waiting on PID 14 in sandbox "ci-gvisor-ptrace-direct-overlay-host-0": urpc method "containerManager.WaitPID" failed: EOF W1213 22:56:13.453148 16982 x:0] Wait RPC to container "ci-gvisor-ptrace-direct-overlay-host-0" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D1213 22:56:13.453213 16982 x:0] Destroy container "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:13.453221 16982 x:0] Destroying container "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:13.453225 16982 x:0] Destroying root container "ci-gvisor-ptrace-direct-overlay-host-0" by destroying sandbox D1213 22:56:13.453230 16982 x:0] Destroy sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:13.453233 16982 x:0] Killing sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D1213 22:56:13.453288 16982 x:0] Killing gofer for container "ci-gvisor-ptrace-direct-overlay-host-0", PID: 17008 I1213 22:56:18.453490 16982 x:0] Exiting with status: 9