syzbot


DATA RACE in fsutil.(*InodeSimpleExtendedAttributes).ListXattr

Status: fixed on 2020/03/10 21:06
Fix commit: 62bd3ca8a375 Take write lock when removing xattr
First crash: 1731d, last: 1731d

Sample crash report:
WARNING: DATA RACE
Read at 0x00c008dd2840 by goroutine 2545:
  gvisor.dev/gvisor/pkg/sentry/fs/fsutil.(*InodeSimpleExtendedAttributes).ListXattr()
      pkg/sentry/fs/fsutil/inode.go:252 +0x8f
DIAGNOSIS:
I0301 05:37:27.718553   35584 main.go:305] ***************************
I0301 05:37:27.718715   35584 main.go:306] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-2]
I0301 05:37:27.718944   35584 main.go:307] Version release-20200219.0-72-gce4d1e45bb88
I0301 05:37:27.719028   35584 main.go:308] PID: 35584
I0301 05:37:27.719126   35584 main.go:309] UID: 0, GID: 0
I0301 05:37:27.719232   35584 main.go:310] Configuration:
I0301 05:37:27.719297   35584 main.go:311] 		RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root
I0301 05:37:27.719385   35584 main.go:312] 		Platform: ptrace
I0301 05:37:27.719525   35584 main.go:313] 		FileAccess: shared, overlay: false
I0301 05:37:27.719675   35584 main.go:314] 		Network: sandbox, logging: false
I0301 05:37:27.719794   35584 main.go:315] 		Strace: false, max size: 1024, syscalls: []
I0301 05:37:27.719945   35584 main.go:316] ***************************
W0301 05:37:27.720026   35584 main.go:321] Block the TERM signal. This is only safe in tests!
D0301 05:37:27.720221   35584 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2"
D0301 05:37:27.732899   35584 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0
D0301 05:37:27.733065   35584 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2"
D0301 05:37:27.733236   35584 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2"
D0301 05:37:27.733709   35584 urpc.go:534] urpc: successfully marshalled 117 bytes.
I0301 05:37:28.860833   35584 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 36647
I0301 05:37:28.861011   35584 debug.go:131] Retrieving sandbox stacks
D0301 05:37:28.861105   35584 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2"
D0301 05:37:28.861214   35584 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2"
W0301 05:37:28.861365   35584 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 36647: connection refused
retrieving stacks: connecting to control server at PID 36647: connection refused
W0301 05:37:28.861776   35584 main.go:338] Failure to execute command, err: 1


Error collecting stacks: failed to run ["/syzkaller/managers/ptrace-proxy-sandbox-race/current/image" "-root" "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-alsologtostderr" "-platform=ptrace" "-file-access=shared" "-network=sandbox" "-TESTONLY-unsafe-nonroot" "debug" "-stacks" "--ps" "ci-gvisor-ptrace-proxy-sandbox-race-2"]: exit status 128
I0301 05:37:27.718553   35584 main.go:305] ***************************
I0301 05:37:27.718715   35584 main.go:306] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-2]
I0301 05:37:27.718944   35584 main.go:307] Version release-20200219.0-72-gce4d1e45bb88
I0301 05:37:27.719028   35584 main.go:308] PID: 35584
I0301 05:37:27.719126   35584 main.go:309] UID: 0, GID: 0
I0301 05:37:27.719232   35584 main.go:310] Configuration:
I0301 05:37:27.719297   35584 main.go:311] 		RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root
I0301 05:37:27.719385   35584 main.go:312] 		Platform: ptrace
I0301 05:37:27.719525   35584 main.go:313] 		FileAccess: shared, overlay: false
I0301 05:37:27.719675   35584 main.go:314] 		Network: sandbox, logging: false
I0301 05:37:27.719794   35584 main.go:315] 		Strace: false, max size: 1024, syscalls: []
I0301 05:37:27.719945   35584 main.go:316] ***************************
W0301 05:37:27.720026   35584 main.go:321] Block the TERM signal. This is only safe in tests!
D0301 05:37:27.720221   35584 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2"
D0301 05:37:27.732899   35584 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0
D0301 05:37:27.733065   35584 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2"
D0301 05:37:27.733236   35584 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2"
D0301 05:37:27.733709   35584 urpc.go:534] urpc: successfully marshalled 117 bytes.
I0301 05:37:28.860833   35584 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 36647
I0301 05:37:28.861011   35584 debug.go:131] Retrieving sandbox stacks
D0301 05:37:28.861105   35584 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2"
D0301 05:37:28.861214   35584 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2"
W0301 05:37:28.861365   35584 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 36647: connection refused
retrieving stacks: connecting to control server at PID 36647: connection refused
W0301 05:37:28.861776   35584 main.go:338] Failure to execute command, err: 1
  gvisor.dev/gvisor/pkg/sentry/fs/tmpfs.(*fileInodeOperations).ListXattr()
      <autogenerated>:1 +0x79
  gvisor.dev/gvisor/pkg/sentry/fs.(*Inode).ListXattr()
      pkg/sentry/fs/inode.go:285 +0x14e
  gvisor.dev/gvisor/pkg/sentry/syscalls/linux.listXattr()
      pkg/sentry/syscalls/linux/sys_xattr.go:318 +0x141
  gvisor.dev/gvisor/pkg/sentry/syscalls/linux.FListXattr()
      pkg/sentry/syscalls/linux/sys_xattr.go:271 +0x158
  gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall()
      pkg/sentry/kernel/task_syscall.go:170 +0x44d
  gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke()
      pkg/sentry/kernel/task_syscall.go:291 +0xb4
  gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter()
      pkg/sentry/kernel/task_syscall.go:252 +0x109
  gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall()
      pkg/sentry/kernel/task_syscall.go:227 +0x1b3
  gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute()
      pkg/sentry/kernel/task_run.go:232 +0x1aaa
  gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run()
      pkg/sentry/kernel/task_run.go:92 +0x320

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2020/03/01 05:37 gvisor ce4d1e45bb88 c88c7b75 .config console log report ci-gvisor-ptrace-proxy-sandbox-race
* Struck through repros no longer work on HEAD.