/* * Copyright (c) 2018 VMware, Inc. * * Permission is hereby granted, free of charge, to any person obtaining a copy of this software and * associated documentation files (the "Software"), to deal in the Software without restriction, including * without limitation the rights to use, copy, modify, merge, publish, distribute, sublicense, and/or sell * copies of the Software, and to permit persons to whom the Software is furnished to do * so, subject to the following conditions: * * The above copyright notice and this permission notice shall be included in all copies or substantial * portions of the Software. * * THE SOFTWARE IS PROVIDED "AS IS", WITHOUT WARRANTY OF ANY KIND, EXPRESS OR IMPLIED, INCLUDING BUT * NOT LIMITED TO THE WARRANTIES OF MERCHANTABILITY, FITNESS FOR A PARTICULAR PURPOSE AND NONINFRINGEMENT. * IN NO EVENT SHALL THE AUTHORS OR COPYRIGHT HOLDERS BE LIABLE FOR ANY CLAIM, DAMAGES OR OTHER LIABILITY, * WHETHER IN AN ACTION OF CONTRACT, TORT OR OTHERWISE, ARISING FROM, OUT OF OR IN CONNECTION WITH THE * SOFTWARE OR THE USE OR OTHER DEALINGS IN THE SOFTWARE. */ // Package worker // The implementation is derived from https://github.com/patrobinson/gokini // // Copyright 2018 Patrick robinson // // Permission is hereby granted, free of charge, to any person obtaining a copy of this software and associated documentation files (the "Software"), to deal in the Software without restriction, including without limitation the rights to use, copy, modify, merge, publish, distribute, sublicense, and/or sell copies of the Software, and to permit persons to whom the Software is furnished to do so, subject to the following conditions: // // The above copyright notice and this permission notice shall be included in all copies or substantial portions of the Software. // // THE SOFTWARE IS PROVIDED "AS IS", WITHOUT WARRANTY OF ANY KIND, EXPRESS OR IMPLIED, INCLUDING BUT NOT LIMITED TO THE WARRANTIES OF MERCHANTABILITY, FITNESS FOR A PARTICULAR PURPOSE AND NONINFRINGEMENT. IN NO EVENT SHALL THE AUTHORS OR COPYRIGHT HOLDERS BE LIABLE FOR ANY CLAIM, DAMAGES OR OTHER LIABILITY, WHETHER IN AN ACTION OF CONTRACT, TORT OR OTHERWISE, ARISING FROM, OUT OF OR IN CONNECTION WITH THE SOFTWARE OR THE USE OR OTHER DEALINGS IN THE SOFTWARE. package worker import ( "context" "crypto/rand" "errors" "math/big" "sync" "time" "github.com/aws/aws-sdk-go-v2/aws" "github.com/aws/aws-sdk-go-v2/aws/retry" awsConfig "github.com/aws/aws-sdk-go-v2/config" "github.com/aws/aws-sdk-go-v2/credentials" "github.com/aws/aws-sdk-go-v2/service/kinesis" chk "github.com/vmware/vmware-go-kcl-v2/clientlibrary/checkpoint" "github.com/vmware/vmware-go-kcl-v2/clientlibrary/config" kcl "github.com/vmware/vmware-go-kcl-v2/clientlibrary/interfaces" "github.com/vmware/vmware-go-kcl-v2/clientlibrary/metrics" par "github.com/vmware/vmware-go-kcl-v2/clientlibrary/partition" ) //Worker is the high level class that Kinesis applications use to start processing data. It initializes and oversees //different components (e.g. syncing shard and lease information, tracking shard assignments, and processing data from //the shards). type Worker struct { streamName string regionName string workerID string consumerARN string processorFactory kcl.IRecordProcessorFactory kclConfig *config.KinesisClientLibConfiguration kc *kinesis.Client checkpointer chk.Checkpointer mService metrics.MonitoringService stop *chan struct{} waitGroup *sync.WaitGroup done bool randomSeed int64 shardStatus map[string]*par.ShardStatus shardStealInProgress bool } // NewWorker constructs a Worker instance for processing Kinesis stream data. func NewWorker(factory kcl.IRecordProcessorFactory, kclConfig *config.KinesisClientLibConfiguration) *Worker { mService := kclConfig.MonitoringService if mService == nil { // Replaces nil with noop monitor service (not emitting any metrics). mService = metrics.NoopMonitoringService{} } return &Worker{ streamName: kclConfig.StreamName, regionName: kclConfig.RegionName, workerID: kclConfig.WorkerID, processorFactory: factory, kclConfig: kclConfig, mService: mService, done: false, randomSeed: time.Now().UTC().UnixNano(), } } // WithKinesis is used to provide Kinesis service for either custom implementation or unit testing. func (w *Worker) WithKinesis(svc *kinesis.Client) *Worker { w.kc = svc return w } // WithCheckpointer is used to provide a custom checkpointer service for non-dynamodb implementation // or unit testing. func (w *Worker) WithCheckpointer(checker chk.Checkpointer) *Worker { w.checkpointer = checker return w } // Start Run starts consuming data from the stream, and pass it to the application record processors. func (w *Worker) Start() error { log := w.kclConfig.Logger if err := w.initialize(); err != nil { log.Errorf("Failed to initialize Worker: %+v", err) return err } // Start monitoring service log.Infof("Starting monitoring service.") if err := w.mService.Start(); err != nil { log.Errorf("Failed to start monitoring service: %+v", err) return err } log.Infof("Starting worker event loop.") w.waitGroup.Add(1) go func() { defer w.waitGroup.Done() // entering event loop w.eventLoop() }() return nil } // Shutdown signals worker to shut down. Worker will try initiating shutdown of all record processors. func (w *Worker) Shutdown() { log := w.kclConfig.Logger log.Infof("Worker shutdown in requested.") if w.done || w.stop == nil { return } close(*w.stop) w.done = true w.waitGroup.Wait() w.mService.Shutdown() log.Infof("Worker loop is complete. Exiting from worker.") } // initialize func (w *Worker) initialize() error { log := w.kclConfig.Logger log.Infof("Worker initialization in progress...") // Create default Kinesis client if w.kc == nil { // create session for Kinesis log.Infof("Creating Kinesis client") resolver := aws.EndpointResolverFunc(func(service, region string) (aws.Endpoint, error) { return aws.Endpoint{ PartitionID: "aws", URL: w.kclConfig.KinesisEndpoint, SigningRegion: w.regionName, }, nil }) cfg, err := awsConfig.LoadDefaultConfig( context.TODO(), awsConfig.WithRegion(w.regionName), awsConfig.WithCredentialsProvider( credentials.NewStaticCredentialsProvider( w.kclConfig.KinesisCredentials.Value.AccessKeyID, w.kclConfig.KinesisCredentials.Value.SecretAccessKey, w.kclConfig.KinesisCredentials.Value.SessionToken)), awsConfig.WithEndpointResolver(resolver), awsConfig.WithRetryer(func() aws.Retryer { return retry.AddWithMaxBackoffDelay(retry.NewStandard(), retry.DefaultMaxBackoff) }), ) if err != nil { // no need to move forward log.Fatalf("Failed in loading Kinesis default config for creating Worker: %+v", err) } w.kc = kinesis.NewFromConfig(cfg) } else { log.Infof("Use custom Kinesis service.") } // Create default dynamodb based checkpointer implementation if w.checkpointer == nil { log.Infof("Creating DynamoDB based checkpointer") w.checkpointer = chk.NewDynamoCheckpoint(w.kclConfig) } else { log.Infof("Use custom checkpointer implementation.") } if w.kclConfig.EnableEnhancedFanOutConsumer { log.Debugf("Enhanced fan-out is enabled") w.consumerARN = w.kclConfig.EnhancedFanOutConsumerARN if w.consumerARN == "" { var err error w.consumerARN, err = w.fetchConsumerARNWithRetry() if err != nil { log.Errorf("Failed to fetch consumer ARN for: %s, %v", w.kclConfig.EnhancedFanOutConsumerName, err) return err } } } err := w.mService.Init(w.kclConfig.ApplicationName, w.streamName, w.workerID) if err != nil { log.Errorf("Failed to start monitoring service: %+v", err) } log.Infof("Initializing Checkpointer") if err := w.checkpointer.Init(); err != nil { log.Errorf("Failed to start Checkpointer: %+v", err) return err } w.shardStatus = make(map[string]*par.ShardStatus) stopChan := make(chan struct{}) w.stop = &stopChan w.waitGroup = &sync.WaitGroup{} log.Infof("Initialization complete.") return nil } // newShardConsumer creates shard consumer for the specified shard func (w *Worker) newShardConsumer(shard *par.ShardStatus) shardConsumer { common := commonShardConsumer{ shard: shard, kc: w.kc, checkpointer: w.checkpointer, recordProcessor: w.processorFactory.CreateProcessor(), kclConfig: w.kclConfig, mService: w.mService, } if w.kclConfig.EnableEnhancedFanOutConsumer { w.kclConfig.Logger.Infof("Start enhanced fan-out shard consumer for shard: %v", shard.ID) return &FanOutShardConsumer{ commonShardConsumer: common, consumerARN: w.consumerARN, consumerID: w.workerID, stop: w.stop, } } w.kclConfig.Logger.Infof("Start polling shard consumer for shard: %v", shard.ID) return &PollingShardConsumer{ commonShardConsumer: common, streamName: w.streamName, consumerID: w.workerID, stop: w.stop, mService: w.mService, } } // eventLoop func (w *Worker) eventLoop() { log := w.kclConfig.Logger var foundShards int for { // Add [-50%, +50%] random jitter to ShardSyncIntervalMillis. When multiple workers // starts at the same time, this decreases the probability of them calling // kinesis.DescribeStream at the same time, and hit the hard-limit on aws API calls. // On average the period remains the same so that doesn't affect behavior. rnd, _ := rand.Int(rand.Reader, big.NewInt(int64(w.kclConfig.ShardSyncIntervalMillis))) shardSyncSleep := w.kclConfig.ShardSyncIntervalMillis/2 + int(rnd.Int64()) err := w.syncShard() if err != nil { log.Errorf("Error syncing shards: %+v, Retrying in %d ms...", err, shardSyncSleep) time.Sleep(time.Duration(shardSyncSleep) * time.Millisecond) continue } if foundShards == 0 || foundShards != len(w.shardStatus) { foundShards = len(w.shardStatus) log.Infof("Found %d shards", foundShards) } // Count the number of leases held by this worker excluding the processed shard counter := 0 for _, shard := range w.shardStatus { if shard.GetLeaseOwner() == w.workerID && shard.GetCheckpoint() != chk.ShardEnd { counter++ } } // max number of lease has not been reached yet if counter < w.kclConfig.MaxLeasesForWorker { for _, shard := range w.shardStatus { // already owner of the shard if shard.GetLeaseOwner() == w.workerID { continue } err := w.checkpointer.FetchCheckpoint(shard) if err != nil { // checkpoint may not exist yet is not an error condition. if err != chk.ErrSequenceIDNotFound { log.Warnf("Couldn't fetch checkpoint: %+v", err) // move on to next shard continue } } // The shard is closed and we have processed all records if shard.GetCheckpoint() == chk.ShardEnd { continue } var stealShard bool if w.kclConfig.EnableLeaseStealing && shard.ClaimRequest != "" { upcomingStealingInterval := time.Now().UTC().Add(time.Duration(w.kclConfig.LeaseStealingIntervalMillis) * time.Millisecond) if shard.GetLeaseTimeout().Before(upcomingStealingInterval) && !shard.IsClaimRequestExpired(w.kclConfig) { if shard.ClaimRequest == w.workerID { stealShard = true log.Debugf("Stealing shard: %s", shard.ID) } else { log.Debugf("Shard being stolen: %s", shard.ID) continue } } } err = w.checkpointer.GetLease(shard, w.workerID) if err != nil { // cannot get lease on the shard if !errors.As(err, &chk.ErrLeaseNotAcquired{}) { log.Errorf("Cannot get lease: %+v", err) } continue } if stealShard { log.Debugf("Successfully stole shard: %+v", shard.ID) w.shardStealInProgress = false } // log metrics on got lease w.mService.LeaseGained(shard.ID) w.waitGroup.Add(1) go func(shard *par.ShardStatus) { defer w.waitGroup.Done() if err := w.newShardConsumer(shard).getRecords(); err != nil { log.Errorf("Error in getRecords: %+v", err) } }(shard) // exit from for loop and not to grab more shard for now. break } } if w.kclConfig.EnableLeaseStealing { err = w.rebalance() if err != nil { log.Warnf("Error in rebalance: %+v", err) } } select { case <-*w.stop: log.Infof("Shutting down...") return case <-time.After(time.Duration(shardSyncSleep) * time.Millisecond): log.Debugf("Waited %d ms to sync shards...", shardSyncSleep) } } } func (w *Worker) rebalance() error { log := w.kclConfig.Logger workers, err := w.checkpointer.ListActiveWorkers(w.shardStatus) if err != nil { log.Debugf("Error listing workers. workerID: %s. Error: %+v ", w.workerID, err) return err } // Only attempt to steal one shard at time, to allow for linear convergence if w.shardStealInProgress { shardInfo := make(map[string]bool) err := w.getShardIDs("", shardInfo) if err != nil { return err } for _, shard := range w.shardStatus { if shard.ClaimRequest != "" && shard.ClaimRequest == w.workerID { log.Debugf("Steal in progress. workerID: %s", w.workerID) return nil } // Our shard steal was stomped on by a Checkpoint. // We could deal with that, but instead just try again w.shardStealInProgress = false } } var numShards int for _, shards := range workers { numShards += len(shards) } numWorkers := len(workers) // 1:1 shards to workers is optimal, so we cannot possibly rebalance if numWorkers >= numShards { log.Debugf("Optimal shard allocation, not stealing any shards. workerID: %s, %v > %v. ", w.workerID, numWorkers, numShards) return nil } currentShards, ok := workers[w.workerID] var numCurrentShards int if !ok { numCurrentShards = 0 numWorkers++ } else { numCurrentShards = len(currentShards) } optimalShards := numShards / numWorkers // We have more than or equal optimal shards, so no rebalancing can take place if numCurrentShards >= optimalShards || numCurrentShards == w.kclConfig.MaxLeasesForWorker { log.Debugf("We have enough shards, not attempting to steal any. workerID: %s", w.workerID) return nil } var workerSteal string for worker, shards := range workers { if worker != w.workerID && len(shards) > optimalShards { workerSteal = worker optimalShards = len(shards) } } // Not all shards are allocated so fallback to default shard allocation mechanisms if workerSteal == "" { log.Infof("Not all shards are allocated, not stealing any. workerID: %s", w.workerID) return nil } // Steal a random shard from the worker with the most shards w.shardStealInProgress = true rnd, _ := rand.Int(rand.Reader, big.NewInt(int64(len(workers[workerSteal])))) randIndex := int(rnd.Int64()) shardToSteal := workers[workerSteal][randIndex] log.Debugf("Stealing shard %s from %s", shardToSteal, workerSteal) err = w.checkpointer.ClaimShard(w.shardStatus[shardToSteal.ID], w.workerID) if err != nil { w.shardStealInProgress = false return err } return nil } // List all shards and store them into shardStatus table // If shard has been removed, need to exclude it from cached shard status. func (w *Worker) getShardIDs(nextToken string, shardInfo map[string]bool) error { log := w.kclConfig.Logger args := &kinesis.ListShardsInput{} // When you have a nextToken, you can't set the streamName if nextToken != "" { args.NextToken = aws.String(nextToken) } else { args.StreamName = aws.String(w.streamName) } listShards, err := w.kc.ListShards(context.TODO(), args) if err != nil { log.Errorf("Error in ListShards: %s Error: %+v Request: %s", w.streamName, err, args) return err } for _, s := range listShards.Shards { // record avail shardId from fresh reading from Kinesis shardInfo[*s.ShardId] = true // found new shard if _, ok := w.shardStatus[*s.ShardId]; !ok { log.Infof("Found new shard with id %s", *s.ShardId) w.shardStatus[*s.ShardId] = &par.ShardStatus{ ID: *s.ShardId, ParentShardId: aws.ToString(s.ParentShardId), Mux: &sync.RWMutex{}, StartingSequenceNumber: aws.ToString(s.SequenceNumberRange.StartingSequenceNumber), EndingSequenceNumber: aws.ToString(s.SequenceNumberRange.EndingSequenceNumber), } } } if listShards.NextToken != nil { err := w.getShardIDs(aws.ToString(listShards.NextToken), shardInfo) if err != nil { log.Errorf("Error in ListShards: %s Error: %+v Request: %s", w.streamName, err, args) return err } } return nil } // syncShard to sync the cached shard info with actual shard info from Kinesis func (w *Worker) syncShard() error { log := w.kclConfig.Logger shardInfo := make(map[string]bool) err := w.getShardIDs("", shardInfo) if err != nil { return err } for _, shard := range w.shardStatus { // The cached shard no longer existed, remove it. if _, ok := shardInfo[shard.ID]; !ok { // remove the shard from local status cache delete(w.shardStatus, shard.ID) // remove the shard entry in dynamoDB as well // Note: syncShard runs periodically. we don't need to do anything in case of error here. if err := w.checkpointer.RemoveLeaseInfo(shard.ID); err != nil { log.Errorf("Failed to remove shard lease info: %s Error: %+v", shard.ID, err) } } } return nil }