I1125 08:55:22.291987 939884 main.go:189] *************************** I1125 08:55:22.292094 939884 main.go:190] Args: [/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/image -root /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root -watchdog-action=panic -network=none -debug -debug-log=/dev/stderr -platform=ptrace -network=host -overlay2=all:self -net-raw -watchdog-action=panic -TESTONLY-unsafe-nonroot --panic-log /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/panic.fifo run -bundle /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle ci-gvisor-ptrace-3-race-cover-test-0] I1125 08:55:22.292159 939884 main.go:191] Version 0.0.0 I1125 08:55:22.292192 939884 main.go:192] GOOS: linux I1125 08:55:22.292218 939884 main.go:193] GOARCH: amd64 I1125 08:55:22.292235 939884 main.go:194] PID: 939884 I1125 08:55:22.292254 939884 main.go:195] UID: 0, GID: 0 I1125 08:55:22.292272 939884 main.go:196] Configuration: I1125 08:55:22.292289 939884 main.go:197] RootDir: /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root I1125 08:55:22.292322 939884 main.go:198] Platform: ptrace I1125 08:55:22.292340 939884 main.go:199] FileAccess: exclusive I1125 08:55:22.292368 939884 main.go:200] Directfs: true I1125 08:55:22.292404 939884 main.go:201] Overlay: all:self I1125 08:55:22.292433 939884 main.go:202] Network: host, logging: false I1125 08:55:22.292472 939884 main.go:203] Strace: false, max size: 1024, syscalls: I1125 08:55:22.292515 939884 main.go:204] IOURING: false I1125 08:55:22.292531 939884 main.go:205] Debug: true I1125 08:55:22.292547 939884 main.go:206] Systemd: false I1125 08:55:22.292576 939884 main.go:207] *************************** W1125 08:55:22.292621 939884 main.go:212] Block the TERM signal. This is only safe in tests! W1125 08:55:22.297058 939884 specutils.go:124] noNewPrivileges ignored. PR_SET_NO_NEW_PRIVS is assumed to always be set. D1125 08:55:22.297714 939884 specutils.go:86] Spec: { "ociVersion": "", "process": { "user": { "uid": 0, "gid": 0 }, "args": [ "/init" ], "env": [ "SYZ_GVISOR_PROXY=1" ], "cwd": "/tmp" }, "root": { "path": "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/image", "readonly": true }, "linux": { "sysctl": { "fs.nr_open": "1048576" }, "resources": { "memory": { "limit": -1, "reservation": -1, "disableOOMKiller": false }, "cpu": { "shares": 1024 } }, "cgroupsPath": "ci-gvisor-ptrace-3-race-cover-test-0" } } D1125 08:55:22.297812 939884 container.go:551] Run container, cid: ci-gvisor-ptrace-3-race-cover-test-0, rootDir: "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root" D1125 08:55:22.297885 939884 container.go:198] Create container, cid: ci-gvisor-ptrace-3-race-cover-test-0, rootDir: "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root" D1125 08:55:22.298216 939884 container.go:260] Creating new sandbox for container, cid: ci-gvisor-ptrace-3-race-cover-test-0 D1125 08:55:22.298881 939884 cgroup.go:410] New cgroup for pid: self, *cgroup.cgroupV1: &{Name:ci-gvisor-ptrace-3-race-cover-test-0 Parents:map[blkio:. cpu:. cpuacct:. cpuset:. devices:. freezer:. hugetlb:. memory:. net_cls:. net_prio:. perf_event:. pids:. rdma:. systemd:.] Own:map[]} D1125 08:55:22.299031 939884 cgroup.go:483] Installing cgroup path "ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299094 939884 cgroup.go:501] Using pre-created cgroup "cpuset": "/sys/fs/cgroup/cpuset/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299138 939884 cgroup.go:501] Using pre-created cgroup "hugetlb": "/sys/fs/cgroup/hugetlb/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299192 939884 cgroup.go:501] Using pre-created cgroup "blkio": "/sys/fs/cgroup/blkio/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299244 939884 cgroup.go:501] Using pre-created cgroup "cpu": "/sys/fs/cgroup/cpu/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299286 939884 cgroup.go:501] Using pre-created cgroup "net_prio": "/sys/fs/cgroup/net_prio/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299324 939884 cgroup.go:501] Using pre-created cgroup "systemd": "/sys/fs/cgroup/systemd/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299371 939884 cgroup.go:501] Using pre-created cgroup "net_cls": "/sys/fs/cgroup/net_cls/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299407 939884 cgroup.go:501] Using pre-created cgroup "devices": "/sys/fs/cgroup/devices/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299444 939884 cgroup.go:501] Using pre-created cgroup "perf_event": "/sys/fs/cgroup/perf_event/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299490 939884 cgroup.go:501] Using pre-created cgroup "rdma": "/sys/fs/cgroup/rdma/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299576 939884 cgroup.go:501] Using pre-created cgroup "memory": "/sys/fs/cgroup/memory/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299677 939884 cgroup.go:501] Using pre-created cgroup "pids": "/sys/fs/cgroup/pids/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299771 939884 cgroup.go:501] Using pre-created cgroup "cpuacct": "/sys/fs/cgroup/cpuacct/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.299840 939884 cgroup.go:501] Using pre-created cgroup "freezer": "/sys/fs/cgroup/freezer/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.300676 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/blkio/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.300742 939884 cgroup.go:116] Setting "/sys/fs/cgroup/blkio/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.300868 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/cpu/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.300938 939884 cgroup.go:116] Setting "/sys/fs/cgroup/cpu/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.301181 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/net_prio/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.301270 939884 cgroup.go:116] Setting "/sys/fs/cgroup/net_prio/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.301379 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/systemd/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.301414 939884 cgroup.go:116] Setting "/sys/fs/cgroup/systemd/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.301511 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/net_cls/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.301551 939884 cgroup.go:116] Setting "/sys/fs/cgroup/net_cls/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.301623 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/devices/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.301659 939884 cgroup.go:116] Setting "/sys/fs/cgroup/devices/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.301768 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/memory/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.301806 939884 cgroup.go:116] Setting "/sys/fs/cgroup/memory/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.301933 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/pids/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.301970 939884 cgroup.go:116] Setting "/sys/fs/cgroup/pids/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.302052 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/cpuacct/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.302092 939884 cgroup.go:116] Setting "/sys/fs/cgroup/cpuacct/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.302165 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/freezer/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.302250 939884 cgroup.go:116] Setting "/sys/fs/cgroup/freezer/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.302377 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/perf_event/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.302433 939884 cgroup.go:116] Setting "/sys/fs/cgroup/perf_event/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.302659 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/rdma/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.302731 939884 cgroup.go:116] Setting "/sys/fs/cgroup/rdma/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.302821 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/cpuset/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.302870 939884 cgroup.go:116] Setting "/sys/fs/cgroup/cpuset/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.580193 939884 cgroup.go:616] Joining cgroup "/sys/fs/cgroup/hugetlb/ci-gvisor-ptrace-3-race-cover-test-0" D1125 08:55:22.580330 939884 cgroup.go:116] Setting "/sys/fs/cgroup/hugetlb/ci-gvisor-ptrace-3-race-cover-test-0/cgroup.procs" to "0" D1125 08:55:22.588202 939884 donation.go:31] Donating FD 3: "/dev/stderr" D1125 08:55:22.588305 939884 donation.go:31] Donating FD 4: "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/config.json" D1125 08:55:22.588407 939884 donation.go:31] Donating FD 5: "|1" D1125 08:55:22.588442 939884 donation.go:31] Donating FD 6: "gofer IO FD" D1125 08:55:22.588475 939884 container.go:1348] Starting gofer: /proc/self/exe [runsc-gofer --net-raw=true --platform=ptrace --watchdog-action=panic --root=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root --overlay2=all:self --panic-log=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/panic.fifo --network=host --TESTONLY-unsafe-nonroot=true --debug=true --debug-log=/dev/stderr --debug-log-fd=3 gofer --bundle /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle --gofer-mount-confs=lisafs:none --spec-fd=4 --mounts-fd=5 --io-fds=6] I1125 08:55:22.598769 939884 container.go:1352] Gofer started, PID: 939961 D1125 08:55:22.602835 939884 sandbox.go:84] Attempting to create socket file "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root/runsc-ci-gvisor-ptrace-3-race-cover-test-0.sock" D1125 08:55:22.603101 939884 sandbox.go:87] Using socket file "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root/runsc-ci-gvisor-ptrace-3-race-cover-test-0.sock" I1125 08:55:22.603154 939884 sandbox.go:774] Control socket path: "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root/runsc-ci-gvisor-ptrace-3-race-cover-test-0.sock" I1125 08:55:22.603232 939884 sandbox.go:809] Sandbox will be started in new mount, IPC and UTS namespaces I1125 08:55:22.603255 939884 sandbox.go:819] Sandbox will be started in the current PID namespace I1125 08:55:22.603289 939884 sandbox.go:841] Sandbox will be started in the host network namespace I1125 08:55:22.603324 939884 sandbox.go:878] Sandbox will be started in the current user namespace W1125 08:55:22.603349 939884 sandbox.go:888] Running sandbox in test mode without chroot. This is only safe in tests! D1125 08:55:22.603648 939884 donation.go:31] Donating FD 3: "/dev/stderr" D1125 08:55:22.603698 939884 donation.go:31] Donating FD 4: "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/panic.fifo" D1125 08:55:22.603746 939884 donation.go:31] Donating FD 5: "sandbox IO FD" D1125 08:55:22.603789 939884 donation.go:31] Donating FD 6: "|0" D1125 08:55:22.603827 939884 donation.go:31] Donating FD 7: "|1" D1125 08:55:22.603885 939884 donation.go:31] Donating FD 8: "control_server_socket" D1125 08:55:22.603927 939884 donation.go:31] Donating FD 9: "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/config.json" D1125 08:55:22.604008 939884 donation.go:31] Donating FD 10: "/dev/stdin" D1125 08:55:22.604043 939884 donation.go:31] Donating FD 11: "/dev/stdout" D1125 08:55:22.604073 939884 donation.go:31] Donating FD 12: "/dev/stderr" D1125 08:55:22.604113 939884 sandbox.go:1075] Starting sandbox: /proc/self/exe [runsc-sandbox --platform=ptrace --TESTONLY-unsafe-nonroot=true --watchdog-action=panic --root=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root --debug=true --debug-log=/dev/stderr --panic-log=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/panic.fifo --overlay2=all:self --network=host --net-raw=true --debug-log-fd=3 --panic-log-fd=4 boot --bundle=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle --gofer-mount-confs=lisafs:none --apply-caps=true --total-host-memory 811625025536 --cpu-num 96 --total-memory 811625025536 --attached --io-fds=5 --dev-io-fd=-1 --mounts-fd=6 --start-sync-fd=7 --controller-fd=8 --spec-fd=9 --stdio-fds=10 --stdio-fds=11 --stdio-fds=12 ci-gvisor-ptrace-3-race-cover-test-0] D1125 08:55:22.604244 939884 sandbox.go:1076] SysProcAttr: &{Chroot: Credential: Ptrace:false Setsid:true Setpgid:false Setctty:false Noctty:false Ctty:0 Foreground:false Pgid:0 Pdeathsig:killed Cloneflags:0 Unshareflags:0 UidMappings:[] GidMappings:[] GidMappingsEnableSetgroups:false AmbientCaps:[] UseCgroupFD:false CgroupFD:0} I1125 08:55:22.618953 939884 sandbox.go:1104] Sandbox started, PID: 939969 I1125 08:55:22.960614 939969 main.go:189] *************************** I1125 08:55:22.960642 1 main.go:189] *************************** I1125 08:55:22.960736 1 main.go:190] Args: [runsc-gofer --net-raw=true --platform=ptrace --watchdog-action=panic --root=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root --overlay2=all:self --panic-log=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/panic.fifo --network=host --TESTONLY-unsafe-nonroot=true --debug=true --debug-log=/dev/stderr --debug-log-fd=3 gofer --bundle /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle --gofer-mount-confs=lisafs:none --spec-fd=4 --mounts-fd=5 --io-fds=6] I1125 08:55:22.960721 939969 main.go:190] Args: [runsc-sandbox --platform=ptrace --TESTONLY-unsafe-nonroot=true --watchdog-action=panic --root=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root --debug=true --debug-log=/dev/stderr --panic-log=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/panic.fifo --overlay2=all:self --network=host --net-raw=true --debug-log-fd=3 --panic-log-fd=4 boot --bundle=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle --gofer-mount-confs=lisafs:none --apply-caps=true --total-host-memory 811625025536 --cpu-num 96 --total-memory 811625025536 --attached --io-fds=5 --dev-io-fd=-1 --mounts-fd=6 --start-sync-fd=7 --controller-fd=8 --spec-fd=9 --stdio-fds=10 --stdio-fds=11 --stdio-fds=12 ci-gvisor-ptrace-3-race-cover-test-0] I1125 08:55:22.960855 939969 main.go:191] Version 0.0.0 I1125 08:55:22.960843 1 main.go:191] Version 0.0.0 I1125 08:55:22.960899 1 main.go:192] GOOS: linux I1125 08:55:22.960898 939969 main.go:192] GOOS: linux I1125 08:55:22.960926 1 main.go:193] GOARCH: amd64 I1125 08:55:22.960942 939969 main.go:193] GOARCH: amd64 I1125 08:55:22.960976 939969 main.go:194] PID: 939969 I1125 08:55:22.960976 1 main.go:194] PID: 1 I1125 08:55:22.961035 1 main.go:195] UID: 0, GID: 0 I1125 08:55:22.961030 939969 main.go:195] UID: 0, GID: 0 I1125 08:55:22.961062 1 main.go:196] Configuration: I1125 08:55:22.961088 939969 main.go:196] Configuration: I1125 08:55:22.961088 1 main.go:197] RootDir: /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root I1125 08:55:22.961122 939969 main.go:197] RootDir: /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root I1125 08:55:22.961158 939969 main.go:198] Platform: ptrace I1125 08:55:22.961155 1 main.go:198] Platform: ptrace I1125 08:55:22.961189 939969 main.go:199] FileAccess: exclusive I1125 08:55:22.961225 939969 main.go:200] Directfs: true I1125 08:55:22.961224 1 main.go:199] FileAccess: exclusive I1125 08:55:22.961287 939969 main.go:201] Overlay: all:self I1125 08:55:22.961285 1 main.go:200] Directfs: true I1125 08:55:22.961319 939969 main.go:202] Network: host, logging: false I1125 08:55:22.961328 1 main.go:201] Overlay: all:self I1125 08:55:22.961362 1 main.go:202] Network: host, logging: false I1125 08:55:22.961362 939969 main.go:203] Strace: false, max size: 1024, syscalls: I1125 08:55:22.961427 939969 main.go:204] IOURING: false I1125 08:55:22.961424 1 main.go:203] Strace: false, max size: 1024, syscalls: I1125 08:55:22.961463 939969 main.go:205] Debug: true I1125 08:55:22.961473 1 main.go:204] IOURING: false I1125 08:55:22.961514 1 main.go:205] Debug: true I1125 08:55:22.961507 939969 main.go:206] Systemd: false I1125 08:55:22.961566 1 main.go:206] Systemd: false I1125 08:55:22.961566 939969 main.go:207] *************************** I1125 08:55:22.961620 1 main.go:207] *************************** W1125 08:55:22.961629 939969 main.go:212] Block the TERM signal. This is only safe in tests! W1125 08:55:22.961657 1 main.go:212] Block the TERM signal. This is only safe in tests! I1125 08:55:22.966355 939969 boot.go:245] Setting product_name: "Google Compute Engine" W1125 08:55:22.966524 1 specutils.go:124] noNewPrivileges ignored. PR_SET_NO_NEW_PRIVS is assumed to always be set. I1125 08:55:22.966830 1 gofer.go:442] Create working directory "/tmp" if needed I1125 08:55:22.966992 1 gofer.go:456] Remounting root as readonly: "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/image" I1125 08:55:22.967559 1 capability.go:66] Capabilities applied: { effective="chown, dac_override, dac_read_search, fowner, fsetid, sys_chroot" permitted="chown, dac_override, dac_read_search, fowner, fsetid, sys_chroot" inheritable="empty" bounding="chown, dac_override, dac_read_search, fowner, fsetid, sys_chroot" } I1125 08:55:22.967676 1 cmd.go:82] Execve "/proc/self/exe" again, bye! W1125 08:55:22.970966 939969 specutils.go:124] noNewPrivileges ignored. PR_SET_NO_NEW_PRIVS is assumed to always be set. D1125 08:55:22.971625 939969 specutils.go:86] Spec: { "ociVersion": "", "process": { "user": { "uid": 0, "gid": 0 }, "args": [ "/init" ], "env": [ "SYZ_GVISOR_PROXY=1" ], "cwd": "/tmp" }, "root": { "path": "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/image", "readonly": true }, "linux": { "sysctl": { "fs.nr_open": "1048576" }, "resources": { "memory": { "limit": -1, "reservation": -1, "disableOOMKiller": false }, "cpu": { "shares": 1024 } }, "cgroupsPath": "ci-gvisor-ptrace-3-race-cover-test-0" } } I1125 08:55:22.972764 939969 capability.go:66] Capabilities applied: { effective="chown, dac_override, dac_read_search, fowner, fsetid, kill, setgid, setuid, setpcap, linux_immutable, net_bind_service, net_broadcast, net_admin, net_raw, ipc_lock, ipc_owner, sys_module, sys_rawio, sys_chroot, sys_ptrace, sys_pacct, sys_admin, sys_boot, sys_nice, sys_resource, sys_time, sys_tty_config, mknod, lease, audit_write, audit_control, setfcap, mac_override, mac_admin, syslog, wake_alarm, block_suspend, audit_read" permitted="chown, dac_override, dac_read_search, fowner, fsetid, kill, setgid, setuid, setpcap, linux_immutable, net_bind_service, net_broadcast, net_admin, net_raw, ipc_lock, ipc_owner, sys_module, sys_rawio, sys_chroot, sys_ptrace, sys_pacct, sys_admin, sys_boot, sys_nice, sys_resource, sys_time, sys_tty_config, mknod, lease, audit_write, audit_control, setfcap, mac_override, mac_admin, syslog, wake_alarm, block_suspend, audit_read" inheritable="chown, dac_override, dac_read_search, fowner, fsetid, kill, setgid, setuid, setpcap, linux_immutable, net_bind_service, net_broadcast, net_admin, net_raw, ipc_lock, ipc_owner, sys_module, sys_rawio, sys_chroot, sys_ptrace, sys_pacct, sys_admin, sys_boot, sys_nice, sys_resource, sys_time, sys_tty_config, mknod, lease, audit_write, audit_control, setfcap, mac_override, mac_admin, syslog, wake_alarm, block_suspend, audit_read" bounding="chown, dac_override, dac_read_search, fowner, fsetid, kill, setgid, setuid, setpcap, linux_immutable, net_bind_service, net_broadcast, net_admin, net_raw, ipc_lock, ipc_owner, sys_module, sys_rawio, sys_chroot, sys_ptrace, sys_pacct, sys_admin, sys_boot, sys_nice, sys_resource, sys_time, sys_tty_config, mknod, lease, audit_write, audit_control, setfcap, mac_override, mac_admin, syslog, wake_alarm, block_suspend, audit_read" } I1125 08:55:22.974462 939969 cmd.go:82] Execve "/proc/self/exe" again, bye! I1125 08:55:23.285855 939969 main.go:189] *************************** I1125 08:55:23.285965 939969 main.go:190] Args: [runsc-sandbox --platform=ptrace --TESTONLY-unsafe-nonroot=true --watchdog-action=panic --root=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root --debug=true --debug-log=/dev/stderr --panic-log=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/panic.fifo --overlay2=all:self --network=host --net-raw=true --debug-log-fd=3 --panic-log-fd=4 boot --apply-caps=false --attached=true --bundle=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle --controller-fd=8 --cpu-num=96 --dev-io-fd=-1 --gofer-mount-confs=lisafs:none --io-fds=5 --mounts-fd=6 --spec-fd=9 --start-sync-fd=7 --stdio-fds=10,11,12 --total-host-memory=811625025536 --total-memory=811625025536 --product-name=Google Compute Engine ci-gvisor-ptrace-3-race-cover-test-0] I1125 08:55:23.286078 939969 main.go:191] Version 0.0.0 I1125 08:55:23.286118 939969 main.go:192] GOOS: linux I1125 08:55:23.286171 939969 main.go:193] GOARCH: amd64 I1125 08:55:23.286201 939969 main.go:194] PID: 939969 I1125 08:55:23.286251 939969 main.go:195] UID: 0, GID: 0 I1125 08:55:23.286294 939969 main.go:196] Configuration: I1125 08:55:23.286327 939969 main.go:197] RootDir: /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root I1125 08:55:23.286374 939969 main.go:198] Platform: ptrace I1125 08:55:23.286402 939969 main.go:199] FileAccess: exclusive I1125 08:55:23.286422 939969 main.go:200] Directfs: true I1125 08:55:23.286448 939969 main.go:201] Overlay: all:self I1125 08:55:23.286498 939969 main.go:202] Network: host, logging: false I1125 08:55:23.286536 939969 main.go:203] Strace: false, max size: 1024, syscalls: I1125 08:55:23.286573 939969 main.go:204] IOURING: false I1125 08:55:23.286602 939969 main.go:205] Debug: true I1125 08:55:23.286637 939969 main.go:206] Systemd: false I1125 08:55:23.286686 939969 main.go:207] *************************** W1125 08:55:23.286714 939969 main.go:212] Block the TERM signal. This is only safe in tests! I1125 08:55:23.288837 1 main.go:189] *************************** I1125 08:55:23.288953 1 main.go:190] Args: [runsc-gofer --net-raw=true --platform=ptrace --watchdog-action=panic --root=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root --overlay2=all:self --panic-log=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle/panic.fifo --network=host --TESTONLY-unsafe-nonroot=true --debug=true --debug-log=/dev/stderr --debug-log-fd=3 gofer --bundle=/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/bundle --gofer-mount-confs=lisafs:none --io-fds=6 --mounts-fd=5 --spec-fd=4 --apply-caps=false --setup-root=false --sync-nvproxy-fd=-1 --sync-userns-fd=-1 --proc-mount-sync-fd=-1] I1125 08:55:23.289060 1 main.go:191] Version 0.0.0 I1125 08:55:23.289109 1 main.go:192] GOOS: linux I1125 08:55:23.289157 1 main.go:193] GOARCH: amd64 I1125 08:55:23.289198 1 main.go:194] PID: 1 I1125 08:55:23.289242 1 main.go:195] UID: 0, GID: 0 I1125 08:55:23.289319 1 main.go:196] Configuration: I1125 08:55:23.289347 1 main.go:197] RootDir: /syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/gvisor_root I1125 08:55:23.289377 1 main.go:198] Platform: ptrace I1125 08:55:23.289414 1 main.go:199] FileAccess: exclusive I1125 08:55:23.289459 1 main.go:200] Directfs: true I1125 08:55:23.289486 1 main.go:201] Overlay: all:self I1125 08:55:23.289515 1 main.go:202] Network: host, logging: false I1125 08:55:23.289544 1 main.go:203] Strace: false, max size: 1024, syscalls: I1125 08:55:23.289573 1 main.go:204] IOURING: false I1125 08:55:23.289598 1 main.go:205] Debug: true I1125 08:55:23.289624 1 main.go:206] Systemd: false I1125 08:55:23.289666 1 main.go:207] *************************** W1125 08:55:23.289717 1 main.go:212] Block the TERM signal. This is only safe in tests! W1125 08:55:23.294779 1 specutils.go:124] noNewPrivileges ignored. PR_SET_NO_NEW_PRIVS is assumed to always be set. W1125 08:55:23.294799 939969 specutils.go:124] noNewPrivileges ignored. PR_SET_NO_NEW_PRIVS is assumed to always be set. D1125 08:55:23.295419 939969 specutils.go:86] Spec: { "ociVersion": "", "process": { "user": { "uid": 0, "gid": 0 }, "args": [ "/init" ], "env": [ "SYZ_GVISOR_PROXY=1" ], "cwd": "/tmp" }, "root": { "path": "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/image", "readonly": true }, "linux": { "sysctl": { "fs.nr_open": "1048576" }, "resources": { "memory": { "limit": -1, "reservation": -1, "disableOOMKiller": false }, "cpu": { "shares": 1024 } }, "cgroupsPath": "ci-gvisor-ptrace-3-race-cover-test-0" } } D1125 08:55:23.295574 1 specutils.go:86] Spec: { "ociVersion": "", "process": { "user": { "uid": 0, "gid": 0 }, "args": [ "/init" ], "env": [ "SYZ_GVISOR_PROXY=1" ], "cwd": "/tmp" }, "root": { "path": "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/image", "readonly": true }, "linux": { "sysctl": { "fs.nr_open": "1048576" }, "resources": { "memory": { "limit": -1, "reservation": -1, "disableOOMKiller": false }, "cpu": { "shares": 1024 } }, "cgroupsPath": "ci-gvisor-ptrace-3-race-cover-test-0" } } I1125 08:55:23.295874 1 gofer.go:235] Process chroot'd to "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/image" W1125 08:55:23.296006 1 extra_filters_race.go:28] *** SECCOMP WARNING: TSAN is enabled: syscall filters less restrictive! I1125 08:55:23.296131 1 seccomp.go:63] Installing seccomp filters for 68 syscalls (action=kill process) D1125 08:55:23.301169 1 seccomp.go:672] Ordered seccomp rules: D1125 08:55:23.301224 1 seccomp.go:682] Hot non-trivial syscalls: None. D1125 08:55:23.301256 1 seccomp.go:685] Cold non-trivial syscalls: D1125 08:55:23.301376 1 seccomp.go:692] - sysno=9: {((arg0 == * && arg1 == * && arg2 & 0x4 == 0x4)) => kill process, (true) => allow} D1125 08:55:23.301495 1 seccomp.go:692] - sysno=10: {((arg0 == * && arg1 == * && arg2 & 0x4 == 0x4)) => kill process, (true) => allow} D1125 08:55:23.301638 1 seccomp.go:692] - sysno=46: {(((arg0 == * && arg1 == * && arg2 == 0x0) || (arg0 == * && arg1 == * && arg2 == 0x4040))) => allow} D1125 08:55:23.301780 1 seccomp.go:692] - sysno=47: {(((arg0 == * && arg1 == * && arg2 == 0x60) || (arg0 == * && arg1 == * && arg2 == 0x62))) => allow} D1125 08:55:23.301850 1 seccomp.go:692] - sysno=48: {((arg0 == * && arg1 == 0x2)) => allow} D1125 08:55:23.301911 1 seccomp.go:692] - sysno=53: {((arg0 == 0x1 && arg1 == 0x80005 && arg2 == 0x0)) => allow} D1125 08:55:23.302028 1 seccomp.go:692] - sysno=72: {(((arg0 == * && arg1 == 0x3) || (arg0 == * && arg1 == 0x4) || (arg0 == * && arg1 == 0x1) || (arg0 == * && arg1 == 0x409))) => allow} D1125 08:55:23.302107 1 seccomp.go:692] - sysno=234: {(arg0 == 0x1) => allow} D1125 08:55:23.302156 1 seccomp.go:692] - sysno=281: {((arg0 == * && arg1 == * && arg2 == * && arg3 == * && arg4 == 0x0)) => allow} D1125 08:55:23.302236 1 seccomp.go:692] - sysno=285: {((arg0 == * && arg1 == 0x0)) => allow} D1125 08:55:23.302313 1 seccomp.go:692] - sysno=290: {((arg0 == 0x0 && arg1 == 0x0)) => allow} D1125 08:55:23.302381 1 seccomp.go:692] - sysno=309: {((arg0 == * && arg1 == 0x0 && arg2 == 0x0)) => allow} D1125 08:55:23.302449 1 seccomp.go:685] Trivial syscalls: D1125 08:55:23.302520 1 seccomp.go:692] - sysno=0: {(true) => allow} D1125 08:55:23.302560 1 seccomp.go:692] - sysno=1: {(true) => allow} D1125 08:55:23.302581 1 seccomp.go:692] - sysno=2: {(true) => allow} D1125 08:55:23.302611 1 seccomp.go:692] - sysno=3: {(true) => allow} D1125 08:55:23.302640 1 seccomp.go:692] - sysno=5: {(true) => allow} D1125 08:55:23.302660 1 seccomp.go:692] - sysno=8: {(true) => allow} D1125 08:55:23.302696 1 seccomp.go:692] - sysno=11: {(true) => allow} D1125 08:55:23.302730 1 seccomp.go:692] - sysno=12: {(true) => allow} D1125 08:55:23.302772 1 seccomp.go:692] - sysno=13: {(true) => allow} D1125 08:55:23.302792 1 seccomp.go:692] - sysno=14: {(true) => allow} D1125 08:55:23.302819 1 seccomp.go:692] - sysno=15: {(true) => allow} D1125 08:55:23.302858 1 seccomp.go:692] - sysno=17: {(true) => allow} D1125 08:55:23.302910 1 seccomp.go:692] - sysno=18: {(true) => allow} D1125 08:55:23.302967 1 seccomp.go:692] - sysno=24: {(true) => allow} D1125 08:55:23.303002 1 seccomp.go:692] - sysno=28: {(true) => allow} D1125 08:55:23.303056 1 seccomp.go:692] - sysno=32: {(true) => allow} D1125 08:55:23.303085 1 seccomp.go:692] - sysno=35: {(true) => allow} D1125 08:55:23.303119 1 seccomp.go:692] - sysno=39: {(true) => allow} D1125 08:55:23.303139 1 seccomp.go:692] - sysno=43: {(true) => allow} D1125 08:55:23.303167 1 seccomp.go:692] - sysno=56: {(true) => allow} D1125 08:55:23.303226 1 seccomp.go:692] - sysno=60: {(true) => allow} D1125 08:55:23.303277 1 seccomp.go:692] - sysno=74: {(true) => allow} D1125 08:55:23.303334 1 seccomp.go:692] - sysno=77: {(true) => allow} D1125 08:55:23.303382 1 seccomp.go:692] - sysno=91: {(true) => allow} D1125 08:55:23.303416 1 seccomp.go:692] - sysno=96: {(true) => allow} D1125 08:55:23.303445 1 seccomp.go:692] - sysno=131: {(true) => allow} D1125 08:55:23.303500 1 seccomp.go:692] - sysno=138: {(true) => allow} D1125 08:55:23.303546 1 seccomp.go:692] - sysno=150: {(true) => allow} D1125 08:55:23.303599 1 seccomp.go:692] - sysno=186: {(true) => allow} D1125 08:55:23.303657 1 seccomp.go:692] - sysno=201: {(true) => allow} D1125 08:55:23.303725 1 seccomp.go:692] - sysno=202: {(true) => allow} D1125 08:55:23.303789 1 seccomp.go:692] - sysno=204: {(true) => allow} D1125 08:55:23.303856 1 seccomp.go:692] - sysno=217: {(true) => allow} D1125 08:55:23.303952 1 seccomp.go:692] - sysno=219: {(true) => allow} D1125 08:55:23.304006 1 seccomp.go:692] - sysno=228: {(true) => allow} D1125 08:55:23.304060 1 seccomp.go:692] - sysno=230: {(true) => allow} D1125 08:55:23.304109 1 seccomp.go:692] - sysno=231: {(true) => allow} D1125 08:55:23.304161 1 seccomp.go:692] - sysno=233: {(true) => allow} D1125 08:55:23.304225 1 seccomp.go:692] - sysno=257: {(true) => allow} D1125 08:55:23.304273 1 seccomp.go:692] - sysno=258: {(true) => allow} D1125 08:55:23.304323 1 seccomp.go:692] - sysno=259: {(true) => allow} D1125 08:55:23.304419 1 seccomp.go:692] - sysno=260: {(true) => allow} D1125 08:55:23.304463 1 seccomp.go:692] - sysno=262: {(true) => allow} D1125 08:55:23.304530 1 seccomp.go:692] - sysno=263: {(true) => allow} D1125 08:55:23.304562 1 seccomp.go:692] - sysno=264: {(true) => allow} D1125 08:55:23.304629 1 seccomp.go:692] - sysno=265: {(true) => allow} D1125 08:55:23.304682 1 seccomp.go:692] - sysno=266: {(true) => allow} D1125 08:55:23.304756 1 seccomp.go:692] - sysno=267: {(true) => allow} D1125 08:55:23.304778 1 seccomp.go:692] - sysno=268: {(true) => allow} D1125 08:55:23.304806 1 seccomp.go:692] - sysno=271: {(true) => allow} D1125 08:55:23.304933 1 seccomp.go:692] - sysno=273: {(true) => allow} D1125 08:55:23.304978 1 seccomp.go:692] - sysno=280: {(true) => allow} D1125 08:55:23.305014 1 seccomp.go:692] - sysno=318: {(true) => allow} D1125 08:55:23.305077 1 seccomp.go:692] - sysno=319: {(true) => allow} D1125 08:55:23.305150 1 seccomp.go:692] - sysno=334: {(true) => allow} D1125 08:55:23.305203 1 seccomp.go:692] - sysno=435: {(true) => allow} D1125 08:55:23.305265 1 seccomp.go:695] End of ordered seccomp rules. I1125 08:55:23.567864 939969 loader.go:656] Platform: ptrace D1125 08:55:23.568293 939969 seccomp.go:672] Ordered seccomp rules: D1125 08:55:23.568484 939969 seccomp.go:682] Hot non-trivial syscalls: None. D1125 08:55:23.568524 939969 seccomp.go:685] Cold non-trivial syscalls: D1125 08:55:23.568672 939969 seccomp.go:692] - sysno=56: {((arg0 == 0x409 || arg0 == 0x12f00)) => allow} D1125 08:55:23.568783 939969 seccomp.go:692] - sysno=62: {((arg0 == * && arg1 == 0x13)) => allow} D1125 08:55:23.568866 939969 seccomp.go:692] - sysno=96[vsyscall]: {(true) => trap (0)} D1125 08:55:23.568956 939969 seccomp.go:692] - sysno=157: {((arg0 == 0x1 && arg1 == 0x9)) => allow} D1125 08:55:23.569036 939969 seccomp.go:692] - sysno=158: {((arg0 == 0x1012 && arg1 == 0x0)) => allow} D1125 08:55:23.569129 939969 seccomp.go:692] - sysno=201[vsyscall]: {(true) => trap (0)} D1125 08:55:23.569221 939969 seccomp.go:692] - sysno=309[vsyscall]: {(true) => trap (0)} D1125 08:55:23.569259 939969 seccomp.go:685] Trivial syscalls: D1125 08:55:23.569307 939969 seccomp.go:692] - sysno=9: {(true) => allow} D1125 08:55:23.569367 939969 seccomp.go:692] - sysno=11: {(true) => allow} D1125 08:55:23.569440 939969 seccomp.go:692] - sysno=39: {(true) => allow} D1125 08:55:23.569511 939969 seccomp.go:692] - sysno=60: {(true) => allow} D1125 08:55:23.569566 939969 seccomp.go:692] - sysno=61: {(true) => allow} D1125 08:55:23.569636 939969 seccomp.go:692] - sysno=110: {(true) => allow} D1125 08:55:23.569681 939969 seccomp.go:695] End of ordered seccomp rules. I1125 08:55:23.585868 939969 subprocess_linux.go:50] Latest seccomp behavior found (kernel >= 4.8 likely) D1125 08:55:23.586485 939969 seccomp.go:672] Ordered seccomp rules: D1125 08:55:23.586574 939969 seccomp.go:682] Hot non-trivial syscalls: None. D1125 08:55:23.586677 939969 seccomp.go:685] Cold non-trivial syscalls: D1125 08:55:23.586856 939969 seccomp.go:692] - sysno=56: {((arg0 == 0x409 || arg0 == 0x12f00)) => allow} D1125 08:55:23.586994 939969 seccomp.go:692] - sysno=62: {((arg0 == * && arg1 == 0x13)) => allow} D1125 08:55:23.587119 939969 seccomp.go:692] - sysno=96[vsyscall]: {(true) => trap (0)} D1125 08:55:23.587187 939969 seccomp.go:692] - sysno=157: {((arg0 == 0x1 && arg1 == 0x9)) => allow} D1125 08:55:23.587349 939969 seccomp.go:692] - sysno=158: {((arg0 == 0x1012 && arg1 == 0x0)) => allow} D1125 08:55:23.587467 939969 seccomp.go:692] - sysno=201[vsyscall]: {(true) => trap (0)} D1125 08:55:23.587543 939969 seccomp.go:692] - sysno=309[vsyscall]: {(true) => trap (0)} D1125 08:55:23.587576 939969 seccomp.go:685] Trivial syscalls: D1125 08:55:23.587686 939969 seccomp.go:692] - sysno=9: {(true) => allow} D1125 08:55:23.587848 939969 seccomp.go:692] - sysno=11: {(true) => allow} D1125 08:55:23.587890 939969 seccomp.go:692] - sysno=39: {(true) => allow} D1125 08:55:23.587929 939969 seccomp.go:692] - sysno=60: {(true) => allow} D1125 08:55:23.587989 939969 seccomp.go:692] - sysno=61: {(true) => allow} D1125 08:55:23.588013 939969 seccomp.go:692] - sysno=110: {(true) => allow} D1125 08:55:23.588053 939969 seccomp.go:695] End of ordered seccomp rules. I1125 08:55:23.609077 939969 loader.go:439] CPUs: 96 I1125 08:55:23.609222 939969 loader.go:453] Setting total memory to 755.88 GB I1125 08:55:23.610627 939969 loader.go:495] Packet logging disabled I1125 08:55:23.610744 939969 watchdog.go:182] Watchdog waiting 30s for startup D1125 08:55:23.612979 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/net_cls" D1125 08:55:23.613153 939884 cgroup.go:116] Setting "/sys/fs/cgroup/net_cls/cgroup.procs" to "0" D1125 08:55:23.613396 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/net_prio" D1125 08:55:23.613465 939884 cgroup.go:116] Setting "/sys/fs/cgroup/net_prio/cgroup.procs" to "0" D1125 08:55:23.613606 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/cpuset" D1125 08:55:23.613676 939884 cgroup.go:116] Setting "/sys/fs/cgroup/cpuset/cgroup.procs" to "0" D1125 08:55:23.613833 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/cpu" D1125 08:55:23.613869 939884 cgroup.go:116] Setting "/sys/fs/cgroup/cpu/cgroup.procs" to "0" D1125 08:55:23.614046 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/cpuacct" D1125 08:55:23.614101 939884 cgroup.go:116] Setting "/sys/fs/cgroup/cpuacct/cgroup.procs" to "0" D1125 08:55:23.614214 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/hugetlb" D1125 08:55:23.614251 939884 cgroup.go:116] Setting "/sys/fs/cgroup/hugetlb/cgroup.procs" to "0" D1125 08:55:23.614329 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/rdma" D1125 08:55:23.614375 939884 cgroup.go:116] Setting "/sys/fs/cgroup/rdma/cgroup.procs" to "0" D1125 08:55:23.614619 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/pids" D1125 08:55:23.614694 939884 cgroup.go:116] Setting "/sys/fs/cgroup/pids/cgroup.procs" to "0" D1125 08:55:23.614830 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/memory" D1125 08:55:23.614886 939884 cgroup.go:116] Setting "/sys/fs/cgroup/memory/cgroup.procs" to "0" D1125 08:55:23.614943 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/blkio" D1125 08:55:23.615017 939884 cgroup.go:116] Setting "/sys/fs/cgroup/blkio/cgroup.procs" to "0" D1125 08:55:23.615094 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/systemd" D1125 08:55:23.615135 939884 cgroup.go:116] Setting "/sys/fs/cgroup/systemd/cgroup.procs" to "0" D1125 08:55:23.615263 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/perf_event" D1125 08:55:23.615326 939884 cgroup.go:116] Setting "/sys/fs/cgroup/perf_event/cgroup.procs" to "0" D1125 08:55:23.615642 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/devices" D1125 08:55:23.615704 939884 cgroup.go:116] Setting "/sys/fs/cgroup/devices/cgroup.procs" to "0" D1125 08:55:23.615859 939884 cgroup.go:602] Restoring cgroup "/sys/fs/cgroup/freezer" D1125 08:55:23.615922 939884 cgroup.go:116] Setting "/sys/fs/cgroup/freezer/cgroup.procs" to "0" D1125 08:55:23.616069 939884 container.go:1073] Save container, cid: ci-gvisor-ptrace-3-race-cover-test-0 D1125 08:55:23.618379 939884 container.go:422] Start container, cid: ci-gvisor-ptrace-3-race-cover-test-0 D1125 08:55:23.618467 939884 sandbox.go:385] Start root sandbox "ci-gvisor-ptrace-3-race-cover-test-0", PID: 939969 D1125 08:55:23.618537 939884 sandbox.go:613] Connecting to sandbox "ci-gvisor-ptrace-3-race-cover-test-0" I1125 08:55:23.618706 939884 network.go:59] Setting up network D1125 08:55:23.618837 939884 urpc.go:568] urpc: successfully marshalled 84 bytes. D1125 08:55:23.619870 939969 urpc.go:611] urpc: unmarshal success. D1125 08:55:23.620072 939969 controller.go:221] containerManager.StartRoot, cid: ci-gvisor-ptrace-3-race-cover-test-0 D1125 08:55:23.620183 939969 loader.go:723] Configuring host network W1125 08:55:23.620748 939969 extra_filters_race.go:28] TSAN is enabled: syscall filters less restrictive! W1125 08:55:23.620877 939969 filter.go:33] *** SECCOMP WARNING: host networking (with raw sockets) enabled: syscall filters less restrictive! W1125 08:55:23.620927 939969 filter.go:33] *** SECCOMP WARNING: host filesystem enabled: syscall filters less restrictive! W1125 08:55:23.620986 939969 filter.go:33] *** SECCOMP WARNING: instrumentation enabled: syscall filters less restrictive! W1125 08:55:23.621085 939969 extra_filters_race.go:28] TSAN is enabled: syscall filters less restrictive! I1125 08:55:23.621309 939969 filter.go:46] No precompiled program found for config options Platform="ptrace" HostNetwork=true HostNetworkRawSockets=true HostFilesystem=true ProfileEnable=false Instrumentation=true NVProxy=false TPUProxy=false, building seccomp program from scratch. This may slow down container startup. W1125 08:55:23.621530 939969 extra_filters_race.go:28] TSAN is enabled: syscall filters less restrictive! I1125 08:55:23.621913 939969 seccomp.go:63] Installing seccomp filters for 99 syscalls (action=kill process) I1125 08:55:24.608773 939969 calibrated_clock.go:74] CalibratedClock(Monotonic): ready I1125 08:55:24.609082 939969 calibrated_clock.go:74] CalibratedClock(Realtime): ready D1125 08:55:24.667007 939969 seccomp.go:672] Ordered seccomp rules: D1125 08:55:24.667080 939969 seccomp.go:685] Hot non-trivial syscalls: D1125 08:55:24.667147 939969 seccomp.go:692] - sysno=307: {((arg0 == * && arg1 == * && arg2 == * && arg3 == 0x40)) => allow} D1125 08:55:24.667199 939969 seccomp.go:685] Cold non-trivial syscalls: D1125 08:55:24.667278 939969 seccomp.go:692] - sysno=9: {((arg0 == * && arg1 == * && arg2 & 0x4 == 0x4)) => kill process, (true) => allow} D1125 08:55:24.667344 939969 seccomp.go:692] - sysno=10: {((arg0 == * && arg1 == * && arg2 & 0x4 == 0x4)) => kill process, (true) => allow} D1125 08:55:24.667588 939969 seccomp.go:692] - sysno=16: {(((arg0 NonNegativeFD && arg1 == 0x541b) || (arg0 NonNegativeFD && arg1 == 0x5401) || (arg0 NonNegativeFD && arg1 == 0x5402) || (arg0 NonNegativeFD && arg1 == 0x5404) || (arg0 NonNegativeFD && arg1 == 0x5403) || (arg0 NonNegativeFD && arg1 == 0x5414) || (arg0 NonNegativeFD && arg1 == 0x5413) || (arg0 NonNegativeFD && arg1 == 0x8942) || (arg0 NonNegativeFD && arg1 == 0x8912) || (arg0 NonNegativeFD && arg1 == 0x8946) || (arg0 NonNegativeFD && arg1 == 0x8913) || (arg0 NonNegativeFD && arg1 == 0x8927) || (arg0 NonNegativeFD && arg1 == 0x8933) || (arg0 NonNegativeFD && arg1 == 0x8921) || (arg0 NonNegativeFD && arg1 == 0x8910) || (arg0 NonNegativeFD && arg1 == 0x891b) || (arg0 NonNegativeFD && arg1 == 0x5411))) => allow} D1125 08:55:24.668026 939969 seccomp.go:692] - sysno=41: {(((arg0 == 0x10 && arg1 == 0x80003 && arg2 == 0x0) || (arg0 == 0x2 && arg1 == 0x1 && arg2 == 0x0) || (arg0 == 0xa && arg1 == 0x1 && arg2 == 0x0) || (arg0 == 0x2 && arg1 == 0x80801 && arg2 == 0x6) || (arg0 == 0x2 && arg1 == 0x80802 && arg2 == 0x11) || (arg0 == 0x2 && arg1 == 0x80802 && arg2 == 0x1) || (arg0 == 0xa && arg1 == 0x80801 && arg2 == 0x6) || (arg0 == 0xa && arg1 == 0x80802 && arg2 == 0x11) || (arg0 == 0xa && arg1 == 0x80802 && arg2 == 0x3a) || (arg0 == 0x2 && arg1 == 0x80803 && arg2 == 0xff) || (arg0 == 0x2 && arg1 == 0x80803 && arg2 == 0x6) || (arg0 == 0x2 && arg1 == 0x80803 && arg2 == 0x11) || (arg0 == 0x2 && arg1 == 0x80803 && arg2 == 0x1) || (arg0 == 0xa && arg1 == 0x80803 && arg2 == 0xff) || (arg0 == 0xa && arg1 == 0x80803 && arg2 == 0x6) || (arg0 == 0xa && arg1 == 0x80803 && arg2 == 0x11) || (arg0 == 0xa && arg1 == 0x80803 && arg2 == 0x3a) || (arg0 == 0x11 && arg1 == 0x80802) || (arg0 == 0x11 && arg1 == 0x80803))) => allow} D1125 08:55:24.668122 939969 seccomp.go:692] - sysno=48: {(((arg0 == * && arg1 == 0x0) || (arg0 == * && arg1 == 0x1) || (arg0 == * && arg1 == 0x2))) => allow} D1125 08:55:24.669185 939969 seccomp.go:692] - sysno=54: {(((arg0 == * && arg1 == 0x0 && arg2 == 0x23) || (arg0 == * && arg1 == 0x0 && arg2 == 0x24) || (arg0 == * && arg1 == 0x0 && arg2 == 0x3 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x0 && arg2 == 0x20 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x0 && arg2 == 0x22) || (arg0 == * && arg1 == 0x0 && arg2 == 0x21) || (arg0 == * && arg1 == 0x0 && arg2 == 0x8 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x0 && arg2 == 0xb && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x0 && arg2 == 0x14 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x0 && arg2 == 0xd && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x0 && arg2 == 0xc && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x0 && arg2 == 0x1) || (arg0 == * && arg1 == 0x0 && arg2 == 0x2 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x7 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x12 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x19 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x33 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x4a && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x31 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x42 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x43 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x10 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x29 && arg2 == 0x1a && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x1e && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x19) || (arg0 == * && arg1 == 0x1 && arg2 == 0x6 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x9 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0xd && arg3 == * && arg4 == 0x8) || (arg0 == * && arg1 == 0x1 && arg2 == 0xb && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0xa && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x10 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x8 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x21 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x12 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x2 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0xf && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x7 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x1d && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0xd) || (arg0 == * && arg1 == 0x6 && arg2 == 0x3 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x9 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x24 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x6 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x4 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x5 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x8 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x2 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x1 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0xc && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x7 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x12 && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0xa && arg3 == * && arg4 == 0x4) || (arg0 == * && arg1 == 0x3a && arg2 == 0x1 && arg3 == * && arg4 == 0x20))) => allow} D1125 08:55:24.670028 939969 seccomp.go:692] - sysno=55: {(((arg0 == * && arg1 == 0x1 && arg2 == 0x27) || (arg0 == * && arg1 == 0x1 && arg2 == 0x3) || (arg0 == * && arg1 == 0x1 && arg2 == 0x4) || (arg0 == * && arg1 == 0x1 && arg2 == 0x7) || (arg0 == * && arg1 == 0x1 && arg2 == 0x11) || (arg0 == * && arg1 == 0x0 && arg2 == 0x3) || (arg0 == * && arg1 == 0x0 && arg2 == 0x20) || (arg0 == * && arg1 == 0x0 && arg2 == 0x22) || (arg0 == * && arg1 == 0x0 && arg2 == 0x21) || (arg0 == * && arg1 == 0x0 && arg2 == 0x8) || (arg0 == * && arg1 == 0x0 && arg2 == 0xb) || (arg0 == * && arg1 == 0x0 && arg2 == 0x14) || (arg0 == * && arg1 == 0x0 && arg2 == 0xd) || (arg0 == * && arg1 == 0x0 && arg2 == 0xc) || (arg0 == * && arg1 == 0x0 && arg2 == 0x1) || (arg0 == * && arg1 == 0x0 && arg2 == 0x2) || (arg0 == * && arg1 == 0x29 && arg2 == 0x7) || (arg0 == * && arg1 == 0x29 && arg2 == 0x12) || (arg0 == * && arg1 == 0x29 && arg2 == 0x19) || (arg0 == * && arg1 == 0x29 && arg2 == 0x33) || (arg0 == * && arg1 == 0x29 && arg2 == 0x4a) || (arg0 == * && arg1 == 0x29 && arg2 == 0x31) || (arg0 == * && arg1 == 0x29 && arg2 == 0x42) || (arg0 == * && arg1 == 0x29 && arg2 == 0x43) || (arg0 == * && arg1 == 0x29 && arg2 == 0x10) || (arg0 == * && arg1 == 0x29 && arg2 == 0x1a) || (arg0 == * && arg1 == 0x1 && arg2 == 0x1e) || (arg0 == * && arg1 == 0x1 && arg2 == 0x19) || (arg0 == * && arg1 == 0x1 && arg2 == 0x6) || (arg0 == * && arg1 == 0x1 && arg2 == 0x9) || (arg0 == * && arg1 == 0x1 && arg2 == 0xd) || (arg0 == * && arg1 == 0x1 && arg2 == 0xb) || (arg0 == * && arg1 == 0x1 && arg2 == 0xa) || (arg0 == * && arg1 == 0x1 && arg2 == 0x10) || (arg0 == * && arg1 == 0x1 && arg2 == 0x8) || (arg0 == * && arg1 == 0x1 && arg2 == 0x12) || (arg0 == * && arg1 == 0x1 && arg2 == 0x2) || (arg0 == * && arg1 == 0x1 && arg2 == 0xf) || (arg0 == * && arg1 == 0x1 && arg2 == 0x1d) || (arg0 == * && arg1 == 0x6 && arg2 == 0xd) || (arg0 == * && arg1 == 0x6 && arg2 == 0x3) || (arg0 == * && arg1 == 0x6 && arg2 == 0x9) || (arg0 == * && arg1 == 0x6 && arg2 == 0xb) || (arg0 == * && arg1 == 0x6 && arg2 == 0x24) || (arg0 == * && arg1 == 0x6 && arg2 == 0x6) || (arg0 == * && arg1 == 0x6 && arg2 == 0x4) || (arg0 == * && arg1 == 0x6 && arg2 == 0x5) || (arg0 == * && arg1 == 0x6 && arg2 == 0x8) || (arg0 == * && arg1 == 0x6 && arg2 == 0x2) || (arg0 == * && arg1 == 0x6 && arg2 == 0x1) || (arg0 == * && arg1 == 0x6 && arg2 == 0xc) || (arg0 == * && arg1 == 0x6 && arg2 == 0x7) || (arg0 == * && arg1 == 0x6 && arg2 == 0x12) || (arg0 == * && arg1 == 0x6 && arg2 == 0xa) || (arg0 == * && arg1 == 0x3a && arg2 == 0x1))) => allow} D1125 08:55:24.670109 939969 seccomp.go:692] - sysno=72: {(((arg0 == * && arg1 == 0x3) || (arg0 == * && arg1 == 0x4) || (arg0 == * && arg1 == 0x1))) => allow} D1125 08:55:24.670157 939969 seccomp.go:692] - sysno=138: {(arg0 NonNegativeFD) => allow} D1125 08:55:24.670182 939969 seccomp.go:692] - sysno=217: {(arg0 NonNegativeFD) => allow} D1125 08:55:24.670219 939969 seccomp.go:692] - sysno=222: {(arg0 == 0x3) => allow} D1125 08:55:24.670289 939969 seccomp.go:692] - sysno=223: {((arg0 == * && arg1 == 0x0 && arg2 == * && arg3 == 0x0)) => allow} D1125 08:55:24.670356 939969 seccomp.go:692] - sysno=258: {(arg0 NonNegativeFD) => allow} D1125 08:55:24.670390 939969 seccomp.go:692] - sysno=259: {(arg0 NonNegativeFD) => allow} D1125 08:55:24.670445 939969 seccomp.go:692] - sysno=260: {((arg0 NonNegativeFD && arg1 == * && arg2 == * && arg3 == * && arg4 == 0x1100)) => allow} D1125 08:55:24.670491 939969 seccomp.go:692] - sysno=262: {(arg0 NonNegativeFD) => allow} D1125 08:55:24.670541 939969 seccomp.go:692] - sysno=263: {(arg0 NonNegativeFD) => allow} D1125 08:55:24.670594 939969 seccomp.go:692] - sysno=264: {((arg0 NonNegativeFD && arg1 == * && arg2 NonNegativeFD)) => allow} D1125 08:55:24.670644 939969 seccomp.go:692] - sysno=265: {((arg0 NonNegativeFD && arg1 == * && arg2 NonNegativeFD && arg3 == * && arg4 == 0x0)) => allow} D1125 08:55:24.670675 939969 seccomp.go:692] - sysno=266: {((arg0 == * && arg1 NonNegativeFD)) => allow} D1125 08:55:24.670734 939969 seccomp.go:692] - sysno=267: {(arg0 NonNegativeFD) => allow} D1125 08:55:24.670780 939969 seccomp.go:692] - sysno=268: {(arg0 NonNegativeFD) => allow} D1125 08:55:24.670882 939969 seccomp.go:692] - sysno=276: {((arg0 == * && arg1 == * && arg2 == 0x1 && arg3 == 0x2)) => allow} D1125 08:55:24.670998 939969 seccomp.go:692] - sysno=280: {(((arg0 == * && arg1 == 0x0 && arg2 == * && arg3 == 0x0) || arg0 NonNegativeFD)) => allow} D1125 08:55:24.671109 939969 seccomp.go:692] - sysno=281: {((arg0 == * && arg1 == * && arg2 == * && arg3 == * && arg4 == 0x0)) => allow} D1125 08:55:24.671188 939969 seccomp.go:692] - sysno=288: {((arg0 == 0x8 || (arg0 == * && arg1 == * && arg2 == * && arg3 == 0x80800))) => allow} D1125 08:55:24.671263 939969 seccomp.go:692] - sysno=290: {((arg0 == 0x0 && arg1 == 0x0)) => allow} D1125 08:55:24.671337 939969 seccomp.go:692] - sysno=292: {((arg0 == * && arg1 == * && arg2 == 0x80000)) => allow} D1125 08:55:24.671444 939969 seccomp.go:692] - sysno=299: {((arg0 == * && arg1 == * && arg2 == 0x8 && arg3 == 0x40 && arg4 == 0x0)) => allow} D1125 08:55:24.671515 939969 seccomp.go:692] - sysno=309: {((arg0 == * && arg1 == 0x0 && arg2 == 0x0)) => allow} D1125 08:55:24.671641 939969 seccomp.go:692] - sysno=324: {((arg0 == 0x1 && arg1 == 0x0)) => allow} D1125 08:55:24.671693 939969 seccomp.go:685] Trivial syscalls: D1125 08:55:24.671784 939969 seccomp.go:692] - sysno=0: {(true) => allow} D1125 08:55:24.671832 939969 seccomp.go:692] - sysno=1: {(true) => allow} D1125 08:55:24.671860 939969 seccomp.go:692] - sysno=2: {(true) => allow} D1125 08:55:24.671889 939969 seccomp.go:692] - sysno=3: {(true) => allow} D1125 08:55:24.671921 939969 seccomp.go:692] - sysno=5: {(true) => allow} D1125 08:55:24.671951 939969 seccomp.go:692] - sysno=8: {(true) => allow} D1125 08:55:24.671971 939969 seccomp.go:692] - sysno=11: {(true) => allow} D1125 08:55:24.672020 939969 seccomp.go:692] - sysno=12: {(true) => allow} D1125 08:55:24.672049 939969 seccomp.go:692] - sysno=13: {(true) => allow} D1125 08:55:24.672068 939969 seccomp.go:692] - sysno=14: {(true) => allow} D1125 08:55:24.672102 939969 seccomp.go:692] - sysno=15: {(true) => allow} D1125 08:55:24.672129 939969 seccomp.go:692] - sysno=17: {(true) => allow} D1125 08:55:24.672165 939969 seccomp.go:692] - sysno=18: {(true) => allow} D1125 08:55:24.672185 939969 seccomp.go:692] - sysno=19: {(true) => allow} D1125 08:55:24.672212 939969 seccomp.go:692] - sysno=20: {(true) => allow} D1125 08:55:24.672231 939969 seccomp.go:692] - sysno=24: {(true) => allow} D1125 08:55:24.672267 939969 seccomp.go:692] - sysno=27: {(true) => allow} D1125 08:55:24.672293 939969 seccomp.go:692] - sysno=28: {(true) => allow} D1125 08:55:24.672319 939969 seccomp.go:692] - sysno=32: {(true) => allow} D1125 08:55:24.672346 939969 seccomp.go:692] - sysno=35: {(true) => allow} D1125 08:55:24.672373 939969 seccomp.go:692] - sysno=38: {(true) => allow} D1125 08:55:24.672392 939969 seccomp.go:692] - sysno=39: {(true) => allow} D1125 08:55:24.672424 939969 seccomp.go:692] - sysno=42: {(true) => allow} D1125 08:55:24.672467 939969 seccomp.go:692] - sysno=44: {(true) => allow} D1125 08:55:24.672495 939969 seccomp.go:692] - sysno=45: {(true) => allow} D1125 08:55:24.672520 939969 seccomp.go:692] - sysno=46: {(true) => allow} D1125 08:55:24.672541 939969 seccomp.go:692] - sysno=47: {(true) => allow} D1125 08:55:24.672585 939969 seccomp.go:692] - sysno=49: {(true) => allow} D1125 08:55:24.672604 939969 seccomp.go:692] - sysno=50: {(true) => allow} D1125 08:55:24.672631 939969 seccomp.go:692] - sysno=51: {(true) => allow} D1125 08:55:24.672657 939969 seccomp.go:692] - sysno=52: {(true) => allow} D1125 08:55:24.672693 939969 seccomp.go:692] - sysno=56: {(true) => allow} D1125 08:55:24.672720 939969 seccomp.go:692] - sysno=60: {(true) => allow} D1125 08:55:24.672748 939969 seccomp.go:692] - sysno=61: {(true) => allow} D1125 08:55:24.672767 939969 seccomp.go:692] - sysno=74: {(true) => allow} D1125 08:55:24.672787 939969 seccomp.go:692] - sysno=77: {(true) => allow} D1125 08:55:24.672853 939969 seccomp.go:692] - sysno=91: {(true) => allow} D1125 08:55:24.672895 939969 seccomp.go:692] - sysno=96: {(true) => allow} D1125 08:55:24.672974 939969 seccomp.go:692] - sysno=101: {(true) => allow} D1125 08:55:24.673046 939969 seccomp.go:692] - sysno=131: {(true) => allow} D1125 08:55:24.673094 939969 seccomp.go:692] - sysno=149: {(true) => allow} D1125 08:55:24.673126 939969 seccomp.go:692] - sysno=150: {(true) => allow} D1125 08:55:24.673173 939969 seccomp.go:692] - sysno=186: {(true) => allow} D1125 08:55:24.673234 939969 seccomp.go:692] - sysno=201: {(true) => allow} D1125 08:55:24.673300 939969 seccomp.go:692] - sysno=202: {(true) => allow} D1125 08:55:24.673359 939969 seccomp.go:692] - sysno=204: {(true) => allow} D1125 08:55:24.673426 939969 seccomp.go:692] - sysno=219: {(true) => allow} D1125 08:55:24.673461 939969 seccomp.go:692] - sysno=226: {(true) => allow} D1125 08:55:24.673491 939969 seccomp.go:692] - sysno=228: {(true) => allow} D1125 08:55:24.673536 939969 seccomp.go:692] - sysno=230: {(true) => allow} D1125 08:55:24.673575 939969 seccomp.go:692] - sysno=231: {(true) => allow} D1125 08:55:24.673595 939969 seccomp.go:692] - sysno=233: {(true) => allow} D1125 08:55:24.673620 939969 seccomp.go:692] - sysno=234: {(true) => allow} D1125 08:55:24.673658 939969 seccomp.go:692] - sysno=257: {(true) => allow} D1125 08:55:24.673746 939969 seccomp.go:692] - sysno=271: {(true) => allow} D1125 08:55:24.673786 939969 seccomp.go:692] - sysno=273: {(true) => allow} D1125 08:55:24.673814 939969 seccomp.go:692] - sysno=277: {(true) => allow} D1125 08:55:24.673842 939969 seccomp.go:692] - sysno=285: {(true) => allow} D1125 08:55:24.673880 939969 seccomp.go:692] - sysno=291: {(true) => allow} D1125 08:55:24.673954 939969 seccomp.go:692] - sysno=295: {(true) => allow} D1125 08:55:24.674041 939969 seccomp.go:692] - sysno=296: {(true) => allow} D1125 08:55:24.674083 939969 seccomp.go:692] - sysno=318: {(true) => allow} D1125 08:55:24.674133 939969 seccomp.go:692] - sysno=327: {(true) => allow} D1125 08:55:24.674273 939969 seccomp.go:692] - sysno=328: {(true) => allow} D1125 08:55:24.674323 939969 seccomp.go:692] - sysno=332: {(true) => allow} D1125 08:55:24.674345 939969 seccomp.go:692] - sysno=334: {(true) => allow} D1125 08:55:24.674375 939969 seccomp.go:692] - sysno=435: {(true) => allow} D1125 08:55:24.674414 939969 seccomp.go:695] End of ordered seccomp rules. D1125 08:55:26.609998 939969 sampler.go:191] Time: Adjusting syscall overhead down to 875 D1125 08:55:27.609511 939969 sampler.go:191] Time: Adjusting syscall overhead down to 766 D1125 08:55:31.609385 939969 sampler.go:191] Time: Adjusting syscall overhead down to 875 D1125 08:55:31.771634 1 seccomp.go:412] Seccomp program optimized from 757 to 186 instructions; took 286.897738ms to build and 8.183762398s to optimize D1125 08:55:31.777758 1 seccomp.go:80] Seccomp program dump: 0: A <- P[4:4] 1: pc += (A == 3221225534) ? 0 [2] : 183 [185] 2: A <- P[0:4] 3: pc += (A >= 72) ? 0 [4] : 17 [21] 4: pc += (A > 72) ? 69 [74] : 0 [5] 5: A <- P[24:4] 6: pc += (A == 3) ? 0 [7] : 2 [9] 7: A <- P[28:4] 8: pc += (A == 0) ? 175 [184] : 0 [9] 9: A <- P[24:4] 10: pc += (A == 4) ? 0 [11] : 2 [13] 11: A <- P[28:4] 12: pc += (A == 0) ? 171 [184] : 0 [13] 13: A <- P[24:4] 14: pc += (A == 1) ? 0 [15] : 2 [17] 15: A <- P[28:4] 16: pc += (A == 0) ? 167 [184] : 0 [17] 17: A <- P[24:4] 18: pc += (A == 1033) ? 0 [19] : 166 [185] 19: A <- P[28:4] 20: pc += (A == 0) ? 163 [184] : 164 [185] 21: pc += (A >= 47) ? 0 [22] : 9 [31] 22: pc += (A > 47) ? 32 [55] : 0 [23] 23: A <- P[32:4] 24: pc += (A == 96) ? 0 [25] : 2 [27] 25: A <- P[36:4] 26: pc += (A == 0) ? 157 [184] : 0 [27] 27: A <- P[32:4] 28: pc += (A == 98) ? 0 [29] : 156 [185] 29: A <- P[36:4] 30: pc += (A == 0) ? 153 [184] : 154 [185] 31: pc += (A >= 10) ? 0 [32] : 7 [39] 32: pc += (A > 10) ? 13 [46] : 0 [33] 33: A <- P[32:4] 34: A <- A & 4 35: pc += (A == 4) ? 0 [36] : 148 [184] 36: A <- P[36:4] 37: A <- A & 0 38: pc += (A == 0) ? 146 [185] : 145 [184] 39: pc += (A == 9) ? 0 [40] : 70 [110] 40: A <- P[32:4] 41: A <- A & 4 42: pc += (A == 4) ? 0 [43] : 141 [184] 43: A <- P[36:4] 44: A <- A & 0 45: pc += (A == 0) ? 139 [185] : 138 [184] 46: pc += (A == 46) ? 0 [47] : 63 [110] 47: A <- P[32:4] 48: pc += (A == 0) ? 0 [49] : 2 [51] 49: A <- P[36:4] 50: pc += (A == 0) ? 133 [184] : 0 [51] 51: A <- P[32:4] 52: pc += (A == 16448) ? 0 [53] : 132 [185] 53: A <- P[36:4] 54: pc += (A == 0) ? 129 [184] : 130 [185] 55: pc += (A >= 53) ? 0 [56] : 13 [69] 56: pc += (A == 53) ? 0 [57] : 53 [110] 57: A <- P[16:4] 58: pc += (A == 1) ? 0 [59] : 126 [185] 59: A <- P[20:4] 60: pc += (A == 0) ? 0 [61] : 124 [185] 61: A <- P[24:4] 62: pc += (A == 524293) ? 0 [63] : 122 [185] 63: A <- P[28:4] 64: pc += (A == 0) ? 0 [65] : 120 [185] 65: A <- P[32:4] 66: pc += (A == 0) ? 0 [67] : 118 [185] 67: A <- P[36:4] 68: pc += (A == 0) ? 115 [184] : 116 [185] 69: pc += (A == 48) ? 0 [70] : 40 [110] 70: A <- P[24:4] 71: pc += (A == 2) ? 0 [72] : 113 [185] 72: A <- P[28:4] 73: pc += (A == 0) ? 110 [184] : 111 [185] 74: pc += (A >= 285) ? 0 [75] : 5 [80] 75: pc += (A > 285) ? 15 [91] : 0 [76] 76: A <- P[24:4] 77: pc += (A == 0) ? 0 [78] : 107 [185] 78: A <- P[28:4] 79: pc += (A == 0) ? 104 [184] : 105 [185] 80: pc += (A >= 281) ? 0 [81] : 5 [86] 81: pc += (A == 281) ? 0 [82] : 28 [110] 82: A <- P[48:4] 83: pc += (A == 0) ? 0 [84] : 101 [185] 84: A <- P[52:4] 85: pc += (A == 0) ? 98 [184] : 99 [185] 86: pc += (A == 234) ? 0 [87] : 23 [110] 87: A <- P[16:4] 88: pc += (A == 1) ? 0 [89] : 96 [185] 89: A <- P[20:4] 90: pc += (A == 0) ? 93 [184] : 94 [185] 91: pc += (A >= 309) ? 0 [92] : 9 [101] 92: pc += (A == 309) ? 0 [93] : 17 [110] 93: A <- P[24:4] 94: pc += (A == 0) ? 0 [95] : 90 [185] 95: A <- P[28:4] 96: pc += (A == 0) ? 0 [97] : 88 [185] 97: A <- P[32:4] 98: pc += (A == 0) ? 0 [99] : 86 [185] 99: A <- P[36:4] 100: pc += (A == 0) ? 83 [184] : 84 [185] 101: pc += (A == 290) ? 0 [102] : 8 [110] 102: A <- P[16:4] 103: pc += (A == 0) ? 0 [104] : 81 [185] 104: A <- P[20:4] 105: pc += (A == 0) ? 0 [106] : 79 [185] 106: A <- P[24:4] 107: pc += (A == 0) ? 0 [108] : 77 [185] 108: A <- P[28:4] 109: pc += (A == 0) ? 74 [184] : 75 [185] 110: pc += (A >= 186) ? 0 [111] : 1 [112] 111: pc += (A > 186) ? 36 [148] : 72 [184] 112: pc += (A >= 28) ? 0 [113] : 1 [114] 113: pc += (A > 28) ? 14 [128] : 70 [184] 114: pc += (A >= 12) ? 0 [115] : 1 [116] 115: pc += (A > 12) ? 4 [120] : 68 [184] 116: pc += (A >= 3) ? 0 [117] : 67 [184] 117: pc += (A > 3) ? 0 [118] : 66 [184] 118: pc += (A >= 8) ? 65 [184] : 0 [119] 119: pc += (A == 5) ? 64 [184] : 65 [185] 120: pc += (A >= 17) ? 0 [121] : 1 [122] 121: pc += (A > 17) ? 3 [125] : 62 [184] 122: pc += (A >= 14) ? 0 [123] : 61 [184] 123: pc += (A > 14) ? 0 [124] : 60 [184] 124: pc += (A == 15) ? 59 [184] : 60 [185] 125: pc += (A >= 24) ? 0 [126] : 1 [127] 126: pc += (A == 24) ? 57 [184] : 58 [185] 127: pc += (A == 18) ? 56 [184] : 57 [185] 128: pc += (A >= 74) ? 0 [129] : 1 [130] 129: pc += (A > 74) ? 9 [139] : 54 [184] 130: pc += (A >= 43) ? 0 [131] : 1 [132] 131: pc += (A > 43) ? 4 [136] : 52 [184] 132: pc += (A >= 35) ? 0 [133] : 1 [134] 133: pc += (A > 35) ? 1 [135] : 50 [184] 134: pc += (A == 32) ? 49 [184] : 50 [185] 135: pc += (A == 39) ? 48 [184] : 49 [185] 136: pc += (A >= 60) ? 0 [137] : 1 [138] 137: pc += (A == 60) ? 46 [184] : 47 [185] 138: pc += (A == 56) ? 45 [184] : 46 [185] 139: pc += (A >= 131) ? 0 [140] : 1 [141] 140: pc += (A > 131) ? 4 [145] : 43 [184] 141: pc += (A >= 91) ? 0 [142] : 1 [143] 142: pc += (A > 91) ? 1 [144] : 41 [184] 143: pc += (A == 77) ? 40 [184] : 41 [185] 144: pc += (A == 96) ? 39 [184] : 40 [185] 145: pc += (A >= 150) ? 0 [146] : 1 [147] 146: pc += (A == 150) ? 37 [184] : 38 [185] 147: pc += (A == 138) ? 36 [184] : 37 [185] 148: pc += (A >= 262) ? 0 [149] : 1 [150] 149: pc += (A > 262) ? 19 [169] : 34 [184] 150: pc += (A >= 230) ? 0 [151] : 1 [152] 151: pc += (A > 230) ? 9 [161] : 32 [184] 152: pc += (A >= 217) ? 0 [153] : 1 [154] 153: pc += (A > 217) ? 4 [158] : 30 [184] 154: pc += (A >= 202) ? 0 [155] : 1 [156] 155: pc += (A > 202) ? 1 [157] : 28 [184] 156: pc += (A == 201) ? 27 [184] : 28 [185] 157: pc += (A == 204) ? 26 [184] : 27 [185] 158: pc += (A >= 228) ? 0 [159] : 1 [160] 159: pc += (A == 228) ? 24 [184] : 25 [185] 160: pc += (A == 219) ? 23 [184] : 24 [185] 161: pc += (A >= 258) ? 0 [162] : 1 [163] 162: pc += (A > 258) ? 4 [167] : 21 [184] 163: pc += (A >= 233) ? 0 [164] : 1 [165] 164: pc += (A > 233) ? 1 [166] : 19 [184] 165: pc += (A == 231) ? 18 [184] : 19 [185] 166: pc += (A == 257) ? 17 [184] : 18 [185] 167: pc += (A >= 260) ? 0 [168] : 16 [184] 168: pc += (A == 260) ? 15 [184] : 16 [185] 169: pc += (A >= 271) ? 0 [170] : 1 [171] 170: pc += (A > 271) ? 4 [175] : 13 [184] 171: pc += (A >= 266) ? 0 [172] : 12 [184] 172: pc += (A > 266) ? 0 [173] : 11 [184] 173: pc += (A >= 268) ? 0 [174] : 10 [184] 174: pc += (A == 268) ? 9 [184] : 10 [185] 175: pc += (A >= 319) ? 0 [176] : 1 [177] 176: pc += (A > 319) ? 4 [181] : 7 [184] 177: pc += (A >= 280) ? 0 [178] : 1 [179] 178: pc += (A > 280) ? 1 [180] : 5 [184] 179: pc += (A == 273) ? 4 [184] : 5 [185] 180: pc += (A == 318) ? 3 [184] : 4 [185] 181: pc += (A >= 435) ? 0 [182] : 1 [183] 182: pc += (A == 435) ? 1 [184] : 2 [185] 183: pc += (A == 334) ? 0 [184] : 1 [185] 184: ret 2147418112 185: ret 2147483648 I1125 08:55:31.779378 1 seccomp.go:91] Seccomp filters installed. I1125 08:55:31.779984 1 gofer.go:282] Serving "/" mapped to "/syzkaller/managers/ci-gvisor-ptrace-3-race-cover/latest.tmp/workdir/instance-1/image" on FD 6 (ro: true) D1125 08:55:49.609015 939969 sampler.go:191] Time: Adjusting syscall overhead down to 766 D1125 08:55:49.609332 939969 sampler.go:191] Time: Adjusting syscall overhead down to 671 W1125 08:55:53.613851 939969 log.go:359] Watchdog.Start() not called within 30s: goroutine 119 [running]: gvisor.dev/gvisor/pkg/log.Stacks(0x20?) pkg/log/log.go:319 +0xb3 gvisor.dev/gvisor/pkg/log.TracebackAll({0xc00079c120, 0x26}, {0x0, 0x0, 0x0}) pkg/log/log.go:358 +0x65 gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).doAction(0xc000824100, 0x0, 0x0, 0xc000306f88) pkg/sentry/watchdog/watchdog.go:360 +0x256 gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).waitForStart(0xc000824100) pkg/sentry/watchdog/watchdog.go:243 +0x265 created by gvisor.dev/gvisor/pkg/sentry/watchdog.New in goroutine 1 pkg/sentry/watchdog/watchdog.go:183 +0x3b1 goroutine 1 [runnable]: gvisor.dev/gvisor/pkg/bpf.Instruction.IsUnconditionalJump({0x20?, 0x0?, 0x0?, 0x1c?}) pkg/bpf/bpf.go:168 +0x91 gvisor.dev/gvisor/pkg/bpf.rewriteAllJumpsToReturn({0xc000ac8000, 0x7be, 0x1400}, 0x715, 0x70a) pkg/bpf/optimizer.go:336 +0x715 gvisor.dev/gvisor/pkg/bpf.optimizeJumpsToSmallestSetOfReturns({0xc000ac8000?, 0x7be, 0x1400}) pkg/bpf/optimizer.go:534 +0x5a5 gvisor.dev/gvisor/pkg/bpf.optimize({0xc000ac8000, 0x1300, 0x1400}, {0xc0003ce720, 0x7, 0x8000000000000000?}) pkg/bpf/optimizer.go:556 +0x1c9 gvisor.dev/gvisor/pkg/bpf.Optimize({0xc000ac8000, 0x1300, 0x1400}) pkg/bpf/optimizer.go:569 +0xe5 gvisor.dev/gvisor/pkg/seccomp.BuildProgram({0xc0003cea98?, 0x2, 0x2}, {0x80000000, 0x80000000, 0x1, {0xc000757710, 0x6, 0x6}}) pkg/seccomp/seccomp.go:409 +0x752 gvisor.dev/gvisor/pkg/seccomp.Install({0x725f70?}, {0xc000759740?}, {0x80000000, 0x80000000, 0x1, {0xc000757710, 0x6, 0x6}}) pkg/seccomp/seccomp.go:65 +0x279 gvisor.dev/gvisor/runsc/boot/filter.Install({{0x725f70, 0xc000759740}, 0x1, 0x1, 0x1, 0x0, 0x0, 0x0, 0x8}) runsc/boot/filter/filter.go:48 +0x89a gvisor.dev/gvisor/runsc/boot.(*Loader).installSeccompFilters(0xc000842000) runsc/boot/loader.go:697 +0x41d gvisor.dev/gvisor/runsc/boot.(*Loader).run(0xc000842000) runsc/boot/loader.go:748 +0x365 gvisor.dev/gvisor/runsc/boot.(*Loader).Run(0xc000842000) runsc/boot/loader.go:706 +0x2f gvisor.dev/gvisor/runsc/cmd.(*Boot).Execute(0xc0004f0000, {0xc0000402c0?, 0x10?}, 0xc00042ed90, {0xc000154380, 0x2, 0x12835a9?}) runsc/cmd/boot.go:492 +0x2725 github.com/google/subcommands.(*Commander).Execute(0xc000226000, {0x724d80, 0x3802740}, {0xc000154380, 0x2, 0x2}) external/com_github_google_subcommands/subcommands.go:200 +0x70f github.com/google/subcommands.Execute(...) external/com_github_google_subcommands/subcommands.go:481 gvisor.dev/gvisor/runsc/cli.Main() runsc/cli/main.go:219 +0x20de main.main() runsc/main.go:31 +0x1d goroutine 15 [chan receive, locked to thread]: gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess.func1() pkg/sentry/platform/ptrace/subprocess.go:196 +0x20f created by gvisor.dev/gvisor/pkg/sentry/platform/ptrace.newSubprocess in goroutine 1 pkg/sentry/platform/ptrace/subprocess.go:180 +0x26a goroutine 117 [sync.Cond.Wait]: sync.runtime_notifyListWait(0xc0006e66c8, 0x0) GOROOT/src/runtime/sema.go:527 +0x159 sync.(*Cond).Wait(0xc0006e66b8) GOROOT/src/sync/cond.go:70 +0x75 gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).findReclaimable(0xc0006e6000) pkg/sentry/pgalloc/pgalloc.go:1436 +0x1cd gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).runReclaim(0xc0006e6000) pkg/sentry/pgalloc/pgalloc.go:1345 +0xb2 created by gvisor.dev/gvisor/pkg/sentry/pgalloc.NewMemoryFile in goroutine 1 pkg/sentry/pgalloc/pgalloc.go:368 +0x5c5 goroutine 118 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Timekeeper).startUpdater.func1() pkg/sentry/kernel/timekeeper.go:254 +0x2ca created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Timekeeper).startUpdater in goroutine 1 pkg/sentry/kernel/timekeeper.go:224 +0x18d goroutine 120 [syscall]: syscall.Syscall6(0x1281f4c?, 0x1281d4c?, 0x12836d1?, 0xc0006e4a30?, 0x12835a9?, 0x1281f4c?, 0x1283a95?) GOROOT/src/syscall/syscall_linux.go:91 +0x3d gvisor.dev/gvisor/pkg/unet.(*Socket).wait(0xc0006e4a20, 0x0) pkg/unet/unet_unsafe.go:53 +0x17b gvisor.dev/gvisor/pkg/unet.(*ServerSocket).Accept(0xc00054c060) pkg/unet/unet.go:517 +0x24d gvisor.dev/gvisor/pkg/control/server.(*Server).serve(0xc0004e01c0) pkg/control/server/server.go:104 +0x9e gvisor.dev/gvisor/pkg/control/server.(*Server).StartServing.func1() pkg/control/server/server.go:92 +0x45 created by gvisor.dev/gvisor/pkg/control/server.(*Server).StartServing in goroutine 1 pkg/control/server/server.go:91 +0xee goroutine 108 [chan receive]: gvisor.dev/gvisor/runsc/boot.(*containerManager).StartRoot(0xc0004ea300, 0xc0006abf60, 0x16?) runsc/boot/controller.go:224 +0x11d reflect.Value.call({0xc000596780?, 0xc00054c260?, 0x282500?}, {0x4d4812, 0x4}, {0xc000745e18, 0x3, 0x1283571?}) GOROOT/src/reflect/value.go:596 +0x14a5 reflect.Value.Call({0xc000596780?, 0xc00054c260?, 0xc0006abf60?}, {0xc000745e18, 0x3, 0x3}) GOROOT/src/reflect/value.go:380 +0xb6 gvisor.dev/gvisor/pkg/urpc.(*Server).handleOne(0xc000828190, 0xc000780090) pkg/urpc/urpc.go:338 +0x6ea gvisor.dev/gvisor/pkg/urpc.(*Server).handleRegistered(0x0?, 0x1281f4c?) pkg/urpc/urpc.go:433 +0x57 gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling.func1() pkg/urpc/urpc.go:453 +0x9d created by gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling in goroutine 120 pkg/urpc/urpc.go:451 +0xf1 D1125 08:55:54.609391 939969 sampler.go:191] Time: Adjusting syscall overhead down to 588 D1125 08:55:55.609678 939969 sampler.go:191] Time: Adjusting syscall overhead down to 515