diff --git a/jwt/jwt_signer_factory.go b/jwt/jwt_signer_factory.go index 631e62f..bb1cd14 100644 --- a/jwt/jwt_signer_factory.go +++ b/jwt/jwt_signer_factory.go @@ -18,7 +18,10 @@ import ( "fmt" "github.com/tink-crypto/tink-go/v2/core/primitiveset" + "github.com/tink-crypto/tink-go/v2/internal/internalregistry" + "github.com/tink-crypto/tink-go/v2/internal/monitoringutil" "github.com/tink-crypto/tink-go/v2/keyset" + "github.com/tink-crypto/tink-go/v2/monitoring" tinkpb "github.com/tink-crypto/tink-go/v2/proto/tink_go_proto" ) @@ -36,11 +39,28 @@ func NewSigner(handle *keyset.Handle) (Signer, error) { // wrappedSigner is a JWT Signer implementation that uses the underlying primitive set for JWT Sign. type wrappedSigner struct { - ps *primitiveset.PrimitiveSet + ps *primitiveset.PrimitiveSet + logger monitoring.Logger } var _ Signer = (*wrappedSigner)(nil) +func createSignerLogger(ps *primitiveset.PrimitiveSet) (monitoring.Logger, error) { + // only keysets which contain annotations are monitored. + if len(ps.Annotations) == 0 { + return &monitoringutil.DoNothingLogger{}, nil + } + keysetInfo, err := monitoringutil.KeysetInfoFromPrimitiveSet(ps) + if err != nil { + return nil, err + } + return internalregistry.GetMonitoringClient().NewLogger(&monitoring.Context{ + KeysetInfo: keysetInfo, + Primitive: "jwtsign", + APIFunction: "sign", + }) +} + func newWrappedSigner(ps *primitiveset.PrimitiveSet) (*wrappedSigner, error) { if _, ok := (ps.Primary.Primitive).(*signerWithKID); !ok { return nil, fmt.Errorf("jwt_signer_factory: not a JWT Signer primitive") @@ -55,7 +75,14 @@ func newWrappedSigner(ps *primitiveset.PrimitiveSet) (*wrappedSigner, error) { } } } - return &wrappedSigner{ps: ps}, nil + logger, err := createSignerLogger(ps) + if err != nil { + return nil, err + } + return &wrappedSigner{ + ps: ps, + logger: logger, + }, nil } func (w *wrappedSigner) SignAndEncode(rawJWT *RawJWT) (string, error) { @@ -64,5 +91,11 @@ func (w *wrappedSigner) SignAndEncode(rawJWT *RawJWT) (string, error) { if !ok { return "", fmt.Errorf("jwt_signer_factory: not a JWT Signer primitive") } - return p.SignAndEncodeWithKID(rawJWT, keyID(primary.KeyID, primary.PrefixType)) + token, err := p.SignAndEncodeWithKID(rawJWT, keyID(primary.KeyID, primary.PrefixType)) + if err != nil { + w.logger.LogFailure() + return "", err + } + w.logger.Log(primary.KeyID, 1) + return token, nil } diff --git a/jwt/jwt_signer_verifier_factory_test.go b/jwt/jwt_signer_verifier_factory_test.go index ad6fc9f..10e166e 100644 --- a/jwt/jwt_signer_verifier_factory_test.go +++ b/jwt/jwt_signer_verifier_factory_test.go @@ -15,16 +15,22 @@ package jwt_test import ( + "bytes" "crypto/ecdsa" "crypto/elliptic" "crypto/rand" "fmt" "testing" + "github.com/google/go-cmp/cmp" "google.golang.org/protobuf/proto" + "github.com/tink-crypto/tink-go/v2/insecurecleartextkeyset" + "github.com/tink-crypto/tink-go/v2/internal/internalregistry" "github.com/tink-crypto/tink-go/v2/jwt" "github.com/tink-crypto/tink-go/v2/keyset" + "github.com/tink-crypto/tink-go/v2/monitoring" "github.com/tink-crypto/tink-go/v2/signature" + "github.com/tink-crypto/tink-go/v2/testing/fakemonitoring" "github.com/tink-crypto/tink-go/v2/testkeyset" "github.com/tink-crypto/tink-go/v2/testutil" jepb "github.com/tink-crypto/tink-go/v2/proto/jwt_ecdsa_go_proto" @@ -374,3 +380,260 @@ func TestFactorySignVerifyWithKIDSuccess(t *testing.T) { }) } } + +func TestFactorySignVerifyWithoutAnnotationsEmitsNoMonitoring(t *testing.T) { + defer internalregistry.ClearMonitoringClient() + client := fakemonitoring.NewClient("fake-client") + if err := internalregistry.RegisterMonitoringClient(client); err != nil { + t.Fatalf("internalregistry.RegisterMonitoringClient() err = %v, want nil", err) + } + privHandle, err := keyset.NewHandle(jwt.ES256Template()) + if err != nil { + t.Fatalf("keyset.NewHandle() err = %v, want nil", err) + } + pubHandle, err := privHandle.Public() + if err != nil { + t.Fatalf("privHandle.Public() err = %v, want nil", err) + } + signer, err := jwt.NewSigner(privHandle) + if err != nil { + t.Fatalf("jwt.NewSigner() err = %v, want nil", err) + } + verifier, err := jwt.NewVerifier(pubHandle) + if err != nil { + t.Fatalf("jwt.NewVerifier() err = %v, want nil", err) + } + rawJWT, err := jwt.NewRawJWT(&jwt.RawJWTOptions{WithoutExpiration: true}) + if err != nil { + t.Fatalf("jwt.NewRawJWT() err = %v, want nil", err) + } + validator, err := jwt.NewValidator(&jwt.ValidatorOpts{AllowMissingExpiration: true}) + if err != nil { + t.Fatalf("jwt.NewValidator() err = %v, want nil", err) + } + compact, err := signer.SignAndEncode(rawJWT) + if err != nil { + t.Fatalf("signer.SignAndEncode() err = %v, want nil", err) + } + if _, err := verifier.VerifyAndDecode(compact, validator); err != nil { + t.Fatalf("verifier.VerifyAndDecode() err = %v, want nil", err) + } + if len(client.Events()) != 0 { + t.Errorf("len(client.Events()) = %d, want 0", len(client.Events())) + } + if len(client.Failures()) != 0 { + t.Errorf("len(client.Failures()) = %d, want 0", len(client.Failures())) + } +} + +func TestFactorySignWithAnnotationsEmitsMonitoringSuccess(t *testing.T) { + defer internalregistry.ClearMonitoringClient() + client := fakemonitoring.NewClient("fake-client") + if err := internalregistry.RegisterMonitoringClient(client); err != nil { + t.Fatalf("internalregistry.RegisterMonitoringClient() err = %v, want nil", err) + } + handle, err := keyset.NewHandle(jwt.ES256Template()) + if err != nil { + t.Fatalf("keyset.NewHandle() err = %v, want nil", err) + } + buff := &bytes.Buffer{} + if err := insecurecleartextkeyset.Write(handle, keyset.NewBinaryWriter(buff)); err != nil { + t.Fatalf("insecurecleartextkeyset.Write() err = %v, want nil", err) + } + annotations := map[string]string{"foo": "bar"} + privHandle, err := insecurecleartextkeyset.Read(keyset.NewBinaryReader(buff), keyset.WithAnnotations(annotations)) + if err != nil { + t.Fatalf("insecurecleartextkeyset.Read() err = %v, want nil", err) + } + // Verify annotations aren't propagated. + pubHandle, err := privHandle.Public() + if err != nil { + t.Fatalf("privHandle.Public() err = %v, want nil", err) + } + signer, err := jwt.NewSigner(privHandle) + if err != nil { + t.Fatalf("jwt.NewSigner() err = %v, want nil", err) + } + verifier, err := jwt.NewVerifier(pubHandle) + if err != nil { + t.Fatalf("jwt.NewVerifier() err = %v, want nil", err) + } + rawJWT, err := jwt.NewRawJWT(&jwt.RawJWTOptions{WithoutExpiration: true}) + if err != nil { + t.Fatalf("jwt.NewRawJWT() err = %v, want nil", err) + } + validator, err := jwt.NewValidator(&jwt.ValidatorOpts{AllowMissingExpiration: true}) + if err != nil { + t.Fatalf("jwt.NewValidator() err = %v, want nil", err) + } + compact, err := signer.SignAndEncode(rawJWT) + if err != nil { + t.Fatalf("signer.SignAndEncode() err = %v, want nil", err) + } + if _, err := verifier.VerifyAndDecode(compact, validator); err != nil { + t.Fatalf("verifier.VerifyAndDecode() err = %v, want nil", err) + } + // verify error emits no monitoring. + if _, err := verifier.VerifyAndDecode("invalid", validator); err == nil { + t.Fatalf("verifier.VerifyAndDecode() err = %v, want nil", err) + } + if len(client.Failures()) != 0 { + t.Errorf("len(client.Failures()) = %d, want 0", len(client.Failures())) + } + if len(client.Events()) != 1 { + t.Errorf("len(client.Events()) = %d, want 1", len(client.Events())) + } + got := client.Events() + wantSignKeysetInfo := &monitoring.KeysetInfo{ + Annotations: annotations, + PrimaryKeyID: privHandle.KeysetInfo().GetPrimaryKeyId(), + Entries: []*monitoring.Entry{ + { + KeyID: privHandle.KeysetInfo().GetPrimaryKeyId(), + Status: monitoring.Enabled, + KeyType: "tink.JwtEcdsaPrivateKey", + KeyPrefix: "TINK", + }, + }, + } + want := []*fakemonitoring.LogEvent{ + { + Context: monitoring.NewContext("jwtsign", "sign", wantSignKeysetInfo), + KeyID: privHandle.KeysetInfo().GetPrimaryKeyId(), + NumBytes: 1, + }, + } + if diff := cmp.Diff(want, got); diff != "" { + t.Errorf("%v", diff) + } +} + +func TestFactoryVerifyWithAnnotationsEmitsMonitoringSuccess(t *testing.T) { + defer internalregistry.ClearMonitoringClient() + client := fakemonitoring.NewClient("fake-client") + if err := internalregistry.RegisterMonitoringClient(client); err != nil { + t.Fatalf("internalregistry.RegisterMonitoringClient() err = %v, want nil", err) + } + privHandle, err := keyset.NewHandle(jwt.ES256Template()) + if err != nil { + t.Fatalf("keyset.NewHandle() err = %v, want nil", err) + } + signer, err := jwt.NewSigner(privHandle) + if err != nil { + t.Fatalf("jwt.NewSigner() err = %v, want nil", err) + } + + pubHandle, err := privHandle.Public() + if err != nil { + t.Fatalf("keyset.NewHandle() err = %v, want nil", err) + } + buff := &bytes.Buffer{} + if err := insecurecleartextkeyset.Write(pubHandle, keyset.NewBinaryWriter(buff)); err != nil { + t.Fatalf("insecurecleartextkeyset.Write() err = %v, want nil", err) + } + annotations := map[string]string{"foo": "bar"} + pubHandle, err = insecurecleartextkeyset.Read(keyset.NewBinaryReader(buff), keyset.WithAnnotations(annotations)) + if err != nil { + t.Fatalf("insecurecleartextkeyset.Read() err = %v, want nil", err) + } + verifier, err := jwt.NewVerifier(pubHandle) + if err != nil { + t.Fatalf("jwt.NewVerifier() err = %v, want nil", err) + } + rawJWT, err := jwt.NewRawJWT(&jwt.RawJWTOptions{WithoutExpiration: true}) + if err != nil { + t.Fatalf("jwt.NewRawJWT() err = %v, want nil", err) + } + validator, err := jwt.NewValidator(&jwt.ValidatorOpts{AllowMissingExpiration: true}) + if err != nil { + t.Fatalf("jwt.NewValidator() err = %v, want nil", err) + } + compact, err := signer.SignAndEncode(rawJWT) + if err != nil { + t.Fatalf("signer.SignAndEncode() err = %v, want nil", err) + } + if _, err := verifier.VerifyAndDecode(compact, validator); err != nil { + t.Fatalf("verifier.VerifyAndDecode() err = %v, want nil", err) + } + if len(client.Failures()) != 0 { + t.Errorf("len(client.Failures()) = %d, want 0", len(client.Failures())) + } + if len(client.Events()) != 1 { + t.Errorf("len(client.Events()) = %d, want 1", len(client.Events())) + } + got := client.Events() + wantSignKeysetInfo := &monitoring.KeysetInfo{ + Annotations: annotations, + PrimaryKeyID: privHandle.KeysetInfo().GetPrimaryKeyId(), + Entries: []*monitoring.Entry{ + { + KeyID: privHandle.KeysetInfo().GetPrimaryKeyId(), + Status: monitoring.Enabled, + KeyType: "tink.JwtEcdsaPublicKey", + KeyPrefix: "TINK", + }, + }, + } + want := []*fakemonitoring.LogEvent{ + { + Context: monitoring.NewContext("jwtverify", "verify", wantSignKeysetInfo), + KeyID: privHandle.KeysetInfo().GetPrimaryKeyId(), + NumBytes: 1, + }, + } + if diff := cmp.Diff(want, got); diff != "" { + t.Errorf("%v", diff) + } +} + +func TestFactorySignAndVerifyWithAnnotationsEmitsMonitoringOnError(t *testing.T) { + defer internalregistry.ClearMonitoringClient() + client := fakemonitoring.NewClient("fake-client") + if err := internalregistry.RegisterMonitoringClient(client); err != nil { + t.Fatalf("internalregistry.RegisterMonitoringClient() err = %v, want nil", err) + } + kid := "intrusive_kid" + _, privHandle, pubHandle := createKeyAndKeyHandles(t, &kid, tinkpb.OutputPrefixType_TINK) + buff := &bytes.Buffer{} + if err := insecurecleartextkeyset.Write(privHandle, keyset.NewBinaryWriter(buff)); err != nil { + t.Fatalf("insecurecleartextkeyset.Write() err = %v, want nil", err) + } + annotations := map[string]string{"foo": "bar"} + privHandle, err := insecurecleartextkeyset.Read(keyset.NewBinaryReader(buff), keyset.WithAnnotations(annotations)) + if err != nil { + t.Fatalf("insecurecleartextkeyset.Read() err = %v, want nil", err) + } + signer, err := jwt.NewSigner(privHandle) + if err != nil { + t.Fatalf("jwt.NewSigner() err = %v, want nil", err) + } + buff.Reset() + if err := insecurecleartextkeyset.Write(pubHandle, keyset.NewBinaryWriter(buff)); err != nil { + t.Fatalf("insecurecleartextkeyset.Write() err = %v, want nil", err) + } + pubHandle, err = insecurecleartextkeyset.Read(keyset.NewBinaryReader(buff), keyset.WithAnnotations(annotations)) + if err != nil { + t.Fatalf("insecurecleartextkeyset.Read() err = %v, want nil", err) + } + verifier, err := jwt.NewVerifier(pubHandle) + if err != nil { + t.Fatalf("jwt.NewVerifier() err = %v, want nil", err) + } + rawJWT, err := jwt.NewRawJWT(&jwt.RawJWTOptions{WithoutExpiration: true}) + if err != nil { + t.Fatalf("jwt.NewRawJWT() err = %v, want nil", err) + } + validator, err := jwt.NewValidator(&jwt.ValidatorOpts{AllowMissingExpiration: true}) + if err != nil { + t.Fatalf("jwt.NewValidator() err = %v, want nil", err) + } + if _, err := signer.SignAndEncode(rawJWT); err == nil { + t.Fatalf("signer.SignAndEncode() err = nil, want error") + } + if _, err := verifier.VerifyAndDecode("invalid_token", validator); err == nil { + t.Fatalf("verifier.VerifyAndDecode() err = nil want error") + } + if len(client.Failures()) != 2 { + t.Errorf("len(client.Failures()) = %d, want 2", len(client.Failures())) + } +} diff --git a/jwt/jwt_verifier_factory.go b/jwt/jwt_verifier_factory.go index 5fe230f..a15ca0f 100644 --- a/jwt/jwt_verifier_factory.go +++ b/jwt/jwt_verifier_factory.go @@ -18,7 +18,10 @@ import ( "fmt" "github.com/tink-crypto/tink-go/v2/core/primitiveset" + "github.com/tink-crypto/tink-go/v2/internal/internalregistry" + "github.com/tink-crypto/tink-go/v2/internal/monitoringutil" "github.com/tink-crypto/tink-go/v2/keyset" + "github.com/tink-crypto/tink-go/v2/monitoring" tinkpb "github.com/tink-crypto/tink-go/v2/proto/tink_go_proto" ) @@ -36,11 +39,28 @@ func NewVerifier(handle *keyset.Handle) (Verifier, error) { // wrappedVerifier is a JWT Verifier implementation that uses the underlying primitive set for JWT Verifier. type wrappedVerifier struct { - ps *primitiveset.PrimitiveSet + ps *primitiveset.PrimitiveSet + logger monitoring.Logger } var _ Verifier = (*wrappedVerifier)(nil) +func createVerifierLogger(ps *primitiveset.PrimitiveSet) (monitoring.Logger, error) { + // only keysets which contain annotations are monitored. + if len(ps.Annotations) == 0 { + return &monitoringutil.DoNothingLogger{}, nil + } + keysetInfo, err := monitoringutil.KeysetInfoFromPrimitiveSet(ps) + if err != nil { + return nil, err + } + return internalregistry.GetMonitoringClient().NewLogger(&monitoring.Context{ + KeysetInfo: keysetInfo, + Primitive: "jwtverify", + APIFunction: "verify", + }) +} + func newWrappedVerifier(ps *primitiveset.PrimitiveSet) (*wrappedVerifier, error) { if _, ok := (ps.Primary.Primitive).(*verifierWithKID); !ok { return nil, fmt.Errorf("jwt_verifier_factory: not a JWT Verifier primitive") @@ -55,7 +75,14 @@ func newWrappedVerifier(ps *primitiveset.PrimitiveSet) (*wrappedVerifier, error) } } } - return &wrappedVerifier{ps: ps}, nil + logger, err := createVerifierLogger(ps) + if err != nil { + return nil, err + } + return &wrappedVerifier{ + ps: ps, + logger: logger, + }, nil } func (w *wrappedVerifier) VerifyAndDecode(compact string, validator *Validator) (*VerifiedJWT, error) { @@ -68,6 +95,7 @@ func (w *wrappedVerifier) VerifyAndDecode(compact string, validator *Validator) } verifiedJWT, err := p.VerifyAndDecodeWithKID(compact, validator, keyID(e.KeyID, e.PrefixType)) if err == nil { + w.logger.Log(e.KeyID, 1) return verifiedJWT, nil } if err != errJwtVerification { @@ -76,6 +104,7 @@ func (w *wrappedVerifier) VerifyAndDecode(compact string, validator *Validator) } } } + w.logger.LogFailure() if interestingErr != nil { return nil, interestingErr }