diff --git a/internal/git/catfile/catfile.go b/internal/git/catfile/catfile.go
index 3020c07b7a1f9ac9a402f8200e53d20380d21a36..ba0025b6e473f24cabbd223ed71622fef7eb7b5d 100644
--- a/internal/git/catfile/catfile.go
+++ b/internal/git/catfile/catfile.go
@@ -35,7 +35,7 @@ func CatFile(ctx context.Context, repoPath string, handler Handler) error {
 	if err != nil {
 		return grpc.Errorf(codes.Internal, "CatFile: cmd: %v", err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 	defer stdinWriter.Close()
 	defer stdinReader.Close()
 
diff --git a/internal/helper/command.go b/internal/helper/command.go
index 4d91072512529f63e6853f18c6ac547dde31af97..b194c4eaa8d908addd8258e346483c692c6f6515 100644
--- a/internal/helper/command.go
+++ b/internal/helper/command.go
@@ -8,6 +8,7 @@ import (
 	"os/exec"
 	"strings"
 	"syscall"
+	"time"
 
 	"github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus"
 
@@ -21,6 +22,7 @@ import (
 type Command struct {
 	io.Reader
 	*exec.Cmd
+	startTime time.Time
 }
 
 // GitPath returns the path to the `git` binary. See `SetGitPath` for details
@@ -37,12 +39,6 @@ func GitPath() string {
 	return config.Config.Git.BinPath
 }
 
-// Kill cleans the subprocess group of the command. Callers should defer a call
-// to kill after they get the command from NewCommand
-func (c *Command) Kill() {
-	CleanUpProcessGroup(c.Cmd)
-}
-
 // GitCommandReader creates a git Command with the given args
 func GitCommandReader(ctx context.Context, args ...string) (*Command, error) {
 	return NewCommand(ctx, exec.Command(GitPath(), args...), nil, nil, nil)
@@ -55,7 +51,7 @@ func NewCommand(ctx context.Context, cmd *exec.Cmd, stdin io.Reader, stdout, std
 		"args": cmd.Args,
 	}).Info("spawn")
 
-	command := &Command{Cmd: cmd}
+	command := &Command{Cmd: cmd, startTime: time.Now()}
 
 	// Explicitly set the environment for the command
 	cmd.Env = []string{
@@ -108,7 +104,8 @@ func NewCommand(ctx context.Context, cmd *exec.Cmd, stdin io.Reader, stdout, std
 
 // CleanUpProcessGroup will send a SIGTERM signal to the process group
 // belonging to the `cmd` process
-func CleanUpProcessGroup(cmd *exec.Cmd) {
+func (c *Command) CleanUpProcessGroup(ctx context.Context) {
+	cmd := c.Cmd
 	if cmd == nil {
 		return
 	}
@@ -120,7 +117,17 @@ func CleanUpProcessGroup(cmd *exec.Cmd) {
 	}
 
 	// reap our child process
-	cmd.Wait()
+	err := cmd.Wait()
+
+	exitCode := 0
+	if err != nil {
+		if exitStatus, ok := ExitStatus(err); ok {
+			exitCode = exitStatus
+		}
+	}
+
+	c.logProcessComplete(ctx, exitCode)
+
 }
 
 // ExitStatus will return the exit-code from an error
@@ -137,3 +144,29 @@ func ExitStatus(err error) (int, bool) {
 
 	return waitStatus.ExitStatus(), true
 }
+
+func (c *Command) logProcessComplete(ctx context.Context, exitCode int) {
+	cmd := c.Cmd
+
+	systemTime := cmd.ProcessState.SystemTime()
+	userTime := cmd.ProcessState.UserTime()
+	realTime := time.Now().Sub(c.startTime)
+
+	entry := grpc_logrus.Extract(ctx).WithFields(log.Fields{
+		"path":                   cmd.Path,
+		"args":                   cmd.Args,
+		"command.exitCode":       exitCode,
+		"command.system_time_ms": systemTime.Seconds() * 1000,
+		"command.user_time_ms":   userTime.Seconds() * 1000,
+		"command.real_time_ms":   realTime.Seconds() * 1000,
+	})
+
+	if rusage, ok := cmd.ProcessState.SysUsage().(*syscall.Rusage); ok {
+		entry = entry.WithFields(log.Fields{
+			"command.inblock": rusage.Inblock,
+			"command.oublock": rusage.Oublock,
+		})
+	}
+
+	entry.Info("spawn complete")
+}
diff --git a/internal/helper/repo.go b/internal/helper/repo.go
index 33b51d9247a3731712214199387d83acffe22c98..232dffe67c8a64fd18d901e2d304c8e2b2f8f450 100644
--- a/internal/helper/repo.go
+++ b/internal/helper/repo.go
@@ -91,7 +91,7 @@ func IsValidRef(ctx context.Context, path, ref string) bool {
 	if err != nil {
 		return false
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 	cmd.Stdout, cmd.Stderr, cmd.Stdin = nil, nil, nil
 
 	return cmd.Wait() == nil
diff --git a/internal/service/blob/get_blob.go b/internal/service/blob/get_blob.go
index 82445f37e39fdc5286173a07d4e7b47cf807b52a..8969b58d84b34b23b2ffeaafcd2b5d441a22b6eb 100644
--- a/internal/service/blob/get_blob.go
+++ b/internal/service/blob/get_blob.go
@@ -33,7 +33,7 @@ func (s *server) GetBlob(in *pb.GetBlobRequest, stream pb.BlobService_GetBlobSer
 	if err != nil {
 		return grpc.Errorf(codes.Internal, "GetBlob: cmd: %v", err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(stream.Context())
 	defer stdinWriter.Close()
 	defer stdinReader.Close()
 
diff --git a/internal/service/commit/commits_helper.go b/internal/service/commit/commits_helper.go
index 38e711cbbc8c57c61946b5b281a80f95c614327d..5cd24489c9e4fb9e01a27d1c2fcb2f6c79afd828 100644
--- a/internal/service/commit/commits_helper.go
+++ b/internal/service/commit/commits_helper.go
@@ -68,7 +68,7 @@ func gitLog(ctx context.Context, sender lines.Sender, repo *pb.Repository, revis
 	if err != nil {
 		return err
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	split := lines.ScanWithDelimiter([]byte("\x00"))
 	if err := lines.Send(cmd, sender, split); err != nil {
diff --git a/internal/service/commit/count_commits.go b/internal/service/commit/count_commits.go
index 91790ef85ac4a523d0436605cde4b319312342aa..266327996b30b8d591a34ab44be836bf28c233bb 100644
--- a/internal/service/commit/count_commits.go
+++ b/internal/service/commit/count_commits.go
@@ -43,7 +43,7 @@ func (s *server) CountCommits(ctx context.Context, in *pb.CountCommitsRequest) (
 	if err != nil {
 		return nil, grpc.Errorf(codes.Internal, "CountCommits: cmd: %v", err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	var count int64
 	countStr, readAllErr := ioutil.ReadAll(cmd)
diff --git a/internal/service/commit/isancestor.go b/internal/service/commit/isancestor.go
index fac30d74d7fe54a6406010af9e5e69946473aaf0..b2d84fd5aa39a1ce9653d8f19a162f91df14c4dd 100644
--- a/internal/service/commit/isancestor.go
+++ b/internal/service/commit/isancestor.go
@@ -44,7 +44,7 @@ func commitIsAncestorName(ctx context.Context, path, ancestorID, childID string)
 	if err != nil {
 		return false, grpc.Errorf(codes.Internal, err.Error())
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	return cmd.Wait() == nil, nil
 }
diff --git a/internal/service/commit/list_files.go b/internal/service/commit/list_files.go
index c6559117ece49d5e51022a8a5ade25e5e7aa3b76..b829bfef6d4648b9329d063f77f97d77d1bcab0d 100644
--- a/internal/service/commit/list_files.go
+++ b/internal/service/commit/list_files.go
@@ -38,7 +38,7 @@ func (s *server) ListFiles(in *pb.ListFilesRequest, stream pb.CommitService_List
 	if err != nil {
 		return grpc.Errorf(codes.Internal, err.Error())
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(stream.Context())
 
 	scanner := lines.ScanWithDelimiter([]byte{'\x00'})
 
diff --git a/internal/service/commit/raw_blame.go b/internal/service/commit/raw_blame.go
index 0ec329d9159fb36897292a6f8e8dcabe85ed9a1f..46927fe34701ed2381992f3a2a2b2e8eaa6e175e 100644
--- a/internal/service/commit/raw_blame.go
+++ b/internal/service/commit/raw_blame.go
@@ -32,7 +32,7 @@ func (s *server) RawBlame(in *pb.RawBlameRequest, stream pb.CommitService_RawBla
 	if err != nil {
 		return grpc.Errorf(codes.Internal, "RawBlame: cmd: %v", err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	sw := streamio.NewWriter(func(p []byte) error {
 		return stream.Send(&pb.RawBlameResponse{Data: p})
diff --git a/internal/service/diff/commit.go b/internal/service/diff/commit.go
index 20f965478f1d7ab9f58fe3c62133b688de303d56..205a79306de7f1108f2dd07560e9419feee04339 100644
--- a/internal/service/diff/commit.go
+++ b/internal/service/diff/commit.go
@@ -218,7 +218,7 @@ func eachDiff(ctx context.Context, rpc string, cmdArgs []string, limits diff.Lim
 	if err != nil {
 		return grpc.Errorf(codes.Internal, "%s: cmd: %v", rpc, err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	diffParser := diff.NewDiffParser(cmd, limits)
 
diff --git a/internal/service/ref/refname.go b/internal/service/ref/refname.go
index c9a3df5f4f99626b99dc8531c36727c3ff4d0997..3c001231066bb46e03472df4abd55331684de0d9 100644
--- a/internal/service/ref/refname.go
+++ b/internal/service/ref/refname.go
@@ -45,7 +45,7 @@ func findRefName(ctx context.Context, path, commitID, prefix string) (string, er
 	if err != nil {
 		return "", err
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	scanner := bufio.NewScanner(cmd)
 	scanner.Scan()
diff --git a/internal/service/ref/refs.go b/internal/service/ref/refs.go
index 949c042b4dfb0a9b04c8977c1fcdba240ac7865c..2b84d4c2ecef0e296ab48e4d2dc2d109a0d13c4b 100644
--- a/internal/service/ref/refs.go
+++ b/internal/service/ref/refs.go
@@ -55,7 +55,7 @@ func findRefs(ctx context.Context, writer lines.Sender, repo *pb.Repository, pat
 	if err != nil {
 		return err
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	if err := lines.Send(cmd, writer, opts.splitter); err != nil {
 		return err
@@ -91,7 +91,7 @@ func _findBranchNames(ctx context.Context, repoPath string) ([][]byte, error) {
 	if err != nil {
 		return nil, err
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	scanner := bufio.NewScanner(cmd)
 	for scanner.Scan() {
@@ -115,7 +115,7 @@ func _headReference(ctx context.Context, repoPath string) ([]byte, error) {
 	if err != nil {
 		return nil, err
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	scanner := bufio.NewScanner(cmd)
 	scanner.Scan()
diff --git a/internal/service/smarthttp/inforefs.go b/internal/service/smarthttp/inforefs.go
index 864926327456c1717187eb8dd7029d9d434c6d97..1a95218bdd3b15ed8bf03a9885eca19de2e516c8 100644
--- a/internal/service/smarthttp/inforefs.go
+++ b/internal/service/smarthttp/inforefs.go
@@ -43,7 +43,7 @@ func handleInfoRefs(ctx context.Context, service string, repo *pb.Repository, w
 	if err != nil {
 		return grpc.Errorf(codes.Internal, "GetInfoRefs: cmd: %v", err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(ctx)
 
 	if err := pktLine(w, fmt.Sprintf("# service=git-%s\n", service)); err != nil {
 		return grpc.Errorf(codes.Internal, "GetInfoRefs: pktLine: %v", err)
diff --git a/internal/service/smarthttp/receive_pack.go b/internal/service/smarthttp/receive_pack.go
index 04d7470a359cb30aafb45e37303ba9f8a3c4c08d..57cc70563b9a5da0bd36f6995b6e3660fa0b2d90 100644
--- a/internal/service/smarthttp/receive_pack.go
+++ b/internal/service/smarthttp/receive_pack.go
@@ -54,7 +54,7 @@ func (s *server) PostReceivePack(stream pb.SmartHTTPService_PostReceivePackServe
 	if err != nil {
 		return grpc.Errorf(codes.Unavailable, "PostReceivePack: cmd: %v", err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(stream.Context())
 
 	if err := cmd.Wait(); err != nil {
 		return grpc.Errorf(codes.Unavailable, "PostReceivePack: cmd wait for %v: %v", cmd.Args, err)
diff --git a/internal/service/smarthttp/upload_pack.go b/internal/service/smarthttp/upload_pack.go
index 0690a8e05e7a65f39cab2e667961541f3858d1ea..549a0c24521580148824732813f8a51cbf41d46e 100644
--- a/internal/service/smarthttp/upload_pack.go
+++ b/internal/service/smarthttp/upload_pack.go
@@ -65,7 +65,7 @@ func (s *server) PostUploadPack(stream pb.SmartHTTPService_PostUploadPackServer)
 	if err != nil {
 		return grpc.Errorf(codes.Unavailable, "PostUploadPack: cmd: %v", err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(stream.Context())
 
 	if err := cmd.Wait(); err != nil {
 		pw.Close() // ensure scanDeepen returns
diff --git a/internal/service/ssh/receive_pack.go b/internal/service/ssh/receive_pack.go
index f47e0c4ab1d0bd056f9962d22f60cd0c57b1f3a7..ba324b6b4870dab58bea833512e27b59a9b3faff 100644
--- a/internal/service/ssh/receive_pack.go
+++ b/internal/service/ssh/receive_pack.go
@@ -58,7 +58,7 @@ func (s *server) SSHReceivePack(stream pb.SSHService_SSHReceivePackServer) error
 	if err != nil {
 		return grpc.Errorf(codes.Unavailable, "SSHReceivePack: cmd: %v", err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(stream.Context())
 
 	if err := cmd.Wait(); err != nil {
 		if status, ok := helper.ExitStatus(err); ok {
diff --git a/internal/service/ssh/upload_pack.go b/internal/service/ssh/upload_pack.go
index 19549a2a42168179e16279d3691c02f615b7f787..3a81c9155e4c534e8f3b94f77c289baaba433439 100644
--- a/internal/service/ssh/upload_pack.go
+++ b/internal/service/ssh/upload_pack.go
@@ -52,7 +52,7 @@ func (s *server) SSHUploadPack(stream pb.SSHService_SSHUploadPackServer) error {
 	if err != nil {
 		return grpc.Errorf(codes.Unavailable, "SSHUploadPack: cmd: %v", err)
 	}
-	defer cmd.Kill()
+	defer cmd.CleanUpProcessGroup(stream.Context())
 
 	if err := cmd.Wait(); err != nil {
 		if status, ok := helper.ExitStatus(err); ok {