I0109 00:09:38.407915 20660 x:0] *************************** I0109 00:09:38.408123 20660 x:0] Args: [/syzkaller/managers/kvm-direct-sandbox/latest.tmp/image -root /syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=kvm -file-access=exclusive -network=sandbox run -bundle /syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/instance-1/bundle ci-gvisor-kvm-direct-sandbox-test-1] I0109 00:09:38.408248 20660 x:0] Version release-20191213.0-78-gd530df2f95c3 I0109 00:09:38.408291 20660 x:0] PID: 20660 I0109 00:09:38.408357 20660 x:0] UID: 0, GID: 0 I0109 00:09:38.408400 20660 x:0] Configuration: I0109 00:09:38.408450 20660 x:0] RootDir: /syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root I0109 00:09:38.408489 20660 x:0] Platform: kvm I0109 00:09:38.408551 20660 x:0] FileAccess: exclusive, overlay: false I0109 00:09:38.408612 20660 x:0] Network: sandbox, logging: false I0109 00:09:38.408680 20660 x:0] Strace: false, max size: 1024, syscalls: [] I0109 00:09:38.408769 20660 x:0] *************************** D0109 00:09:38.410134 20660 x:0] Spec: &{Version: Process:0xc00029b380 Root:0xc00029ebe0 Hostname: Mounts:[] Hooks: Annotations:map[] Linux: Solaris: Windows:} D0109 00:09:38.410252 20660 x:0] Spec.Hooks: D0109 00:09:38.410297 20660 x:0] Spec.Linux: D0109 00:09:38.410348 20660 x:0] Spec.Process: &{Terminal:false ConsoleSize: User:{UID:0 GID:0 AdditionalGids:[] Username:} Args:[/init] Env:[SYZ_GVISOR_PROXY=1] Cwd:/tmp Capabilities:0xc000120300 Rlimits:[] NoNewPrivileges:false ApparmorProfile: OOMScoreAdj: SelinuxLabel:} D0109 00:09:38.410442 20660 x:0] Spec.Root: &{Path:/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/instance-1/image Readonly:true} D0109 00:09:38.410491 20660 x:0] Spec.Mounts: [] D0109 00:09:38.410548 20660 x:0] Run container "ci-gvisor-kvm-direct-sandbox-test-1" in root dir: /syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root D0109 00:09:38.410642 20660 x:0] Create container "ci-gvisor-kvm-direct-sandbox-test-1" in root dir: /syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root D0109 00:09:38.410863 20660 x:0] Creating new sandbox for container "ci-gvisor-kvm-direct-sandbox-test-1" D0109 00:09:38.411040 20660 x:0] Starting gofer: /proc/self/exe [--root=/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root --debug=true --log= --log-format=text --debug-log= --debug-log-format=text --file-access=exclusive --overlay=false --fsgofer-host-uds=false --network=sandbox --log-packets=false --platform=kvm --strace=false --strace-syscalls= --strace-log-size=1024 --watchdog-action=Panic --panic-signal=-1 --profile=false --net-raw=false --num-network-channels=1 --rootless=false --alsologtostderr=true --ref-leak-mode=disabled --gso=true --software-gso=true --overlayfs-stale-read=false gofer --bundle /syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/instance-1/bundle --spec-fd=3 --mounts-fd=4 --io-fds=5] I0109 00:09:38.839639 20660 x:0] Gofer started, PID: 24459 I0109 00:09:38.839784 20660 x:0] Creating sandbox process with addr: runsc-sandbox.ci-gvisor-kvm-direct-sandbox-test-1 I0109 00:09:38.839857 20660 x:0] Sandbox will be started in new mount, IPC and UTS namespaces I0109 00:09:38.839874 20660 x:0] Sandbox will be started in a new PID namespace I0109 00:09:38.839881 20660 x:0] Sandbox will be started in new network namespace I0109 00:09:38.840003 20660 x:0] Sandbox will be started in new user namespace D0109 00:09:38.840021 20660 x:0] Donating FD 3: "control_server_socket" D0109 00:09:38.840039 20660 x:0] Donating FD 4: "|0" D0109 00:09:38.840044 20660 x:0] Donating FD 5: "/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/instance-1/bundle/config.json" D0109 00:09:38.840053 20660 x:0] Donating FD 6: "|1" D0109 00:09:38.840059 20660 x:0] Donating FD 7: "sandbox IO FD" D0109 00:09:38.840064 20660 x:0] Donating FD 8: "/dev/kvm" D0109 00:09:38.840068 20660 x:0] Donating FD 9: "/dev/stdin" D0109 00:09:38.840073 20660 x:0] Donating FD 10: "/dev/stdout" D0109 00:09:38.840084 20660 x:0] Donating FD 11: "/dev/stderr" D0109 00:09:38.840089 20660 x:0] Starting sandbox: /proc/self/exe [runsc-sandbox --root=/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root --debug=true --log= --log-format=text --debug-log= --debug-log-format=text --file-access=exclusive --overlay=false --fsgofer-host-uds=false --network=sandbox --log-packets=false --platform=kvm --strace=false --strace-syscalls= --strace-log-size=1024 --watchdog-action=Panic --panic-signal=-1 --profile=false --net-raw=false --num-network-channels=1 --rootless=false --alsologtostderr=true --ref-leak-mode=disabled --gso=true --software-gso=true --overlayfs-stale-read=false --panic-signal=15 boot --bundle=/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/instance-1/bundle --controller-fd=3 --mounts-fd=4 --spec-fd=5 --start-sync-fd=6 --io-fds=7 --device-fd=8 --stdio-fds=9 --stdio-fds=10 --stdio-fds=11 --pidns=true --setup-root ci-gvisor-kvm-direct-sandbox-test-1] D0109 00:09:38.840131 20660 x:0] SysProcAttr: &{Chroot: Credential:0xc0002a0a50 Ptrace:false Setsid:true Setpgid:false Setctty:false Noctty:false Ctty:0 Foreground:false Pgid:0 Pdeathsig:killed Cloneflags:0 Unshareflags:0 UidMappings:[{ContainerID:0 HostID:65533 Size:1} {ContainerID:65534 HostID:65534 Size:1}] GidMappings:[{ContainerID:65534 HostID:65534 Size:1}] GidMappingsEnableSetgroups:false AmbientCaps:[]} I0109 00:09:38.916746 20660 x:0] Sandbox started, PID: 25109 I0109 00:09:39.047185 1 x:0] *************************** I0109 00:09:39.047219 1 x:0] Args: [runsc-sandbox --root=/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root --debug=true --log= --log-format=text --debug-log= --debug-log-format=text --file-access=exclusive --overlay=false --fsgofer-host-uds=false --network=sandbox --log-packets=false --platform=kvm --strace=false --strace-syscalls= --strace-log-size=1024 --watchdog-action=Panic --panic-signal=-1 --profile=false --net-raw=false --num-network-channels=1 --rootless=false --alsologtostderr=true --ref-leak-mode=disabled --gso=true --software-gso=true --overlayfs-stale-read=false --panic-signal=15 boot --bundle=/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/instance-1/bundle --controller-fd=3 --mounts-fd=4 --spec-fd=5 --start-sync-fd=6 --io-fds=7 --device-fd=8 --stdio-fds=9 --stdio-fds=10 --stdio-fds=11 --pidns=true --setup-root ci-gvisor-kvm-direct-sandbox-test-1] I0109 00:09:39.047260 1 x:0] Version release-20191213.0-78-gd530df2f95c3 I0109 00:09:39.047265 1 x:0] PID: 1 I0109 00:09:39.047271 1 x:0] UID: 0, GID: 65534 I0109 00:09:39.047275 1 x:0] Configuration: I0109 00:09:39.047278 1 x:0] RootDir: /syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root I0109 00:09:39.047282 1 x:0] Platform: kvm I0109 00:09:39.047297 1 x:0] FileAccess: exclusive, overlay: false I0109 00:09:39.047313 1 x:0] Network: sandbox, logging: false I0109 00:09:39.047320 1 x:0] Strace: false, max size: 1024, syscalls: [] I0109 00:09:39.047331 1 x:0] *************************** I0109 00:09:39.047374 1 x:0] Setting up sandbox chroot in "/tmp" I0109 00:09:39.187423 1 x:0] Mounting "proc" at "/tmp/proc" I0109 00:09:39.329258 1 x:0] Execve "/proc/self/exe" again, bye! I0109 00:09:39.408675 1 x:0] *************************** I0109 00:09:39.408806 1 x:0] Args: [runsc-sandbox --root=/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root --debug=true --log= --log-format=text --debug-log= --debug-log-format=text --file-access=exclusive --overlay=false --fsgofer-host-uds=false --network=sandbox --log-packets=false --platform=kvm --strace=false --strace-syscalls= --strace-log-size=1024 --watchdog-action=Panic --panic-signal=-1 --profile=false --net-raw=false --num-network-channels=1 --rootless=false --alsologtostderr=true --ref-leak-mode=disabled --gso=true --software-gso=true --overlayfs-stale-read=false --panic-signal=15 boot --bundle=/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/instance-1/bundle --controller-fd=3 --mounts-fd=4 --spec-fd=5 --start-sync-fd=6 --io-fds=7 --device-fd=8 --stdio-fds=9 --stdio-fds=10 --stdio-fds=11 --pidns=true ci-gvisor-kvm-direct-sandbox-test-1] I0109 00:09:39.408901 1 x:0] Version release-20191213.0-78-gd530df2f95c3 I0109 00:09:39.408932 1 x:0] PID: 1 I0109 00:09:39.408950 1 x:0] UID: 65534, GID: 65534 I0109 00:09:39.408954 1 x:0] Configuration: I0109 00:09:39.408957 1 x:0] RootDir: /syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/gvisor_root I0109 00:09:39.408961 1 x:0] Platform: kvm I0109 00:09:39.408964 1 x:0] FileAccess: exclusive, overlay: false I0109 00:09:39.408981 1 x:0] Network: sandbox, logging: false I0109 00:09:39.408987 1 x:0] Strace: false, max size: 1024, syscalls: [] I0109 00:09:39.408994 1 x:0] *************************** D0109 00:09:39.410419 1 x:0] Spec: &{Version: Process:0xc0002c7520 Root:0xc0002ccb00 Hostname: Mounts:[] Hooks: Annotations:map[] Linux: Solaris: Windows:} D0109 00:09:39.410533 1 x:0] Spec.Hooks: D0109 00:09:39.410579 1 x:0] Spec.Linux: D0109 00:09:39.410615 1 x:0] Spec.Process: &{Terminal:false ConsoleSize: User:{UID:0 GID:0 AdditionalGids:[] Username:} Args:[/init] Env:[SYZ_GVISOR_PROXY=1] Cwd:/tmp Capabilities:0xc00014a300 Rlimits:[] NoNewPrivileges:false ApparmorProfile: OOMScoreAdj: SelinuxLabel:} D0109 00:09:39.410697 1 x:0] Spec.Root: &{Path:/syzkaller/managers/kvm-direct-sandbox/latest.tmp/workdir/instance-1/image Readonly:true} D0109 00:09:39.410739 1 x:0] Spec.Mounts: [] I0109 00:09:39.410845 1 x:0] Platform: kvm I0109 00:09:39.411021 1 x:0] excluded: virtual [7ffcbeb73000,7ffcbeb75000) I0109 00:09:39.411063 1 x:0] excluded: virtual [7ffcbeb75000,7ffcbeb77000) I0109 00:09:39.411125 1 x:0] region: virtual [3f1edd06a000,7f205d066000) I0109 00:09:39.411278 1 x:0] region: virtual [7ffcbeb73000,7ffcbeb75000) I0109 00:09:39.411329 1 x:0] region: virtual [7ffcbeb75000,7ffcbeb77000) I0109 00:09:39.411361 1 x:0] physicalRegion: virtual [1000,3f1edd06a000) => physical [100001000,3f1fdd06a000) I0109 00:09:39.411391 1 x:0] physicalRegion: virtual [7f205d066000,7ffcbeb73000) => physical [3f205d066000,3ffcbeb73000) I0109 00:09:39.411419 1 x:0] physicalRegion: virtual [7ffcbeb77000,7ffffffff000) => physical [3ffcbeb77000,3ffffffff000) D0109 00:09:39.432229 1 x:0] The maximum number of vCPUs is 288. runtime: failed to create new OS thread (have 7 already; errno=11) runtime: may need to increase max user processes (ulimit -u) fatal error: newosproc runtime stack: runtime.throw(0xdb7383, 0x9) GOROOT/src/runtime/panic.go:774 +0x72 runtime.newosproc(0xc0000dea80) GOROOT/src/runtime/os_linux.go:153 +0x1ba runtime.newm1(0xc0000dea80) GOROOT/src/runtime/proc.go:1853 +0xdc runtime.newm(0xde8a10, 0xc00003af00) GOROOT/src/runtime/proc.go:1832 +0x8f runtime.startm(0x0, 0xc000034001) GOROOT/src/runtime/proc.go:1969 +0x12a runtime.wakep(...) GOROOT/src/runtime/proc.go:2050 runtime.resetspinning() GOROOT/src/runtime/proc.go:2430 +0x93 runtime.schedule() GOROOT/src/runtime/proc.go:2531 +0x2a0 runtime.park_m(0xc000146c00) GOROOT/src/runtime/proc.go:2610 +0x9d runtime.mcall(0x0) src/runtime/asm_amd64.s:318 +0x5b goroutine 1 [runnable]: reflect.valueInterface(0xbffa00, 0xc0004420c0, 0x16, 0xbffa01, 0xc0004420c0, 0x16) GOROOT/src/reflect/value.go:1006 +0x1c2 reflect.Value.Interface(...) GOROOT/src/reflect/value.go:1003 gvisor.dev/gvisor/pkg/tcpip.fillIn(0xd63240, 0xc000442080, 0x199) pkg/tcpip/tcpip.go:1158 +0xea gvisor.dev/gvisor/pkg/tcpip.fillIn(0xccac80, 0xc000442080, 0x199) pkg/tcpip/tcpip.go:1163 +0x16e gvisor.dev/gvisor/pkg/tcpip.fillIn(0xd07160, 0xc000442018, 0x199) pkg/tcpip/tcpip.go:1163 +0x16e gvisor.dev/gvisor/pkg/tcpip.fillIn(0xd38d20, 0xc000442000, 0x199) pkg/tcpip/tcpip.go:1163 +0x16e gvisor.dev/gvisor/pkg/tcpip.Stats.FillIn(0xc00012eef0, 0xc00012eef8, 0xc00012ef00, 0xc00012ef08, 0xc00012ef10, 0xc00012ef18, 0xc00012ef20, 0xc00012ef28, 0xc00012ef30, 0xc00012ef38, ...) pkg/tcpip/tcpip.go:1170 +0x131 gvisor.dev/gvisor/pkg/tcpip/stack.New(0xc0002cebd0, 0x3, 0x3, 0xc0002cec00, 0x3, 0x3, 0xedc520, 0xc00029f380, 0xc00012eef0, 0xc00012eef8, ...) pkg/tcpip/stack/stack.go:586 +0x1dc gvisor.dev/gvisor/runsc/boot.newEmptyNetworkStack(0xc00025f7a0, 0xedc520, 0xc00029f380, 0xed6280, 0xc00029f380, 0x0, 0x0, 0xc1ece0, 0xc0002cd101) runsc/boot/loader.go:908 +0x3b3 gvisor.dev/gvisor/runsc/boot.New(0x7ffcbeb51fc5, 0x23, 0xc0002510a0, 0xc00025f7a0, 0x3, 0xc0001428f0, 0xc00012fb48, 0x1, 0x1, 0xc000125a20, ...) runsc/boot/loader.go:228 +0x76c gvisor.dev/gvisor/runsc/cmd.(*Boot).Execute(0xc0001bc480, 0xee7860, 0xc00012e000, 0xc000286540, 0xc000240d20, 0x2, 0x2, 0x0) runsc/cmd/boot.go:226 +0xaa6 github.com/google/subcommands.(*Commander).Execute(0xc00014a000, 0xee7860, 0xc00012e000, 0xc000240d20, 0x2, 0x2, 0x0) external/com_github_google_subcommands/subcommands.go:200 +0x2f9 github.com/google/subcommands.Execute(...) external/com_github_google_subcommands/subcommands.go:481 main.main() runsc/main.go:318 +0x1754 goroutine 18 [syscall]: os/signal.signal_recv(0x0) GOROOT/src/runtime/sigqueue.go:147 +0x9c os/signal.loop() GOROOT/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 GOROOT/src/os/signal/signal_unix.go:29 +0x41 goroutine 19 [sync.Cond.Wait]: runtime.goparkunlock(...) GOROOT/src/runtime/proc.go:310 sync.runtime_notifyListWait(0xc00028c948, 0x0) GOROOT/src/runtime/sema.go:510 +0xf8 sync.(*Cond).Wait(0xc00028c938) GOROOT/src/sync/cond.go:56 +0x9d gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).findReclaimable(0xc00028c500, 0x0, 0x0, 0x0) pkg/sentry/pgalloc/pgalloc.go:1090 +0x95 gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).runReclaim(0xc00028c500) pkg/sentry/pgalloc/pgalloc.go:1019 +0x6a created by gvisor.dev/gvisor/pkg/sentry/pgalloc.NewMemoryFile pkg/sentry/pgalloc/pgalloc.go:330 +0x1fd goroutine 21 [runnable]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Timekeeper).startUpdater.func1(0xc000286b40, 0xc0002605a0) pkg/sentry/kernel/timekeeper.go:184 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Timekeeper).startUpdater pkg/sentry/kernel/timekeeper.go:184 +0xbf D0109 00:09:40.530993 20660 x:0] Destroy sandbox "ci-gvisor-kvm-direct-sandbox-test-1" D0109 00:09:40.531021 20660 x:0] Killing sandbox "ci-gvisor-kvm-direct-sandbox-test-1" W0109 00:09:40.636626 20660 x:0] error destroying sandbox: D0109 00:09:40.636732 20660 x:0] Destroy container "ci-gvisor-kvm-direct-sandbox-test-1" D0109 00:09:40.636834 20660 x:0] Killing gofer for container "ci-gvisor-kvm-direct-sandbox-test-1", PID: 24459 W0109 00:09:40.840777 20660 x:0] FATAL ERROR: running container: creating container: waiting for sandbox to start: EOF running container: creating container: waiting for sandbox to start: EOF W0109 00:09:40.841019 20660 x:0] Failure to execute command, err: 1