I0928 00:51:38.479909   17114 x:0] ***************************
I0928 00:51:38.480092   17114 x:0] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot run -bundle /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/instance-1/bundle ci-gvisor-ptrace-proxy-sandbox-race-test-0]
I0928 00:51:38.480204   17114 x:0] Version release-20190806.1-205-gc8bb20865d5f
I0928 00:51:38.480244   17114 x:0] PID: 17114
I0928 00:51:38.480294   17114 x:0] UID: 0, GID: 0
I0928 00:51:38.480338   17114 x:0] Configuration:
I0928 00:51:38.480360   17114 x:0] 		RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root
I0928 00:51:38.480402   17114 x:0] 		Platform: ptrace
I0928 00:51:38.480457   17114 x:0] 		FileAccess: shared, overlay: false
I0928 00:51:38.480503   17114 x:0] 		Network: sandbox, logging: false
I0928 00:51:38.480549   17114 x:0] 		Strace: false, max size: 1024, syscalls: []
I0928 00:51:38.480600   17114 x:0] ***************************
W0928 00:51:38.480634   17114 x:0] Block the TERM signal. This is only safe in tests!
D0928 00:51:38.486165   17114 x:0] Spec: &{Version: Process:0xc0002b6ea0 Root:0xc0002be960 Hostname: Mounts:[] Hooks:<nil> Annotations:map[] Linux:<nil> Solaris:<nil> Windows:<nil>}
D0928 00:51:38.486324   17114 x:0] Spec.Hooks: <nil>
D0928 00:51:38.486353   17114 x:0] Spec.Linux: <nil>
D0928 00:51:38.486381   17114 x:0] Spec.Process: &{Terminal:false ConsoleSize:<nil> User:{UID:0 GID:0 AdditionalGids:[] Username:} Args:[/init] Env:[SYZ_GVISOR_PROXY=1] Cwd:/tmp Capabilities:0xc000152380 Rlimits:[] NoNewPrivileges:false ApparmorProfile: OOMScoreAdj:<nil> SelinuxLabel:}
D0928 00:51:38.486533   17114 x:0] Spec.Root: &{Path:/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/instance-1/image Readonly:true}
D0928 00:51:38.486603   17114 x:0] Spec.Mounts: []
D0928 00:51:38.486660   17114 x:0] Run container "ci-gvisor-ptrace-proxy-sandbox-race-test-0" in root dir: /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root
D0928 00:51:38.486735   17114 x:0] Create container "ci-gvisor-ptrace-proxy-sandbox-race-test-0" in root dir: /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root
D0928 00:51:38.487131   17114 x:0] Creating new sandbox for container "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.487485   17114 x:0] Starting gofer: /proc/self/exe [--root=/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root --debug=true --log= --log-format=text --debug-log= --debug-log-format=text --file-access=shared --overlay=false --fsgofer-host-uds=false --network=sandbox --log-packets=false --platform=ptrace --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 --TESTONLY-unsafe-nonroot=true gofer --bundle /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/instance-1/bundle --spec-fd=3 --mounts-fd=4 --io-fds=5]
I0928 00:51:38.489608   17114 x:0] Gofer started, PID: 17132
I0928 00:51:38.489772   17114 x:0] Creating sandbox process with addr: runsc-sandbox.ci-gvisor-ptrace-proxy-sandbox-race-test-0
I0928 00:51:38.489874   17114 x:0] Sandbox will be started in new mount, IPC and UTS namespaces
I0928 00:51:38.489907   17114 x:0] Sandbox will be started in the current PID namespace
I0928 00:51:38.489944   17114 x:0] Sandbox will be started in new network namespace
W0928 00:51:38.489970   17114 x:0] Running sandbox in test mode as current user (uid=0 gid=0). This is only safe in tests!
W0928 00:51:38.490001   17114 x:0] Running sandbox in test mode without chroot. This is only safe in tests!
D0928 00:51:38.490030   17114 x:0] Donating FD 3: "control_server_socket"
D0928 00:51:38.490064   17114 x:0] Donating FD 4: "|0"
D0928 00:51:38.490108   17114 x:0] Donating FD 5: "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/instance-1/bundle/config.json"
D0928 00:51:38.490190   17114 x:0] Donating FD 6: "|1"
D0928 00:51:38.490234   17114 x:0] Donating FD 7: "sandbox IO FD"
D0928 00:51:38.490281   17114 x:0] Donating FD 8: "/dev/stdin"
D0928 00:51:38.490312   17114 x:0] Donating FD 9: "/dev/stdout"
D0928 00:51:38.490346   17114 x:0] Donating FD 10: "/dev/stderr"
D0928 00:51:38.490384   17114 x:0] Starting sandbox: /proc/self/exe [runsc-sandbox --root=/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root --debug=true --log= --log-format=text --debug-log= --debug-log-format=text --file-access=shared --overlay=false --fsgofer-host-uds=false --network=sandbox --log-packets=false --platform=ptrace --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 --TESTONLY-unsafe-nonroot=true --panic-signal=15 boot --bundle=/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/instance-1/bundle --controller-fd=3 --mounts-fd=4 --spec-fd=5 --start-sync-fd=6 --io-fds=7 --stdio-fds=8 --stdio-fds=9 --stdio-fds=10 ci-gvisor-ptrace-proxy-sandbox-race-test-0]
D0928 00:51:38.490541   17114 x:0] SysProcAttr: &{Chroot: Credential:<nil> 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:[]}
I0928 00:51:38.493314   17114 x:0] Sandbox started, PID: 17134
I0928 00:51:38.519416   17134 x:0] ***************************
I0928 00:51:38.519537   17134 x:0] Args: [runsc-sandbox --root=/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root --debug=true --log= --log-format=text --debug-log= --debug-log-format=text --file-access=shared --overlay=false --fsgofer-host-uds=false --network=sandbox --log-packets=false --platform=ptrace --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 --TESTONLY-unsafe-nonroot=true --panic-signal=15 boot --bundle=/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/instance-1/bundle --controller-fd=3 --mounts-fd=4 --spec-fd=5 --start-sync-fd=6 --io-fds=7 --stdio-fds=8 --stdio-fds=9 --stdio-fds=10 ci-gvisor-ptrace-proxy-sandbox-race-test-0]
I0928 00:51:38.519828   17134 x:0] Version release-20190806.1-205-gc8bb20865d5f
I0928 00:51:38.519865   17134 x:0] PID: 17134
I0928 00:51:38.519913   17134 x:0] UID: 0, GID: 0
I0928 00:51:38.519951   17134 x:0] Configuration:
I0928 00:51:38.519984   17134 x:0] 		RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root
I0928 00:51:38.520021   17134 x:0] 		Platform: ptrace
I0928 00:51:38.520048   17134 x:0] 		FileAccess: shared, overlay: false
I0928 00:51:38.520104   17134 x:0] 		Network: sandbox, logging: false
I0928 00:51:38.520152   17134 x:0] 		Strace: false, max size: 1024, syscalls: []
I0928 00:51:38.520199   17134 x:0] ***************************
W0928 00:51:38.520228   17134 x:0] Block the TERM signal. This is only safe in tests!
D0928 00:51:38.525988   17134 x:0] Spec: &{Version: Process:0xc0002ccb60 Root:0xc0002caa20 Hostname: Mounts:[] Hooks:<nil> Annotations:map[] Linux:<nil> Solaris:<nil> Windows:<nil>}
D0928 00:51:38.526140   17134 x:0] Spec.Hooks: <nil>
D0928 00:51:38.526177   17134 x:0] Spec.Linux: <nil>
D0928 00:51:38.526221   17134 x:0] Spec.Process: &{Terminal:false ConsoleSize:<nil> User:{UID:0 GID:0 AdditionalGids:[] Username:} Args:[/init] Env:[SYZ_GVISOR_PROXY=1] Cwd:/tmp Capabilities:0xc00015a380 Rlimits:[] NoNewPrivileges:false ApparmorProfile: OOMScoreAdj:<nil> SelinuxLabel:}
D0928 00:51:38.526392   17134 x:0] Spec.Root: &{Path:/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/instance-1/image Readonly:true}
D0928 00:51:38.526448   17134 x:0] Spec.Mounts: []
I0928 00:51:38.564545   17134 x:0] Platform: ptrace
D0928 00:51:38.565073   17134 x:0] syscall filter mmap: [] => 0x616c6c6f77
D0928 00:51:38.565214   17134 x:0] syscall filter munmap: [] => 0x616c6c6f77
D0928 00:51:38.565293   17134 x:0] syscall filter getpid: [] => 0x616c6c6f77
D0928 00:51:38.565371   17134 x:0] syscall filter clone: [( 0x409  ) ( 0x12f00  )] => 0x616c6c6f77
D0928 00:51:38.565633   17134 x:0] syscall filter exit: [] => 0x616c6c6f77
D0928 00:51:38.565705   17134 x:0] syscall filter wait4: [] => 0x616c6c6f77
D0928 00:51:38.565745   17134 x:0] syscall filter kill: [( * 0x13  )] => 0x616c6c6f77
D0928 00:51:38.565910   17134 x:0] syscall filter gettimeofday: [] => 0x7472617020283029
D0928 00:51:38.566035   17134 x:0] syscall filter getppid: [] => 0x616c6c6f77
D0928 00:51:38.566121   17134 x:0] syscall filter prctl: [( 0x1  0x9  )] => 0x616c6c6f77
D0928 00:51:38.566294   17134 x:0] syscall filter arch_prctl: [( 0x1012  0x0  )] => 0x616c6c6f77
D0928 00:51:38.566397   17134 x:0] syscall filter time: [] => 0x7472617020283029
D0928 00:51:38.566472   17134 x:0] syscall filter getcpu: [] => 0x7472617020283029
I0928 00:51:38.571683   17134 x:0] Latest seccomp behavior found (kernel >= 4.8 likely)
D0928 00:51:38.571919   17134 x:0] syscall filter mmap: [] => 0x616c6c6f77
D0928 00:51:38.572025   17134 x:0] syscall filter munmap: [] => 0x616c6c6f77
D0928 00:51:38.572100   17134 x:0] syscall filter getpid: [] => 0x616c6c6f77
D0928 00:51:38.572166   17134 x:0] syscall filter clone: [( 0x409  ) ( 0x12f00  )] => 0x616c6c6f77
D0928 00:51:38.572355   17134 x:0] syscall filter exit: [] => 0x616c6c6f77
D0928 00:51:38.572417   17134 x:0] syscall filter wait4: [] => 0x616c6c6f77
D0928 00:51:38.572470   17134 x:0] syscall filter kill: [( * 0x13  )] => 0x616c6c6f77
D0928 00:51:38.572626   17134 x:0] syscall filter gettimeofday: [] => 0x7472617020283029
D0928 00:51:38.572711   17134 x:0] syscall filter getppid: [] => 0x616c6c6f77
D0928 00:51:38.572789   17134 x:0] syscall filter prctl: [( 0x1  0x9  )] => 0x616c6c6f77
D0928 00:51:38.572932   17134 x:0] syscall filter arch_prctl: [( 0x1012  0x0  )] => 0x616c6c6f77
D0928 00:51:38.573077   17134 x:0] syscall filter time: [] => 0x7472617020283029
D0928 00:51:38.573147   17134 x:0] syscall filter getcpu: [] => 0x7472617020283029
D0928 00:51:38.736502   17134 x:0] Time: Adjusting syscall overhead up to 2000
D0928 00:51:38.736785   17134 x:0] Time: Adjusting syscall overhead up to 4000
D0928 00:51:38.736863   17134 x:0] Time: Adjusting syscall overhead up to 2000
D0928 00:51:38.736925   17134 x:0] Time: Adjusting syscall overhead up to 4000
D0928 00:51:38.736989   17134 x:0] Updating VDSO parameters: {monotonicReady:0 monotonicBaseCycles:0 monotonicBaseRef:0 monotonicFrequency:0 realtimeReady:0 realtimeBaseCycles:0 realtimeBaseRef:0 realtimeFrequency:0}
I0928 00:51:38.737749   17134 x:0] CPUs: 64
I0928 00:51:38.738089   17134 x:0] Setting gofer dirent cache size to 512
I0928 00:51:38.738162   17134 x:0] Packet logging disabled
W0928 00:51:38.738353   17134 x:0] Host limit is lower than recommended, resource: "RLIMIT_NOFILE", host: 1024, recommended: 1048576
I0928 00:51:38.738464   17134 x:0] Setting limit from host, resource: "RLIMIT_NOFILE" {soft: 1024, hard: 4096}
D0928 00:51:38.742539   17114 x:0] Save container "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.743960   17114 x:0] Start container "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.744198   17114 x:0] Start root sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-0", PID: 17134
D0928 00:51:38.744295   17114 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
I0928 00:51:38.744401   17114 x:0] Setting up network
I0928 00:51:38.744449   17114 x:0] Applying namespace network at path "/proc/17134/ns/net"
I0928 00:51:38.817875   17114 x:0] Skipping down interface: {Index:1 MTU:65536 Name:lo HardwareAddr: Flags:loopback}
D0928 00:51:38.818083   17114 x:0] Setting up network, config: {FilePayload:{Files:[]} LoopbackLinks:[] FDBasedLinks:[] DefaultGateway:{Route:{Destination:{IP:<nil> Mask:<nil>} Gateway:<nil>} Name:}}
D0928 00:51:38.818770   17114 x:0] urpc: successfully marshalled 178 bytes.
D0928 00:51:38.819161   17134 x:0] urpc: unmarshal success.
I0928 00:51:38.820127   17134 x:0] Setting routes []
D0928 00:51:38.820330   17134 x:0] urpc: successfully marshalled 37 bytes.
D0928 00:51:38.820546   17114 x:0] urpc: unmarshal success.
I0928 00:51:38.820606   17114 x:0] Restoring namespace network
D0928 00:51:38.820750   17114 x:0] urpc: successfully marshalled 90 bytes.
D0928 00:51:38.820866   17134 x:0] urpc: unmarshal success.
D0928 00:51:38.820965   17134 x:0] containerManager.StartRoot "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
W0928 00:51:38.821136   17134 x:0] *** SECCOMP WARNING: TSAN is enabled: syscall filters less restrictive!
I0928 00:51:38.821301   17134 x:0] Installing seccomp filters for 62 syscalls (action=trap (0))
D0928 00:51:38.821549   17134 x:0] syscall filter read: [] => 0x616c6c6f77
D0928 00:51:38.821625   17134 x:0] syscall filter write: [] => 0x616c6c6f77
D0928 00:51:38.821676   17134 x:0] syscall filter open: [] => 0x616c6c6f77
D0928 00:51:38.821729   17134 x:0] syscall filter close: [] => 0x616c6c6f77
D0928 00:51:38.821776   17134 x:0] syscall filter fstat: [] => 0x616c6c6f77
D0928 00:51:38.821825   17134 x:0] syscall filter lseek: [] => 0x616c6c6f77
D0928 00:51:38.821893   17134 x:0] syscall filter mmap: [( * * * 0x1  ) ( * * * 0x2  ) ( * * * 0x22  ) ( * * * 0x20022  ) ( * * * 0x4022  ) ( * * 0x3  0x32  ) ( )] => 0x616c6c6f77
D0928 00:51:38.822361   17134 x:0] syscall filter mprotect: [] => 0x616c6c6f77
D0928 00:51:38.822415   17134 x:0] syscall filter munmap: [] => 0x616c6c6f77
D0928 00:51:38.822460   17134 x:0] syscall filter brk: [] => 0x616c6c6f77
D0928 00:51:38.822514   17134 x:0] syscall filter rt_sigaction: [] => 0x616c6c6f77
D0928 00:51:38.822557   17134 x:0] syscall filter rt_sigprocmask: [] => 0x616c6c6f77
D0928 00:51:38.822607   17134 x:0] syscall filter rt_sigreturn: [] => 0x616c6c6f77
D0928 00:51:38.822652   17134 x:0] syscall filter ioctl: [( * 0x5401  * ) ( * 0x5402  * ) ( * 0x5404  * ) ( * 0x5403  * ) ( * 0x5414  * ) ( * 0x5413  * )] => 0x616c6c6f77
D0928 00:51:38.823055   17134 x:0] syscall filter pread64: [] => 0x616c6c6f77
D0928 00:51:38.823098   17134 x:0] syscall filter pwrite64: [] => 0x616c6c6f77
D0928 00:51:38.823131   17134 x:0] syscall filter writev: [( * * 0x2  ) ( * * 0x3  )] => 0x616c6c6f77
D0928 00:51:38.823276   17134 x:0] syscall filter sched_yield: [] => 0x616c6c6f77
D0928 00:51:38.823308   17134 x:0] syscall filter mincore: [] => 0x616c6c6f77
D0928 00:51:38.823360   17134 x:0] syscall filter madvise: [] => 0x616c6c6f77
D0928 00:51:38.823403   17134 x:0] syscall filter dup: [] => 0x616c6c6f77
D0928 00:51:38.823434   17134 x:0] syscall filter nanosleep: [( ) ( )] => 0x616c6c6f77
D0928 00:51:38.823479   17134 x:0] syscall filter setitimer: [] => 0x616c6c6f77
D0928 00:51:38.823508   17134 x:0] syscall filter getpid: [] => 0x616c6c6f77
D0928 00:51:38.823556   17134 x:0] syscall filter accept: [( 0x3  )] => 0x616c6c6f77
D0928 00:51:38.823669   17134 x:0] syscall filter sendmsg: [( * * 0x4040  )] => 0x616c6c6f77
D0928 00:51:38.823742   17134 x:0] syscall filter recvmsg: [( * * 0x60  ) ( * * 0x62  )] => 0x616c6c6f77
D0928 00:51:38.823849   17134 x:0] syscall filter shutdown: [( * 0x0  ) ( * 0x1  ) ( * 0x2  )] => 0x616c6c6f77
D0928 00:51:38.823987   17134 x:0] syscall filter listen: [( 0x3  0x10  )] => 0x616c6c6f77
D0928 00:51:38.824057   17134 x:0] syscall filter getsockopt: [( * 0x1  0x27  ) ( * 0x1  0x3  ) ( * 0x1  0x4  ) ( * 0x1  0x7  ) ( * 0x1  0x2  ) ( * 0x1  0x11  )] => 0x616c6c6f77
D0928 00:51:38.824504   17134 x:0] syscall filter clone: [( 0x50f00  ) ( )] => 0x616c6c6f77
D0928 00:51:38.824576   17134 x:0] syscall filter exit: [] => 0x616c6c6f77
D0928 00:51:38.824625   17134 x:0] syscall filter wait4: [] => 0x616c6c6f77
D0928 00:51:38.824673   17134 x:0] syscall filter fcntl: [( * 0x3  ) ( * 0x4  ) ( * 0x1  )] => 0x616c6c6f77
D0928 00:51:38.824819   17134 x:0] syscall filter fsync: [] => 0x616c6c6f77
D0928 00:51:38.824859   17134 x:0] syscall filter ftruncate: [] => 0x616c6c6f77
D0928 00:51:38.824910   17134 x:0] syscall filter fchmod: [] => 0x616c6c6f77
D0928 00:51:38.824939   17134 x:0] syscall filter gettimeofday: [] => 0x616c6c6f77
D0928 00:51:38.824967   17134 x:0] syscall filter ptrace: [] => 0x616c6c6f77
D0928 00:51:38.824996   17134 x:0] syscall filter sigaltstack: [] => 0x616c6c6f77
D0928 00:51:38.825051   17134 x:0] syscall filter munlock: [] => 0x616c6c6f77
D0928 00:51:38.825101   17134 x:0] syscall filter arch_prctl: [( 0x1003  ) ( 0x1002  )] => 0x616c6c6f77
D0928 00:51:38.825232   17134 x:0] syscall filter gettid: [] => 0x616c6c6f77
D0928 00:51:38.825282   17134 x:0] syscall filter time: [] => 0x616c6c6f77
D0928 00:51:38.825328   17134 x:0] syscall filter futex: [( * 0x80  * * ) ( * 0x81  * ) ( * 0x0  * * ) ( * 0x1  * ) ( )] => 0x616c6c6f77
D0928 00:51:38.825607   17134 x:0] syscall filter sched_setaffinity: [] => 0x616c6c6f77
D0928 00:51:38.825647   17134 x:0] syscall filter restart_syscall: [] => 0x616c6c6f77
D0928 00:51:38.825704   17134 x:0] syscall filter clock_gettime: [] => 0x616c6c6f77
D0928 00:51:38.825760   17134 x:0] syscall filter exit_group: [] => 0x616c6c6f77
D0928 00:51:38.825799   17134 x:0] syscall filter epoll_ctl: [] => 0x616c6c6f77
D0928 00:51:38.825846   17134 x:0] syscall filter tgkill: [( 0x42ee  ) ( )] => 0x616c6c6f77
D0928 00:51:38.825945   17134 x:0] syscall filter openat: [] => 0x616c6c6f77
D0928 00:51:38.825991   17134 x:0] syscall filter ppoll: [] => 0x616c6c6f77
D0928 00:51:38.826036   17134 x:0] syscall filter set_robust_list: [] => 0x616c6c6f77
D0928 00:51:38.826081   17134 x:0] syscall filter sync_file_range: [] => 0x616c6c6f77
D0928 00:51:38.826128   17134 x:0] syscall filter epoll_pwait: [( * * * * 0x0  )] => 0x616c6c6f77
D0928 00:51:38.826227   17134 x:0] syscall filter fallocate: [] => 0x616c6c6f77
D0928 00:51:38.826282   17134 x:0] syscall filter eventfd2: [( 0x0  0x0  )] => 0x616c6c6f77
D0928 00:51:38.826397   17134 x:0] syscall filter epoll_create1: [] => 0x616c6c6f77
D0928 00:51:38.826428   17134 x:0] syscall filter recvmmsg: [( * * 0x8  0x40  0x0  )] => 0x616c6c6f77
D0928 00:51:38.826552   17134 x:0] syscall filter getcpu: [] => 0x616c6c6f77
D0928 00:51:38.826587   17134 x:0] syscall filter sys_318: [] => 0x616c6c6f77
D0928 00:51:38.839224   17134 x:0] Seccomp program dump:
0: A <- P[4:4]
1: pc += (A == 3221225534) ? 1 [3] : 0 [2]
2: pc += 509 [512]
3: A <- P[0:4]
4: pc += (A == 60) ? 3 [8] : 0 [5]
5: pc += (A > 60) ? 0 [6] : 1 [7]
6: pc += 306 [313]
7: pc += 1 [9]
8: ret 2147418112
9: pc += (A == 18) ? 3 [13] : 0 [10]
10: pc += (A > 18) ? 0 [11] : 1 [12]
11: pc += 126 [138]
12: pc += 1 [14]
13: ret 2147418112
14: pc += (A == 10) ? 3 [18] : 0 [15]
15: pc += (A > 10) ? 0 [16] : 1 [17]
16: pc += 64 [81]
17: pc += 1 [19]
18: ret 2147418112
19: pc += (A == 3) ? 3 [23] : 0 [20]
20: pc += (A > 3) ? 0 [21] : 1 [22]
21: pc += 13 [35]
22: pc += 1 [24]
23: ret 2147418112
24: pc += (A == 1) ? 3 [28] : 0 [25]
25: pc += (A > 1) ? 0 [26] : 1 [27]
26: pc += 5 [32]
27: pc += 1 [29]
28: ret 2147418112
29: pc += (A == 0) ? 1 [31] : 0 [30]
30: pc += 481 [512]
31: ret 2147418112
32: pc += (A == 2) ? 1 [34] : 0 [33]
33: pc += 478 [512]
34: ret 2147418112
35: pc += (A == 8) ? 3 [39] : 0 [36]
36: pc += (A > 8) ? 0 [37] : 1 [38]
37: pc += 5 [43]
38: pc += 1 [40]
39: ret 2147418112
40: pc += (A == 5) ? 1 [42] : 0 [41]
41: pc += 470 [512]
42: ret 2147418112
43: pc += (A == 9) ? 1 [45] : 0 [44]
44: pc += 467 [512]
45: A <- P[40:4]
46: pc += (A == 1) ? 0 [47] : 3 [50]
47: A <- P[44:4]
48: pc += (A == 0) ? 0 [49] : 1 [50]
49: ret 2147418112
50: A <- P[40:4]
51: pc += (A == 2) ? 0 [52] : 3 [55]
52: A <- P[44:4]
53: pc += (A == 0) ? 0 [54] : 1 [55]
54: ret 2147418112
55: A <- P[40:4]
56: pc += (A == 34) ? 0 [57] : 3 [60]
57: A <- P[44:4]
58: pc += (A == 0) ? 0 [59] : 1 [60]
59: ret 2147418112
60: A <- P[40:4]
61: pc += (A == 131106) ? 0 [62] : 3 [65]
62: A <- P[44:4]
63: pc += (A == 0) ? 0 [64] : 1 [65]
64: ret 2147418112
65: A <- P[40:4]
66: pc += (A == 16418) ? 0 [67] : 3 [70]
67: A <- P[44:4]
68: pc += (A == 0) ? 0 [69] : 1 [70]
69: ret 2147418112
70: A <- P[32:4]
71: pc += (A == 3) ? 0 [72] : 7 [79]
72: A <- P[36:4]
73: pc += (A == 0) ? 0 [74] : 5 [79]
74: A <- P[40:4]
75: pc += (A == 50) ? 0 [76] : 3 [79]
76: A <- P[44:4]
77: pc += (A == 0) ? 0 [78] : 1 [79]
78: ret 2147418112
79: ret 2147418112
80: pc += 431 [512]
81: pc += (A == 14) ? 3 [85] : 0 [82]
82: pc += (A > 14) ? 0 [83] : 1 [84]
83: pc += 13 [97]
84: pc += 1 [86]
85: ret 2147418112
86: pc += (A == 12) ? 3 [90] : 0 [87]
87: pc += (A > 12) ? 0 [88] : 1 [89]
88: pc += 5 [94]
89: pc += 1 [91]
90: ret 2147418112
91: pc += (A == 11) ? 1 [93] : 0 [92]
92: pc += 419 [512]
93: ret 2147418112
94: pc += (A == 13) ? 1 [96] : 0 [95]
95: pc += 416 [512]
96: ret 2147418112
97: pc += (A == 16) ? 3 [101] : 0 [98]
98: pc += (A > 16) ? 0 [99] : 1 [100]
99: pc += 35 [135]
100: pc += 31 [132]
101: A <- P[24:4]
102: pc += (A == 21505) ? 0 [103] : 3 [106]
103: A <- P[28:4]
104: pc += (A == 0) ? 0 [105] : 1 [106]
105: ret 2147418112
106: A <- P[24:4]
107: pc += (A == 21506) ? 0 [108] : 3 [111]
108: A <- P[28:4]
109: pc += (A == 0) ? 0 [110] : 1 [111]
110: ret 2147418112
111: A <- P[24:4]
112: pc += (A == 21508) ? 0 [113] : 3 [116]
113: A <- P[28:4]
114: pc += (A == 0) ? 0 [115] : 1 [116]
115: ret 2147418112
116: A <- P[24:4]
117: pc += (A == 21507) ? 0 [118] : 3 [121]
118: A <- P[28:4]
119: pc += (A == 0) ? 0 [120] : 1 [121]
120: ret 2147418112
121: A <- P[24:4]
122: pc += (A == 21524) ? 0 [123] : 3 [126]
123: A <- P[28:4]
124: pc += (A == 0) ? 0 [125] : 1 [126]
125: ret 2147418112
126: A <- P[24:4]
127: pc += (A == 21523) ? 0 [128] : 3 [131]
128: A <- P[28:4]
129: pc += (A == 0) ? 0 [130] : 1 [131]
130: ret 2147418112
131: pc += 380 [512]
132: pc += (A == 15) ? 1 [134] : 0 [133]
133: pc += 378 [512]
134: ret 2147418112
135: pc += (A == 17) ? 1 [137] : 0 [136]
136: pc += 375 [512]
137: ret 2147418112
138: pc += (A == 39) ? 3 [142] : 0 [139]
139: pc += (A > 39) ? 0 [140] : 1 [141]
140: pc += 41 [182]
141: pc += 1 [143]
142: ret 2147418112
143: pc += (A == 28) ? 3 [147] : 0 [144]
144: pc += (A > 28) ? 0 [145] : 1 [146]
145: pc += 23 [169]
146: pc += 1 [148]
147: ret 2147418112
148: pc += (A == 24) ? 3 [152] : 0 [149]
149: pc += (A > 24) ? 0 [150] : 1 [151]
150: pc += 15 [166]
151: pc += 1 [153]
152: ret 2147418112
153: pc += (A == 20) ? 1 [155] : 0 [154]
154: pc += 357 [512]
155: A <- P[32:4]
156: pc += (A == 2) ? 0 [157] : 3 [160]
157: A <- P[36:4]
158: pc += (A == 0) ? 0 [159] : 1 [160]
159: ret 2147418112
160: A <- P[32:4]
161: pc += (A == 3) ? 0 [162] : 3 [165]
162: A <- P[36:4]
163: pc += (A == 0) ? 0 [164] : 1 [165]
164: ret 2147418112
165: pc += 346 [512]
166: pc += (A == 27) ? 1 [168] : 0 [167]
167: pc += 344 [512]
168: ret 2147418112
169: pc += (A == 35) ? 3 [173] : 0 [170]
170: pc += (A > 35) ? 0 [171] : 1 [172]
171: pc += 7 [179]
172: pc += 3 [176]
173: ret 2147418112
174: ret 2147418112
175: pc += 336 [512]
176: pc += (A == 32) ? 1 [178] : 0 [177]
177: pc += 334 [512]
178: ret 2147418112
179: pc += (A == 38) ? 1 [181] : 0 [180]
180: pc += 331 [512]
181: ret 2147418112
182: pc += (A == 48) ? 3 [186] : 0 [183]
183: pc += (A > 48) ? 0 [184] : 1 [185]
184: pc += 48 [233]
185: pc += 16 [202]
186: A <- P[24:4]
187: pc += (A == 0) ? 0 [188] : 3 [191]
188: A <- P[28:4]
189: pc += (A == 0) ? 0 [190] : 1 [191]
190: ret 2147418112
191: A <- P[24:4]
192: pc += (A == 1) ? 0 [193] : 3 [196]
193: A <- P[28:4]
194: pc += (A == 0) ? 0 [195] : 1 [196]
195: ret 2147418112
196: A <- P[24:4]
197: pc += (A == 2) ? 0 [198] : 3 [201]
198: A <- P[28:4]
199: pc += (A == 0) ? 0 [200] : 1 [201]
200: ret 2147418112
201: pc += 310 [512]
202: pc += (A == 46) ? 3 [206] : 0 [203]
203: pc += (A > 46) ? 0 [204] : 1 [205]
204: pc += 15 [220]
205: pc += 6 [212]
206: A <- P[32:4]
207: pc += (A == 16448) ? 0 [208] : 3 [211]
208: A <- P[36:4]
209: pc += (A == 0) ? 0 [210] : 1 [211]
210: ret 2147418112
211: pc += 300 [512]
212: pc += (A == 43) ? 1 [214] : 0 [213]
213: pc += 298 [512]
214: A <- P[16:4]
215: pc += (A == 3) ? 0 [216] : 3 [219]
216: A <- P[20:4]
217: pc += (A == 0) ? 0 [218] : 1 [219]
218: ret 2147418112
219: pc += 292 [512]
220: pc += (A == 47) ? 1 [222] : 0 [221]
221: pc += 290 [512]
222: A <- P[32:4]
223: pc += (A == 96) ? 0 [224] : 3 [227]
224: A <- P[36:4]
225: pc += (A == 0) ? 0 [226] : 1 [227]
226: ret 2147418112
227: A <- P[32:4]
228: pc += (A == 98) ? 0 [229] : 3 [232]
229: A <- P[36:4]
230: pc += (A == 0) ? 0 [231] : 1 [232]
231: ret 2147418112
232: pc += 279 [512]
233: pc += (A == 55) ? 3 [237] : 0 [234]
234: pc += (A > 55) ? 0 [235] : 1 [236]
235: pc += 68 [304]
236: pc += 55 [292]
237: A <- P[24:4]
238: pc += (A == 1) ? 0 [239] : 7 [246]
239: A <- P[28:4]
240: pc += (A == 0) ? 0 [241] : 5 [246]
241: A <- P[32:4]
242: pc += (A == 39) ? 0 [243] : 3 [246]
243: A <- P[36:4]
244: pc += (A == 0) ? 0 [245] : 1 [246]
245: ret 2147418112
246: A <- P[24:4]
247: pc += (A == 1) ? 0 [248] : 7 [255]
248: A <- P[28:4]
249: pc += (A == 0) ? 0 [250] : 5 [255]
250: A <- P[32:4]
251: pc += (A == 3) ? 0 [252] : 3 [255]
252: A <- P[36:4]
253: pc += (A == 0) ? 0 [254] : 1 [255]
254: ret 2147418112
255: A <- P[24:4]
256: pc += (A == 1) ? 0 [257] : 7 [264]
257: A <- P[28:4]
258: pc += (A == 0) ? 0 [259] : 5 [264]
259: A <- P[32:4]
260: pc += (A == 4) ? 0 [261] : 3 [264]
261: A <- P[36:4]
262: pc += (A == 0) ? 0 [263] : 1 [264]
263: ret 2147418112
264: A <- P[24:4]
265: pc += (A == 1) ? 0 [266] : 7 [273]
266: A <- P[28:4]
267: pc += (A == 0) ? 0 [268] : 5 [273]
268: A <- P[32:4]
269: pc += (A == 7) ? 0 [270] : 3 [273]
270: A <- P[36:4]
271: pc += (A == 0) ? 0 [272] : 1 [273]
272: ret 2147418112
273: A <- P[24:4]
274: pc += (A == 1) ? 0 [275] : 7 [282]
275: A <- P[28:4]
276: pc += (A == 0) ? 0 [277] : 5 [282]
277: A <- P[32:4]
278: pc += (A == 2) ? 0 [279] : 3 [282]
279: A <- P[36:4]
280: pc += (A == 0) ? 0 [281] : 1 [282]
281: ret 2147418112
282: A <- P[24:4]
283: pc += (A == 1) ? 0 [284] : 7 [291]
284: A <- P[28:4]
285: pc += (A == 0) ? 0 [286] : 5 [291]
286: A <- P[32:4]
287: pc += (A == 17) ? 0 [288] : 3 [291]
288: A <- P[36:4]
289: pc += (A == 0) ? 0 [290] : 1 [291]
290: ret 2147418112
291: pc += 220 [512]
292: pc += (A == 50) ? 1 [294] : 0 [293]
293: pc += 218 [512]
294: A <- P[16:4]
295: pc += (A == 3) ? 0 [296] : 7 [303]
296: A <- P[20:4]
297: pc += (A == 0) ? 0 [298] : 5 [303]
298: A <- P[24:4]
299: pc += (A == 16) ? 0 [300] : 3 [303]
300: A <- P[28:4]
301: pc += (A == 0) ? 0 [302] : 1 [303]
302: ret 2147418112
303: pc += 208 [512]
304: pc += (A == 56) ? 1 [306] : 0 [305]
305: pc += 206 [512]
306: A <- P[16:4]
307: pc += (A == 331520) ? 0 [308] : 3 [311]
308: A <- P[20:4]
309: pc += (A == 0) ? 0 [310] : 1 [311]
310: ret 2147418112
311: ret 2147418112
312: pc += 199 [512]
313: pc += (A == 228) ? 3 [317] : 0 [314]
314: pc += (A > 228) ? 0 [315] : 1 [316]
315: pc += 107 [423]
316: pc += 1 [318]
317: ret 2147418112
318: pc += (A == 131) ? 3 [322] : 0 [319]
319: pc += (A > 131) ? 0 [320] : 1 [321]
320: pc += 44 [365]
321: pc += 1 [323]
322: ret 2147418112
323: pc += (A == 77) ? 3 [327] : 0 [324]
324: pc += (A > 77) ? 0 [325] : 1 [326]
325: pc += 28 [354]
326: pc += 1 [328]
327: ret 2147418112
328: pc += (A == 72) ? 3 [332] : 0 [329]
329: pc += (A > 72) ? 0 [330] : 1 [331]
330: pc += 20 [351]
331: pc += 16 [348]
332: A <- P[24:4]
333: pc += (A == 3) ? 0 [334] : 3 [337]
334: A <- P[28:4]
335: pc += (A == 0) ? 0 [336] : 1 [337]
336: ret 2147418112
337: A <- P[24:4]
338: pc += (A == 4) ? 0 [339] : 3 [342]
339: A <- P[28:4]
340: pc += (A == 0) ? 0 [341] : 1 [342]
341: ret 2147418112
342: A <- P[24:4]
343: pc += (A == 1) ? 0 [344] : 3 [347]
344: A <- P[28:4]
345: pc += (A == 0) ? 0 [346] : 1 [347]
346: ret 2147418112
347: pc += 164 [512]
348: pc += (A == 61) ? 1 [350] : 0 [349]
349: pc += 162 [512]
350: ret 2147418112
351: pc += (A == 74) ? 1 [353] : 0 [352]
352: pc += 159 [512]
353: ret 2147418112
354: pc += (A == 96) ? 3 [358] : 0 [355]
355: pc += (A > 96) ? 0 [356] : 1 [357]
356: pc += 5 [362]
357: pc += 1 [359]
358: ret 2147418112
359: pc += (A == 91) ? 1 [361] : 0 [360]
360: pc += 151 [512]
361: ret 2147418112
362: pc += (A == 101) ? 1 [364] : 0 [363]
363: pc += 148 [512]
364: ret 2147418112
365: pc += (A == 201) ? 3 [369] : 0 [366]
366: pc += (A > 201) ? 0 [367] : 1 [368]
367: pc += 23 [391]
368: pc += 1 [370]
369: ret 2147418112
370: pc += (A == 158) ? 3 [374] : 0 [371]
371: pc += (A > 158) ? 0 [372] : 1 [373]
372: pc += 15 [388]
373: pc += 11 [385]
374: A <- P[16:4]
375: pc += (A == 4099) ? 0 [376] : 3 [379]
376: A <- P[20:4]
377: pc += (A == 0) ? 0 [378] : 1 [379]
378: ret 2147418112
379: A <- P[16:4]
380: pc += (A == 4098) ? 0 [381] : 3 [384]
381: A <- P[20:4]
382: pc += (A == 0) ? 0 [383] : 1 [384]
383: ret 2147418112
384: pc += 127 [512]
385: pc += (A == 150) ? 1 [387] : 0 [386]
386: pc += 125 [512]
387: ret 2147418112
388: pc += (A == 186) ? 1 [390] : 0 [389]
389: pc += 122 [512]
390: ret 2147418112
391: pc += (A == 203) ? 3 [395] : 0 [392]
392: pc += (A > 203) ? 0 [393] : 1 [394]
393: pc += 26 [420]
394: pc += 1 [396]
395: ret 2147418112
396: pc += (A == 202) ? 1 [398] : 0 [397]
397: pc += 114 [512]
398: A <- P[24:4]
399: pc += (A == 128) ? 0 [400] : 3 [403]
400: A <- P[28:4]
401: pc += (A == 0) ? 0 [402] : 1 [403]
402: ret 2147418112
403: A <- P[24:4]
404: pc += (A == 129) ? 0 [405] : 3 [408]
405: A <- P[28:4]
406: pc += (A == 0) ? 0 [407] : 1 [408]
407: ret 2147418112
408: A <- P[24:4]
409: pc += (A == 0) ? 0 [410] : 3 [413]
410: A <- P[28:4]
411: pc += (A == 0) ? 0 [412] : 1 [413]
412: ret 2147418112
413: A <- P[24:4]
414: pc += (A == 1) ? 0 [415] : 3 [418]
415: A <- P[28:4]
416: pc += (A == 0) ? 0 [417] : 1 [418]
417: ret 2147418112
418: ret 2147418112
419: pc += 92 [512]
420: pc += (A == 219) ? 1 [422] : 0 [421]
421: pc += 90 [512]
422: ret 2147418112
423: pc += (A == 281) ? 3 [427] : 0 [424]
424: pc += (A > 281) ? 0 [425] : 1 [426]
425: pc += 40 [466]
426: pc += 6 [433]
427: A <- P[48:4]
428: pc += (A == 0) ? 0 [429] : 3 [432]
429: A <- P[52:4]
430: pc += (A == 0) ? 0 [431] : 1 [432]
431: ret 2147418112
432: pc += 79 [512]
433: pc += (A == 257) ? 3 [437] : 0 [434]
434: pc += (A > 257) ? 0 [435] : 1 [436]
435: pc += 19 [455]
436: pc += 1 [438]
437: ret 2147418112
438: pc += (A == 233) ? 3 [442] : 0 [439]
439: pc += (A > 233) ? 0 [440] : 1 [441]
440: pc += 5 [446]
441: pc += 1 [443]
442: ret 2147418112
443: pc += (A == 231) ? 1 [445] : 0 [444]
444: pc += 67 [512]
445: ret 2147418112
446: pc += (A == 234) ? 1 [448] : 0 [447]
447: pc += 64 [512]
448: A <- P[16:4]
449: pc += (A == 17134) ? 0 [450] : 3 [453]
450: A <- P[20:4]
451: pc += (A == 0) ? 0 [452] : 1 [453]
452: ret 2147418112
453: ret 2147418112
454: pc += 57 [512]
455: pc += (A == 273) ? 3 [459] : 0 [456]
456: pc += (A > 273) ? 0 [457] : 1 [458]
457: pc += 5 [463]
458: pc += 1 [460]
459: ret 2147418112
460: pc += (A == 271) ? 1 [462] : 0 [461]
461: pc += 50 [512]
462: ret 2147418112
463: pc += (A == 277) ? 1 [465] : 0 [464]
464: pc += 47 [512]
465: ret 2147418112
466: pc += (A == 299) ? 3 [470] : 0 [467]
467: pc += (A > 299) ? 0 [468] : 1 [469]
468: pc += 35 [504]
469: pc += 14 [484]
470: A <- P[32:4]
471: pc += (A == 8) ? 0 [472] : 11 [483]
472: A <- P[36:4]
473: pc += (A == 0) ? 0 [474] : 9 [483]
474: A <- P[40:4]
475: pc += (A == 64) ? 0 [476] : 7 [483]
476: A <- P[44:4]
477: pc += (A == 0) ? 0 [478] : 5 [483]
478: A <- P[48:4]
479: pc += (A == 0) ? 0 [480] : 3 [483]
480: A <- P[52:4]
481: pc += (A == 0) ? 0 [482] : 1 [483]
482: ret 2147418112
483: pc += 28 [512]
484: pc += (A == 290) ? 3 [488] : 0 [485]
485: pc += (A > 290) ? 0 [486] : 1 [487]
486: pc += 14 [501]
487: pc += 10 [498]
488: A <- P[16:4]
489: pc += (A == 0) ? 0 [490] : 7 [497]
490: A <- P[20:4]
491: pc += (A == 0) ? 0 [492] : 5 [497]
492: A <- P[24:4]
493: pc += (A == 0) ? 0 [494] : 3 [497]
494: A <- P[28:4]
495: pc += (A == 0) ? 0 [496] : 1 [497]
496: ret 2147418112
497: pc += 14 [512]
498: pc += (A == 285) ? 1 [500] : 0 [499]
499: pc += 12 [512]
500: ret 2147418112
501: pc += (A == 291) ? 1 [503] : 0 [502]
502: pc += 9 [512]
503: ret 2147418112
504: pc += (A == 318) ? 3 [508] : 0 [505]
505: pc += (A > 318) ? 0 [506] : 1 [507]
506: pc += 5 [512]
507: pc += 1 [509]
508: ret 2147418112
509: pc += (A == 309) ? 1 [511] : 0 [510]
510: pc += 1 [512]
511: ret 2147418112
512: ret 196608

