deployer.go

// Copyright 2022 Google LLC
//
// Licensed under the Apache License, Version 2.0 (the "License");
// you may not use this file except in compliance with the License.
// You may obtain a copy of the License at
//
//      http://www.apache.org/licenses/LICENSE-2.0
//
// Unless required by applicable law or agreed to in writing, software
// distributed under the License is distributed on an "AS IS" BASIS,
// WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
// See the License for the specific language governing permissions and
// limitations under the License.

package multi

import (
	"context"
	"crypto"
	"crypto/x509"
	"errors"
	"fmt"
	"log/slog"
	"net"
	"net/http"
	"os"
	"slices"
	"sync"
	"syscall"
	"time"

	imetrics "github.com/TiagoMalhadas/xcweaver/internal/metrics"
	"github.com/TiagoMalhadas/xcweaver/internal/proxy"
	"github.com/TiagoMalhadas/xcweaver/internal/routing"
	"github.com/TiagoMalhadas/xcweaver/internal/status"
	"github.com/TiagoMalhadas/xcweaver/internal/tool/certs"
	"github.com/TiagoMalhadas/xcweaver/runtime"
	"github.com/TiagoMalhadas/xcweaver/runtime/bin"
	"github.com/TiagoMalhadas/xcweaver/runtime/colors"
	"github.com/TiagoMalhadas/xcweaver/runtime/envelope"
	"github.com/TiagoMalhadas/xcweaver/runtime/graph"
	"github.com/TiagoMalhadas/xcweaver/runtime/logging"
	"github.com/TiagoMalhadas/xcweaver/runtime/metrics"
	"github.com/TiagoMalhadas/xcweaver/runtime/profiling"
	"github.com/TiagoMalhadas/xcweaver/runtime/protos"
	"github.com/TiagoMalhadas/xcweaver/runtime/traces"
	"github.com/google/uuid"
	"golang.org/x/exp/maps"
	"golang.org/x/sync/errgroup"
	"google.golang.org/protobuf/types/known/timestamppb"
)

// The default number of times a component is replicated.
const defaultReplication = 2

// A deployer manages an application deployment.
type deployer struct {
	ctx          context.Context
	ctxCancel    context.CancelFunc
	deploymentId string
	tmpDir       string // Private directory for this weavelet/envelope
	config       *MultiConfig
	started      time.Time
	logger       *slog.Logger
	caCert       *x509.Certificate
	caKey        crypto.PrivateKey
	running      errgroup.Group
	logsDB       *logging.FileStore
	printer      *logging.PrettyPrinter
	traceDB      *traces.DB

	// statsProcessor tracks and computes stats to be rendered on the /statusz page.
	statsProcessor *imetrics.StatsProcessor

	mu      sync.Mutex            // guards the following
	err     error                 // error that stopped the babysitter
	groups  map[string]*group     // groups, by component name
	proxies map[string]*proxyInfo // proxies, by listener name
}

// A group contains information about a co-location group.
type group struct {
	name        string                          // group name
	envelopes   []*envelope.Envelope            // envelopes, one per weavelet
	pids        []int64                         // weavelet pids
	started     map[string]bool                 // started components
	addresses   map[string]bool                 // weavelet addresses
	assignments map[string]*protos.Assignment   // assignment, by component
	subscribers map[string][]*envelope.Envelope // routing info subscribers, by component
	callable    []string                        // callable components for group
	certPEM     []byte                          // group certificate
	keyPEM      []byte                          // group private key
}

// A proxyInfo contains information about a proxy.
type proxyInfo struct {
	listener string       // listener associated with the proxy
	proxy    *proxy.Proxy // the proxy
	addr     string       // dialable address of the proxy
}

// handler handles a connection to a weavelet.
type handler struct {
	*deployer
	g          *group
	envelope   *envelope.Envelope
	subscribed map[string]bool // routing info subscriptions, by component
}

var _ envelope.EnvelopeHandler = &handler{}

