Report metric tracking voting time

modules/actions/logging/prometheus.go

@@ -66,6 +66,18 @@ var BlockRoundSummary = prometheus.NewSummaryVec(
 	},
 )
 
+// VoteTimeHistogram represents the Telemetry histogram used to track voting times
+var VoteTimeHistogram = prometheus.NewHistogramVec(
+	prometheus.HistogramOpts{
+		Name:    "bdjuno_vote_time",
+		Help:    "Measures time required to vote.",
+		Buckets: []float64{250, 500, 750, 1000, 1500, 2000, 2500, 3000, 4000, 5000, 6000, 7000},
+	}, []string{
+		"proposer",
+		"voter",
+	},
+)
+
 func init() {
 	for _, c := range []prometheus.Collector{
 		ActionResponseTime,
@@ -75,6 +87,7 @@ func init() {
 		ProposalSummary,
 		ValidatorBlockMismatchCounter,
 		BlockRoundSummary,
+		VoteTimeHistogram,
 	} {
 		if err := prometheus.Register(c); err != nil {
 			panic(err)

modules/consensus/handle_block.go

@@ -4,6 +4,7 @@ import (
 	"bytes"
 	"fmt"
 	"strconv"
+	"time"
 
 	sdk "github.com/cosmos/cosmos-sdk/types"
 	"github.com/forbole/bdjuno/v4/modules/actions/logging"
@@ -28,6 +29,8 @@ func (m *Module) HandleBlock(
 	logging.BlockRoundSummary.WithLabelValues(strconv.Itoa(int(b.Block.LastCommit.Round))).Observe(1.0)
 
 	m.countProposalsByValidator(b, vals)
+	m.measureVotingTimes(b)
+
 	return nil
 }
 
@@ -111,3 +114,37 @@ func updateProposerMetric(expected, real tmtypes.Address) {
 	}
 	logging.ProposalSummary.WithLabelValues(sdk.ConsAddress(expected).String()).Observe(value)
 }
+
+func (m *Module) measureVotingTimes(block *tmctypes.ResultBlock) {
+	proposerAddr := block.Block.ProposerAddress.Bytes()
+
+	var proposerVoteTime time.Time
+	for _, s := range block.Block.LastCommit.Signatures {
+		if bytes.Equal(s.ValidatorAddress.Bytes(), proposerAddr) {
+			proposerVoteTime = s.Timestamp
+			break
+		}
+	}
+	proposer := sdk.ConsAddress(block.Block.ProposerAddress).String()
+	for _, s := range block.Block.LastCommit.Signatures {
+		logging.VoteTimeHistogram.WithLabelValues(proposer, sdk.ConsAddress(s.ValidatorAddress).String()).
+			Observe(float64(s.Timestamp.Sub(proposerVoteTime)) / float64(time.Millisecond))
+	}
+}
+
+func (m *Module) measureVotedVotingPower(block *tmctypes.ResultBlock, vals *tmctypes.ResultValidators) {
+	proposerAddr := block.Block.ProposerAddress.Bytes()
+
+	var proposerVoteTime time.Time
+	for _, s := range block.Block.LastCommit.Signatures {
+		if bytes.Equal(s.ValidatorAddress.Bytes(), proposerAddr) {
+			proposerVoteTime = s.Timestamp
+			break
+		}
+	}
+	proposer := sdk.ConsAddress(block.Block.ProposerAddress).String()
+	for _, s := range block.Block.LastCommit.Signatures {
+		logging.VoteTimeHistogram.WithLabelValues(proposer, sdk.ConsAddress(s.ValidatorAddress).String()).
+			Observe(float64(s.Timestamp.Sub(proposerVoteTime)) / float64(time.Millisecond))
+	}
+}