zt/zttest/zt-traffic-test/loop4/dialer.go

/*
	Copyright NetFoundry Inc.

	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

	https://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 loop4

import (
	"errors"
	"fmt"
	"net"
	"sync/atomic"
	"time"

	"github.com/michaelquigley/pfxlog"
	"github.com/hanzozt/sdk-golang/zt/edge"
	"github.com/spf13/cobra"
)

func init() {
	loop4Cmd.AddCommand(newDialerCmd())
}

type dialerCmd struct {
	*Sim
	debugWhenDone bool
}

func newDialerCmd() *cobra.Command {
	dialer := &dialerCmd{
		Sim: NewSim(),
	}

	cmd := &cobra.Command{
		Use:   "dialer <scenarioFile>",
		Short: "Start loop4 dialer",
		Args:  cobra.ExactArgs(1),
		Run:   dialer.run,
	}

	cmd.Flags().BoolVarP(&dialer.debugWhenDone, "debug-when-done", "d", false,
		"keep running on when to allow debugging")

	return cmd
}

func (cmd *dialerCmd) run(_ *cobra.Command, args []string) {
	log := pfxlog.Logger()

	defer close(cmd.closeNotify)

	if err := cmd.InitScenario(args[0]); err != nil {
		panic(err)
	}

	if err := cmd.runScenario(cmd.scenario); err != nil {
		if cmd.debugWhenDone {
			log.WithError(err).Error("error running scenario")
		} else {
			log.WithError(err).Fatal("error running scenario")
		}
	}

	if cmd.debugWhenDone {
		log.Info("not exiting to allow debugging")
		doneC := make(chan struct{})
		<-doneC
	}
}

func (sim *Sim) runScenario(scenario *Scenario) error {
	log := pfxlog.Logger()
	log.Info("starting scenario run")

	resultChs := make(map[string]chan *Result)
	for _, workload := range scenario.Workloads {
		if _, ok := sim.dialers[workload.Connector]; !ok {
			return fmt.Errorf("workload '%s' uses unknown connector '%s'", workload.Name, workload.Connector)
		}

		if workload.ConnectTimeout == 0 {
			workload.ConnectTimeout = 5 * time.Second
		}

		if workload.Iterations == 0 {
			workload.Iterations = 1
		}

		for i := 0; i < int(workload.Concurrency); i++ {
			resultCh := make(chan *Result, 1)
			resultChs[workload.GetRunnerName(i)] = resultCh
		}
	}

	for _, workload := range scenario.Workloads {
		log.Infof("executing workload [%s] with concurrency [%d], %d iterations", workload.Name, workload.Concurrency, workload.Iterations)
		var active atomic.Int64
		for i := 0; i < int(workload.Concurrency); i++ {
			resultCh := resultChs[workload.GetRunnerName(i)]
			go sim.RunWorkload(scenario, workload, i+1, resultCh, &active)
		}
	}

	var errs []error

	for name, resultCh := range resultChs {
		result := <-resultCh
		if !result.Success {
			log.Errorf("[%s] -> error (%s)", name, result.Message)
			errs = append(errs, fmt.Errorf("[%s] -> error (%s)", name, result.Message))
		} else {
			log.Infof("workload %s complete: success", name)
		}
	}

	log.Infof("scenario completed with %d errors", len(errs))

	return errors.Join(errs...)
}

func (sim *Sim) RunWorkload(scenario *Scenario, workload *Workload, idx int, resultCh chan *Result, active *atomic.Int64) {
	log := pfxlog.Logger().WithField("workload", fmt.Sprintf("%s:%d", workload.Name, idx))

	var err error
	var conn net.Conn

	defer func() {
		if conn != nil {
			log.Info("closing connection")
			_ = conn.Close()
		}
	}()

	connectTimes := sim.metrics.Timer("service.connect.times:" + workload.Name)
	connectFailures := sim.metrics.Meter("service.connect.failures:" + workload.Name)
	connectSuccesses := sim.metrics.Meter("service.connect.successes:" + workload.Name)
	completed := sim.metrics.Meter("service.completed:" + workload.Name)
	sim.metrics.FuncGauge("service.active:"+workload.Name, func() int64 {
		return active.Load()
	})

	var result *Result
	for i := int64(0); i < workload.Iterations || workload.Iterations == -1; i++ {
		log = log.WithField("iteration", i+1)
		if conn != nil {
			log.Info("closing connection")
			if err = conn.Close(); err != nil {
				log.Errorf("unable to close connection for workload [%s]: %v", workload.Name, err)
			}
			conn = nil
		}

		startConnect := time.Now()
		conn, err = sim.dialers[workload.Connector](workload)
		connectTimes.UpdateSince(startConnect)
		if err != nil {
			log.WithError(err).Error("failed to dial")
			connectFailures.Mark(1)
			continue
		}

		log = log.WithField("src", conn.LocalAddr().String())

		circuitId := "unknown"
		if ztConn, ok := conn.(edge.Conn); ok {
			circuitId = ztConn.GetCircuitId()
			log = log.WithField("circuitId", circuitId).
				WithField("connId", ztConn.Id()).
				WithField("routerId", ztConn.GetRouterId())
		}

		active.Add(1)

		connectSuccesses.Mark(1)
		local, remote := workload.GetTests()

		log.Info("new connection established")

		var proto *protocol
		proto, err = newProtocol(conn, workload.Name, sim.metrics)
		if err != nil {
			log.WithError(err).Error("error creating protocol")
			sim.reportErr(resultCh, err, circuitId)
			active.Add(-1)
			return
		}

		if local.IsTxRandomHashed() {
			if err = proto.txTest(remote); err != nil {
				log.WithError(err).Error("error sending test to host")
				sim.reportErr(resultCh, err, circuitId)
				active.Add(-1)
				return
			}
		}

		if err = proto.run(local); err != nil {
			log.WithError(err).Error("error running test")
			if ztConn, ok := conn.(edge.Conn); ok {
				fmt.Println(ztConn.GetState())
			}
			sim.reportErr(resultCh, err, circuitId)
			active.Add(-1)
			return
		}

		if result, err = proto.rxResult(); err != nil {
			log.WithError(err).Error("error receiving test result")
			sim.reportErr(resultCh, err, circuitId)
			active.Add(-1)
			return
		}

		if !result.Success {
			active.Add(-1)
			resultCh <- result
			return
		}

		active.Add(-1)
		completed.Mark(1)
		log.Debug("completed iteration")
		if scenario.ConnectionDelay > 0 {
			time.Sleep(time.Duration(sim.scenario.ConnectionDelay) * time.Millisecond)
		}
	}

	resultCh <- &Result{
		Success: true,
	}
}

func (sim *Sim) reportErr(resultCh chan *Result, err error, circuitId string) {
	err = fmt.Errorf("circuit failure, circuitId: (%s), %w", circuitId, err)
	result := &Result{
		Success: false,
		Message: err.Error(),
	}
	select {
	case resultCh <- result:
	default:
		pfxlog.Logger().WithError(err).Panicf("failed to send result")
	}
}