// newDeployer creates a new deployer. The deployer can be stopped at any
// time by canceling the passed-in context.
func newDeployer(ctx context.Context, deploymentId string, config *MultiConfig, tmpDir string) (*deployer, error) {
	// Create the log saver.
	logsDB, err := logging.NewFileStore(logDir)
	if err != nil {
		return nil, fmt.Errorf("cannot create log storage: %w", err)
	}
	printer := logging.NewPrettyPrinter(colors.Enabled())
	logger := slog.New(&logging.LogHandler{
		Opts: logging.Options{
			App:       config.App.Name,
			Component: "deployer",
			Weavelet:  uuid.NewString(),
			Attrs:     []string{"serviceweaver/system", ""},
		},
		Write: func(e *protos.LogEntry) { log(logsDB, printer, e) },
	})
	var caCert *x509.Certificate
	var caKey crypto.PrivateKey
	if config.Mtls {
		caCert, caKey, err = certs.GenerateCACert()
		if err != nil {
			return nil, fmt.Errorf("cannot generate signing certificate: %w", err)
		}
	}

	// Create the trace saver.
	traceDB, err := traces.OpenDB(ctx, perfettoFile)
	if err != nil {
		return nil, fmt.Errorf("cannot open Perfetto database: %w", err)
	}

	ctx, cancel := context.WithCancel(ctx)
	d := &deployer{
		ctx:            ctx,
		ctxCancel:      cancel,
		tmpDir:         tmpDir,
		logger:         logger,
		caCert:         caCert,
		caKey:          caKey,
		logsDB:         logsDB,
		printer:        printer,
		traceDB:        traceDB,
		statsProcessor: imetrics.NewStatsProcessor(),
		deploymentId:   deploymentId,
		config:         config,
		started:        time.Now(),
		proxies:        map[string]*proxyInfo{},
	}

	// Form co-location groups.
	if err := d.computeGroups(); err != nil {
		return nil, err
	}

	// Start a goroutine that collects metrics.
	d.running.Go(func() error {
		err := d.statsProcessor.CollectMetrics(d.ctx, d.readMetrics)
		d.stop(err)
		return err
	})

	// Start a goroutine that watches for context cancelation.
	d.running.Go(func() error {
		<-d.ctx.Done()
		err := d.ctx.Err()
		d.stop(err)
		return err
	})

	return d, nil
}

// computeGroups computes the colocation group information for the deployer.
//
// computeGroups places components into co-location groups based on the
// colocate stanza in a config. For example, consider the following config.
//
//	colocate = [
//	    ["A", "B", "C"],
//	    ["D", "E"],
//	]
//
// computeGroups creates a co-location group with components "A", "B", and
// "C" and a co-location group with components "D" and "E". All other
// components are placed in their own co-location group. We use the first
// listed component as the co-location group name.
func (d *deployer) computeGroups() error {
	groups := map[string]*group{}
	ensureGroup := func(component string) (*group, error) {
		if g, ok := groups[component]; ok {
			return g, nil
		}
		var certPEM, keyPEM []byte
		if d.config.Mtls {
			cert, key, err := certs.GenerateSignedCert(d.caCert, d.caKey, component)
			if err != nil {
				return nil, fmt.Errorf("cannot generate cert: %w", err)
			}
			if certPEM, keyPEM, err = certs.PEMEncode(cert, key); err != nil {
				return nil, err
			}
		}

		g := &group{
			// TODO(spetrovic): ensure a consistent name is picked for
			// colocation groups across versions.
			name:        component,
			started:     map[string]bool{},
			addresses:   map[string]bool{},
			assignments: map[string]*protos.Assignment{},
			subscribers: map[string][]*envelope.Envelope{},
			certPEM:     certPEM,
			keyPEM:      keyPEM,
		}
		groups[component] = g
		return g, nil
	}

	// Use the colocation information to place multiple components
	// in the same group.
	for _, grp := range d.config.App.Colocate {
		if len(grp.Components) == 0 {
			continue
		}
		g, err := ensureGroup(grp.Components[0])
		if err != nil {
			return err
		}
		for i := 1; i < len(grp.Components); i++ {
			groups[grp.Components[i]] = g
		}
	}

	// Use the call graph information to (1) identify all components in the
	// application binary and create groups for them, and (2) compute the
	// set of components a given group is allowed to invoke methods on.
	components, g, err := bin.ReadComponentGraph(d.config.App.Binary)
	if err != nil {
		return fmt.Errorf("cannot read the call graph from the application binary: %w", err)
	}
	g.PerNode(func(n graph.Node) {
		ensureGroup(components[n])
	})
	graph.PerEdge(g, func(e graph.Edge) {
		src := components[e.Src]
		dst := components[e.Dst]
		srcGroup := groups[src]
		srcGroup.callable = append(srcGroup.callable, dst)
	})

	d.groups = groups
	return nil
}