I0928 00:51:38.840741   17134 x:0] Seccomp filters installed.
I0928 00:51:38.841678   17134 x:0] Configuring container's file system
I0928 00:51:38.841741   17134 x:0] Mounting root over 9P, ioFD: 7
D0928 00:51:38.842219   17134 x:0] send [FD 7] [Tag 000001] Tversion{MSize: 1048576, Version: 9P2000.L.Google.8}
D0928 00:51:38.842999   17134 x:0] recv [FD 7] [Tag 000001] Rversion{MSize: 1048576, Version: 9P2000.L.Google.8}
D0928 00:51:38.843160   17134 x:0] send [FD 7] [Tag 000001] Tchannel{ID: 0, Control: 0}
D0928 00:51:38.842166   17134 x:0] Monitoring gofer health for container "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.865070   17134 x:0] recv [FD 7] [Tag 000001] Rchannel{Offset: 0, Length: 1052672}
D0928 00:51:38.865297   17134 x:0] send [FD 7] [Tag 000001] Tchannel{ID: 0, Control: 1}
D0928 00:51:38.866041   17134 x:0] recv [FD 7] [Tag 000001] Rchannel{Offset: 0, Length: 1052672}
D0928 00:51:38.866204   17134 x:0] send [FD 7] [Tag 000001] Tchannel{ID: 1, Control: 0}
D0928 00:51:38.867004   17134 x:0] recv [FD 7] [Tag 000001] Rchannel{Offset: 1052672, Length: 1052672}
D0928 00:51:38.867128   17134 x:0] send [FD 7] [Tag 000001] Tchannel{ID: 1, Control: 1}
D0928 00:51:38.867776   17134 x:0] recv [FD 7] [Tag 000001] Rchannel{Offset: 1052672, Length: 1052672}
D0928 00:51:38.867934   17134 x:0] send [FD 7] [Tag 000001] Tchannel{ID: 2, Control: 0}
D0928 00:51:38.868676   17134 x:0] recv [FD 7] [Tag 000001] Rchannel{Offset: 2105344, Length: 1052672}
D0928 00:51:38.868794   17134 x:0] send [FD 7] [Tag 000001] Tchannel{ID: 2, Control: 1}
D0928 00:51:38.869427   17134 x:0] recv [FD 7] [Tag 000001] Rchannel{Offset: 2105344, Length: 1052672}
D0928 00:51:38.869584   17134 x:0] send [FD 7] [Tag 000001] Tchannel{ID: 3, Control: 0}
D0928 00:51:38.870096   17134 x:0] recv [FD 7] [Tag 000001] Rchannel{Offset: 3158016, Length: 1052672}
D0928 00:51:38.870295   17134 x:0] send [FD 7] [Tag 000001] Tchannel{ID: 3, Control: 1}
D0928 00:51:38.870728   17134 x:0] recv [FD 7] [Tag 000001] Rchannel{Offset: 3158016, Length: 1052672}
D0928 00:51:38.871637   17134 x:0] send [channel @0xc0003ea600] Tattach{FID: 1, AuthFID: 4294967295, UserName: , AttachName: /, UID: 4294967295}
D0928 00:51:38.872407   17134 x:0] recv [channel @0xc0003ea600] Rattach{QID: QID{Type: 128, Version: 0, Path: 14156002}}
D0928 00:51:38.872571   17134 x:0] send [channel @0xc0003ea600] Tgetattr{FID: 1, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}}
D0928 00:51:38.873327   17134 x:0] recv [channel @0xc0003ea600] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 128, Version: 0, Path: 14156002}, Attr: Attr{Mode: 0o40755, UID: 0, GID: 0, NLink: 3, RDev: 0, Size: 4096, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1569631894, NanoSec: 143987977}, MTime: {Sec: 1569631898, NanoSec: 524356120}, CTime: {Sec: 1569631898, NanoSec: 524356120}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}}
I0928 00:51:38.873770   17134 x:0] Mounted "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/instance-1/image" to "/" type root
D0928 00:51:38.874020   17134 x:0] Mounting "" to "/proc", type: proc, options: []
D0928 00:51:38.891053   17134 x:0] send [channel @0xc0003ea600] Twalkgetattr{FID: 1, NewFID: 2, Names: [proc]}
D0928 00:51:38.891519   17134 x:0] recv [channel @0xc0003ea600] Rlerror{Error: 2}
I0928 00:51:38.891932   17134 x:0] Mounted "" to "/proc" type proc
D0928 00:51:38.891983   17134 x:0] Mounting "" to "/sys", type: sysfs, options: []
D0928 00:51:38.893640   17134 x:0] send [channel @0xc0003ea600] Twalkgetattr{FID: 1, NewFID: 2, Names: [sys]}
D0928 00:51:38.894057   17134 x:0] recv [channel @0xc0003ea600] Rlerror{Error: 2}
I0928 00:51:38.894279   17134 x:0] Mounted "" to "/sys" type sysfs
D0928 00:51:38.894349   17134 x:0] Mounting "" to "/dev", type: devtmpfs, options: []
I0928 00:51:38.894646   17134 x:0] Adding submount overlay over "/dev"
D0928 00:51:38.894791   17134 x:0] send [channel @0xc0003ea600] Twalkgetattr{FID: 1, NewFID: 2, Names: [dev]}
D0928 00:51:38.895146   17134 x:0] recv [channel @0xc0003ea600] Rlerror{Error: 2}
I0928 00:51:38.895343   17134 x:0] Mounted "" to "/dev" type devtmpfs
D0928 00:51:38.895424   17134 x:0] Mounting "" to "/dev/pts", type: devpts, options: []
I0928 00:51:38.895663   17134 x:0] Mounted "" to "/dev/pts" type devpts
D0928 00:51:38.895762   17134 x:0] send [channel @0xc0003ea600] Twalkgetattr{FID: 1, NewFID: 2, Names: [tmp]}
D0928 00:51:38.896710   17134 x:0] recv [channel @0xc0003ea600] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o40755, UID: 0, GID: 0, NLink: 2, RDev: 0, Size: 4096, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1569631898, NanoSec: 524356120}, MTime: {Sec: 1569631898, NanoSec: 524356120}, CTime: {Sec: 1569631898, NanoSec: 524356120}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 128, Version: 0, Path: 14156059}]}
D0928 00:51:38.896920   17134 x:0] send [channel @0xc0003ea600] Twalk{FID: 2, NewFID: 3, Names: []}
D0928 00:51:38.897391   17134 x:0] recv [channel @0xc0003ea600] Rwalk{QIDs: []}
D0928 00:51:38.897488   17134 x:0] send [channel @0xc0003ea600] Tlopen{FID: 3, Flags: ReadOnly}
D0928 00:51:38.898097   17134 x:0] recv [channel @0xc0003ea600] Rlopen{QID: QID{Type: 128, Version: 0, Path: 14156059}, IoUnit: 0, File: <nil>}
D0928 00:51:38.898269   17134 x:0] send [channel @0xc0003ea600] Twalk{FID: 2, NewFID: 4, Names: []}
D0928 00:51:38.898750   17134 x:0] recv [channel @0xc0003ea600] Rwalk{QIDs: []}
D0928 00:51:38.898840   17134 x:0] send [channel @0xc0003ea600] Tlopen{FID: 4, Flags: ReadOnly}
D0928 00:51:38.899356   17134 x:0] recv [channel @0xc0003ea600] Rlopen{QID: QID{Type: 128, Version: 0, Path: 14156059}, IoUnit: 0, File: <nil>}
D0928 00:51:38.899498   17134 x:0] send [channel @0xc0003ea600] Treaddir{DirectoryFID: 4, Offset: 0, Count: 65536}
D0928 00:51:38.919399   17134 x:0] recv [channel @0xc0003ea600] Rreaddir{Count: 0, Entries: []}
D0928 00:51:38.919581   17134 x:0] send [channel @0xc0003ea600] Tclunk{FID: 4}
D0928 00:51:38.920066   17134 x:0] recv [channel @0xc0003ea600] Rclunk{}
I0928 00:51:38.920298   17134 x:0] Mounting internal tmpfs on top of empty "/tmp"
W0928 00:51:38.920403   17134 x:0] ignoring unknown mount option "mode=1777"
D0928 00:51:38.920581   17134 x:0] send [channel @0xc0003ea600] Twalkgetattr{FID: 1, NewFID: 4, Names: [tmp]}
D0928 00:51:38.922226   17134 x:0] recv [channel @0xc0003ea600] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o40755, UID: 0, GID: 0, NLink: 2, RDev: 0, Size: 4096, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1569631898, NanoSec: 524356120}, MTime: {Sec: 1569631898, NanoSec: 524356120}, CTime: {Sec: 1569631898, NanoSec: 524356120}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 128, Version: 0, Path: 14156059}]}
D0928 00:51:38.922332   17134 x:0] send [channel @0xc0003ea600] Tclunk{FID: 4}
D0928 00:51:38.922605   17134 x:0] recv [channel @0xc0003ea600] Rclunk{}
I0928 00:51:38.922765   17134 x:0] Mounted "" to "/tmp" type tmpfs
D0928 00:51:38.922915   17134 x:0] send [channel @0xc0003ea600] Tclunk{FID: 3}
D0928 00:51:38.923240   17134 x:0] recv [channel @0xc0003ea600] Rclunk{}
D0928 00:51:38.923402   17134 x:0] send [channel @0xc0003ea600] Twalkgetattr{FID: 1, NewFID: 3, Names: [etc]}
D0928 00:51:38.923814   17134 x:0] recv [channel @0xc0003ea600] Rlerror{Error: 2}
I0928 00:51:38.923910   17134 x:0] EXEC: [/init]
D0928 00:51:38.924625   17134 x:0] send [channel @0xc0003ea600] Twalkgetattr{FID: 1, NewFID: 3, Names: [init]}
D0928 00:51:38.925558   17134 x:0] recv [channel @0xc0003ea600] 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: 52788352, BlockSize: 4096, Blocks: 103104, ATime: {Sec: 1569584456, NanoSec: 494533933}, MTime: {Sec: 1569584456, NanoSec: 494533933}, CTime: {Sec: 1569631894, NanoSec: 215994029}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 14156018}]}
D0928 00:51:38.925768   17134 x:0] send [channel @0xc0003ea600] Tgetattr{FID: 3, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}}
D0928 00:51:38.926425   17134 x:0] recv [channel @0xc0003ea600] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 14156018}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 52788352, BlockSize: 4096, Blocks: 103104, ATime: {Sec: 1569584456, NanoSec: 494533933}, MTime: {Sec: 1569584456, NanoSec: 494533933}, CTime: {Sec: 1569631894, NanoSec: 215994029}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}}
D0928 00:51:38.926511   17134 x:0] send [channel @0xc0003ea600] Twalk{FID: 3, NewFID: 4, Names: []}
D0928 00:51:38.926919   17134 x:0] recv [channel @0xc0003ea600] Rwalk{QIDs: []}
D0928 00:51:38.927006   17134 x:0] send [channel @0xc0003ea600] Tlopen{FID: 4, Flags: ReadOnly}
D0928 00:51:38.927589   17134 x:0] recv [channel @0xc0003ea600] Rlopen{QID: QID{Type: 0, Version: 0, Path: 14156018}, IoUnit: 0, File: &{{20}}}
D0928 00:51:38.930615   17134 x:0] Allocating stack with size of 8388608 bytes
I0928 00:51:38.931416   17134 x:0] Process should have started...
I0928 00:51:38.931496   17134 x:0] Starting watchdog, period: 45s, timeout: 3m0s, action: Panic
D0928 00:51:38.932916   17134 x:0] urpc: successfully marshalled 37 bytes.
D0928 00:51:38.933115   17114 x:0] urpc: unmarshal success.
D0928 00:51:38.933227   17114 x:0] Save container "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.934232   17114 x:0] List containers "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root"
D0928 00:51:38.935000   17114 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.937388   17114 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-test-0": signal 0
D0928 00:51:38.937515   17114 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.937588   17114 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.937889   17114 x:0] urpc: successfully marshalled 122 bytes.
D0928 00:51:38.938033   17134 x:0] [   1] Interrupt queued
D0928 00:51:38.938345   17134 x:0] urpc: unmarshal success.
D0928 00:51:38.938647   17134 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-test-0 Signo:0 PID:0 Mode:Process}
D0928 00:51:38.938840   17134 x:0] urpc: successfully marshalled 37 bytes.
D0928 00:51:38.939005   17114 x:0] urpc: unmarshal success.
D0928 00:51:38.939147   17114 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-test-1"
D0928 00:51:38.941814   17114 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-test-2"
D0928 00:51:38.942604   17114 x:0] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.942691   17114 x:0] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.942781   17114 x:0] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-test-0" by destroying sandbox
D0928 00:51:38.942859   17114 x:0] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:38.942955   17114 x:0] Killing sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-0"
D0928 00:51:39.245266   17114 x:0] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-test-0", PID: 17132
D0928 00:51:39.548139   17114 x:0] List containers "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root"
D0928 00:51:39.548804   17114 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-test-1"
D0928 00:51:39.551363   17114 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-test-1": signal 0
D0928 00:51:39.551495   17114 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1"
D0928 00:51:39.551586   17114 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-1"
D0928 00:51:39.551809   17114 x:0] urpc: successfully marshalled 122 bytes.
D0928 00:51:39.553157   17114 x:0] urpc: unmarshal success.
D0928 00:51:39.553293   17114 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-test-2"
D0928 00:51:39.555719   17114 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-test-2": signal 0
D0928 00:51:39.555839   17114 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2"
D0928 00:51:39.555896   17114 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-test-2"
D0928 00:51:39.556091   17114 x:0] urpc: successfully marshalled 122 bytes.
D0928 00:51:39.557273   17114 x:0] urpc: unmarshal success.
W0928 00:51:39.557457   17114 x:0] FATAL ERROR: running container: starting container: loading sandbox containers: loading container "ci-gvisor-ptrace-proxy-sandbox-race-test-0": unmarshaling container metadata from "/syzkaller/managers/ptrace-proxy-sandbox-race/latest.tmp/workdir/gvisor_root/ci-gvisor-ptrace-proxy-sandbox-race-test-2/meta.json": unexpected end of JSON input