// wait waits for the deployer to terminate. It returns an error that
// caused the deployer to terminate. This method will never return
// a non-nil error.
//
// REQUIRES: d.mu is NOT held.
func (d *deployer) wait() error {
	d.running.Wait()
	d.mu.Lock()
	defer d.mu.Unlock()
	return d.err
}

// REQUIRES: err != nil
// REQUIRES: d.mu is NOT held.
func (d *deployer) stop(err error) {
	// Record the first error.
	d.mu.Lock()
	if d.err == nil {
		d.err = err
	}
	d.mu.Unlock()

	// Cancel the context.
	d.ctxCancel()
}

// routing returns the RoutingInfo for the provided component.
//
// REQUIRES: d.mu is held.
func (g *group) routing(component string) *protos.RoutingInfo {
	return &protos.RoutingInfo{
		Component:  component,
		Replicas:   maps.Keys(g.addresses),
		Assignment: g.assignments[component],
	}
}

// startColocationGroup starts the colocation group hosting the provided
// component, if it hasn't been started already.
//
// REQUIRES: d.mu is held.
func (d *deployer) startColocationGroup(g *group) error {
	// Check if the deployer has already been stopped. The cleanup protocol
	// requires that no further envelopes be started after the deployer
	// has been stopped.
	if d.err != nil {
		return d.err
	}
	if len(g.envelopes) == defaultReplication {
		// Already started.
		return nil
	}

	components := maps.Keys(g.started)
	for r := 0; r < defaultReplication; r++ {
		// Start the weavelet and capture its logs, traces, and metrics.
		info := &protos.WeaveletArgs{
			App:             d.config.App.Name,
			DeploymentId:    d.deploymentId,
			Id:              uuid.New().String(),
			RunMain:         g.started[runtime.Main],
			Mtls:            d.config.Mtls,
			InternalAddress: "localhost:0",
		}
		e, err := envelope.NewEnvelope(d.ctx, info, d.config.App, envelope.Options{
			Logger: d.logger,
		})
		if err != nil {
			return err
		}

		h := &handler{
			deployer:   d,
			g:          g,
			subscribed: map[string]bool{},
			envelope:   e,
		}

		d.running.Go(func() error {
			err := e.Serve(h)
			d.stop(err)
			return err
		})
		pid, ok := e.Pid()
		if !ok {
			panic("multi deployer child must be a real process")
		}
		if err := d.registerReplica(g, e.WeaveletAddress(), pid); err != nil {
			return err
		}
		if err := e.UpdateComponents(components); err != nil {
			return err
		}
		g.envelopes = append(g.envelopes, e)
	}
	return nil
}

func (d *deployer) startMain() error {
	return d.activateComponent(&protos.ActivateComponentRequest{
		Component: runtime.Main,
	})
}

// ActivateComponent implements the control.DeployerControl interface.
func (h *handler) ActivateComponent(_ context.Context, req *protos.ActivateComponentRequest) (*protos.ActivateComponentReply, error) {
	if err := h.subscribeTo(req); err != nil {
		return nil, err
	}
	return &protos.ActivateComponentReply{}, h.activateComponent(req)
}

func (h *handler) subscribeTo(req *protos.ActivateComponentRequest) error {
	h.mu.Lock()
	defer h.mu.Unlock()

	if h.subscribed[req.Component] {
		return nil
	}
	h.subscribed[req.Component] = true

	target, ok := h.groups[req.Component]
	if !ok {
		return fmt.Errorf("unknown component %q", req.Component)
	}

	if !req.Routed && h.g.name == target.name {
		// Route locally.
		routing := &protos.RoutingInfo{Component: req.Component, Local: true}
		return h.envelope.UpdateRoutingInfo(routing)
	}

	// Route remotely.
	target.subscribers[req.Component] = append(target.subscribers[req.Component], h.envelope)
	return h.envelope.UpdateRoutingInfo(target.routing(req.Component))
}

// GetSelfCertificate implements the control.DeployerControl interface.
func (h *handler) GetSelfCertificate(context.Context, *protos.GetSelfCertificateRequest) (*protos.GetSelfCertificateReply, error) {
	return &protos.GetSelfCertificateReply{
		Cert: h.g.certPEM,
		Key:  h.g.keyPEM,
	}, nil
}

// VerifyClientCertificate implements the control.DeployerControl interface.
func (h *handler) VerifyClientCertificate(_ context.Context, req *protos.VerifyClientCertificateRequest) (*protos.VerifyClientCertificateReply, error) {
	groupName, err := h.verifyCertificate(req.CertChain)
	if err != nil {
		return nil, err
	}

	// Find which weavelet components the client is allowed to call.
	g, ok := h.groups[groupName]
	if !ok {
		return nil, fmt.Errorf("unknown client group %q", groupName)
	}
	return &protos.VerifyClientCertificateReply{Components: g.callable}, nil
}

// VerifyServerCertificate implements the control.DeployerControl interface.
func (h *handler) VerifyServerCertificate(_ context.Context, req *protos.VerifyServerCertificateRequest) (*protos.VerifyServerCertificateReply, error) {
	actual, err := h.verifyCertificate(req.CertChain)
	if err != nil {
		return nil, err
	}

	// Find the expected group name for the target component.
	g, ok := h.groups[req.TargetComponent]
	if !ok {
		return nil, fmt.Errorf("unknown group for component %q", req.TargetComponent)
	}
	if g.name != actual {
		return nil, fmt.Errorf("invalid server identity for target component %s: want %q, got %q", req.TargetComponent, g.name, actual)
	}
	return &protos.VerifyServerCertificateReply{}, nil
}

// verifyCertificate verifies and returns the group name stored in the given
// certificate chain.
func (h *handler) verifyCertificate(certChain [][]byte) (string, error) {
	if n := len(certChain); n != 1 {
		return "", fmt.Errorf("invalid cert chain length: want 1, got %d", n)
	}
	names, err := certs.VerifySignedCert(certChain[0], h.caCert)
	if err != nil {
		return "", fmt.Errorf("cannot verify the cert: %w", err)
	}
	if len(names) != 1 {
		return "", fmt.Errorf("invalid cert: expected a single name, got %v", names)
	}
	name := names[0]
	if name == "" {
		return "", fmt.Errorf("empty group name %q in cert", name)
	}
	return name, nil
}

func (d *deployer) activateComponent(req *protos.ActivateComponentRequest) error {
	d.mu.Lock()
	defer d.mu.Unlock()

	target, ok := d.groups[req.Component]
	if !ok {
		return fmt.Errorf("unknown component %q", req.Component)
	}

	// Update the set of components in the target co-location group.
	if !target.started[req.Component] {
		target.started[req.Component] = true

		// Notify the weavelets.
		components := maps.Keys(target.started)
		for _, envelope := range target.envelopes {
			if err := envelope.UpdateComponents(components); err != nil {
				return err
			}
		}

		// Create an initial assignment.
		if req.Routed {
			replicas := maps.Keys(target.addresses)
			assignment := routingAlgo(&protos.Assignment{}, replicas)
			target.assignments[req.Component] = assignment
			d.logger.Debug(fmt.Sprintf("Initial assignment for component %s:\n%s", req.Component, routing.FormatAssignment(assignment)))
		}

		// Notify the subscribers.
		routing := target.routing(req.Component)
		for _, sub := range target.subscribers[req.Component] {
			if err := sub.UpdateRoutingInfo(routing); err != nil {
				return err
			}
		}
	}

	// Start the co-location group, if it hasn't started already.
	return d.startColocationGroup(target)
}

// registerReplica registers the information about a colocation group replica
// (i.e., a weavelet).
func (d *deployer) registerReplica(g *group, replicaAddr string, pid int) error {
	// Update addresses and pids.
	if g.addresses[replicaAddr] {
		// Replica already registered.
		return nil
	}
	g.addresses[replicaAddr] = true
	g.pids = append(g.pids, int64(pid))

	// Update all assignments.
	replicas := maps.Keys(g.addresses)
	for component, assignment := range g.assignments {
		assignment = routingAlgo(assignment, replicas)
		g.assignments[component] = assignment
		d.logger.Debug(fmt.Sprintf("Updated assignment for component %s:\n%s", component, routing.FormatAssignment(assignment)))
	}

	// Notify subscribers.
	for component := range g.started {
		routing := g.routing(component)
		for _, sub := range g.subscribers[component] {
			if err := sub.UpdateRoutingInfo(routing); err != nil {
				return err
			}
		}
	}

	return nil
}

// LogBatch implements the control.DeployerControl interface.
func (d *deployer) LogBatch(ctx context.Context, batch *protos.LogEntryBatch) error {
	for _, entry := range batch.Entries {
		log(d.logsDB, d.printer, entry)
	}
	return nil
}

// HandleTraceSpans implements the control.DeployerControl interface.
func (d *deployer) HandleTraceSpans(ctx context.Context, spans *protos.TraceSpans) error {
	return d.traceDB.Store(ctx, d.config.App.Name, d.deploymentId, spans)
}

// GetListenerAddress implements the control.DeployerControl interface.
func (d *deployer) GetListenerAddress(context.Context, *protos.GetListenerAddressRequest) (*protos.GetListenerAddressReply, error) {
	return &protos.GetListenerAddressReply{Address: "localhost:0"}, nil
}

// GetAntipodeAgentInfo implements the control.DeployerControl interface.
func (d *deployer) GetAntipodeAgentInfo(_ context.Context, request *protos.GetAntipodeAgentInfoRequest) (*protos.GetAntipodeAgentInfoReply, error) {
	name := request.Name
	if antipodeAgent, ok := d.config.AntipodeAgents[name]; !ok {
		// The antipode agent name does not exist.
		return nil, fmt.Errorf("The antipode agent %s does not exist", name)
	} else {
		reply := &protos.GetAntipodeAgentInfoReply{
			DatastoreType: antipodeAgent.DatastoreType,
			Host:          antipodeAgent.Host,
			Port:          antipodeAgent.Port,
			User:          antipodeAgent.User,
			Password:      antipodeAgent.Password,
			Datastore:     antipodeAgent.Datastore,
			Queue:         antipodeAgent.Queue,
		}
		return reply, nil
	}
}

// ExportListener implements the control.DeployerControl interface.
func (d *deployer) ExportListener(_ context.Context, req *protos.ExportListenerRequest) (*protos.ExportListenerReply, error) {
	d.mu.Lock()
	defer d.mu.Unlock()

	// Update the proxy.
	if p, ok := d.proxies[req.Listener]; ok {
		p.proxy.AddBackend(req.Address)
		return &protos.ExportListenerReply{ProxyAddress: p.addr}, nil
	}

	// Get the proxy address. It should be the same as the Address field
	// in the options for this listener, if any was specified.
	var proxyAddr string
	if opts, ok := d.config.Listeners[req.Listener]; ok {
		proxyAddr = opts.Address
	}

	lis, err := net.Listen("tcp", proxyAddr)
	if errors.Is(err, syscall.EADDRINUSE) {
		// Don't retry if this address is already in use.
		return &protos.ExportListenerReply{Error: err.Error()}, nil
	}
	if err != nil {
		return nil, fmt.Errorf("proxy listen: %w", err)
	}
	addr := lis.Addr().String() // actual proxy address
	d.logger.Info("Proxy listening", "address", addr)
	proxy := proxy.NewProxy(d.logger)
	proxy.AddBackend(req.Address)
	d.proxies[req.Listener] = &proxyInfo{
		listener: req.Listener,
		proxy:    proxy,
		addr:     addr,
	}
	go func() {
		if err := serveHTTP(d.ctx, lis, proxy); err != nil {
			d.logger.Error("proxy", "err", err)
		}
	}()
	return &protos.ExportListenerReply{ProxyAddress: addr}, nil
}

func (d *deployer) readMetrics() []*metrics.MetricSnapshot {
	d.mu.Lock()
	defer d.mu.Unlock()

	var ms []*metrics.MetricSnapshot
	for _, group := range d.groups {
		for _, envelope := range group.envelopes {
			m, err := envelope.GetMetrics()
			if err != nil {
				continue
			}
			ms = append(ms, m...)
		}
	}
	return append(ms, metrics.Snapshot()...)
}

// Profile implements the status.Server interface.
func (d *deployer) Profile(_ context.Context, req *protos.GetProfileRequest) (*protos.GetProfileReply, error) {
	// Make a copy of the envelopes, so we can operate on it without holding the
	// lock. A profile can last a long time.
	d.mu.Lock()
	envelopes := map[string][]*envelope.Envelope{}
	for _, group := range d.groups {
		envelopes[group.name] = slices.Clone(group.envelopes)
	}
	d.mu.Unlock()

	profile, err := runProfiling(d.ctx, req, envelopes)
	if err != nil {
		return nil, err
	}
	return profile, nil
}

// Status implements the status.Server interface.
func (d *deployer) Status(context.Context) (*status.Status, error) {
	d.mu.Lock()
	defer d.mu.Unlock()

	stats := d.statsProcessor.GetStatsStatusz()
	var components []*status.Component
	for _, group := range d.groups {
		for component := range group.started {
			c := &status.Component{
				Name: component,
				Pids: slices.Clone(group.pids),
			}
			components = append(components, c)

			// TODO(mwhittaker): Unify with ui package and remove duplication.
			s := stats[logging.ShortenComponent(component)]
			if s == nil {
				continue
			}
			for _, methodStats := range s {
				c.Methods = append(c.Methods, &status.Method{
					Name: methodStats.Name,
					Minute: &status.MethodStats{
						NumCalls:     methodStats.Minute.NumCalls,
						AvgLatencyMs: methodStats.Minute.AvgLatencyMs,
						RecvKbPerSec: methodStats.Minute.RecvKBPerSec,
						SentKbPerSec: methodStats.Minute.SentKBPerSec,
					},
					Hour: &status.MethodStats{
						NumCalls:     methodStats.Hour.NumCalls,
						AvgLatencyMs: methodStats.Hour.AvgLatencyMs,
						RecvKbPerSec: methodStats.Hour.RecvKBPerSec,
						SentKbPerSec: methodStats.Hour.SentKBPerSec,
					},
					Total: &status.MethodStats{
						NumCalls:     methodStats.Total.NumCalls,
						AvgLatencyMs: methodStats.Total.AvgLatencyMs,
						RecvKbPerSec: methodStats.Total.RecvKBPerSec,
						SentKbPerSec: methodStats.Total.SentKBPerSec,
					},
				})
			}
		}
	}

	var listeners []*status.Listener
	for _, proxy := range d.proxies {
		listeners = append(listeners, &status.Listener{
			Name: proxy.listener,
			Addr: proxy.addr,
		})
	}

	return &status.Status{
		App:            d.config.App.Name,
		DeploymentId:   d.deploymentId,
		SubmissionTime: timestamppb.New(d.started),
		Components:     components,
		Listeners:      listeners,
		Config:         d.config.App,
	}, nil
}

// Metrics implements the status.Server interface.
func (d *deployer) Metrics(context.Context) (*status.Metrics, error) {
	m := &status.Metrics{}
	for _, snap := range d.readMetrics() {
		m.Metrics = append(m.Metrics, snap.ToProto())
	}
	return m, nil
}

func routingAlgo(currAssignment *protos.Assignment, candidates []string) *protos.Assignment {
	assignment := routing.EqualSlices(candidates)
	assignment.Version = currAssignment.Version + 1
	return assignment
}

// serveHTTP serves HTTP traffic on the provided listener using the provided
// handler. The server is shut down when then provided context is cancelled.
func serveHTTP(ctx context.Context, lis net.Listener, handler http.Handler) error {
	server := http.Server{Handler: handler}
	errs := make(chan error, 1)
	go func() { errs <- server.Serve(lis) }()
	select {
	case err := <-errs:
		return err
	case <-ctx.Done():
		return server.Shutdown(ctx)
	}
}

// runProfiling runs a profiling request on a set of processes.
func runProfiling(_ context.Context, req *protos.GetProfileRequest, processes map[string][]*envelope.Envelope) (*protos.GetProfileReply, error) {
	// Collect together the groups we want to profile.
	groups := make([][]func() ([]byte, error), 0, len(processes))
	for _, envelopes := range processes {
		group := make([]func() ([]byte, error), 0, len(envelopes))
		for _, e := range envelopes {
			e := e
			group = append(group, func() ([]byte, error) {
				return e.GetProfile(req)
			})
		}
		groups = append(groups, group)
	}
	data, err := profiling.ProfileGroups(groups)
	return &protos.GetProfileReply{Data: data}, err
}

func log(db *logging.FileStore, printer *logging.PrettyPrinter, e *protos.LogEntry) {
	if !logging.IsSystemGenerated(e) {
		fmt.Fprintln(os.Stderr, printer.Format(e))
	}
	db.Add(e)
}