package app import ( "context" "encoding/hex" "errors" "fmt" "strings" "time" "github.com/dgraph-io/badger/v4" "lol.mleku.dev/chk" "lol.mleku.dev/log" "next.orly.dev/pkg/acl" "next.orly.dev/pkg/encoders/bech32encoding" "next.orly.dev/pkg/encoders/envelopes/authenvelope" "next.orly.dev/pkg/encoders/envelopes/closedenvelope" "next.orly.dev/pkg/encoders/envelopes/eoseenvelope" "next.orly.dev/pkg/encoders/envelopes/eventenvelope" "next.orly.dev/pkg/encoders/envelopes/reqenvelope" "next.orly.dev/pkg/encoders/event" "next.orly.dev/pkg/encoders/filter" hexenc "next.orly.dev/pkg/encoders/hex" "next.orly.dev/pkg/encoders/kind" "next.orly.dev/pkg/encoders/reason" "next.orly.dev/pkg/encoders/tag" "next.orly.dev/pkg/utils" "next.orly.dev/pkg/utils/normalize" "next.orly.dev/pkg/utils/pointers" ) func (l *Listener) HandleReq(msg []byte) (err error) { log.D.F("handling REQ: %s", msg) log.T.F("HandleReq: START processing from %s", l.remote) // var rem []byte env := reqenvelope.New() if _, err = env.Unmarshal(msg); chk.E(err) { return normalize.Error.Errorf(err.Error()) } log.T.C( func() string { return fmt.Sprintf( "REQ sub=%s filters=%d", env.Subscription, len(*env.Filters), ) }, ) // send a challenge to the client to auth if an ACL is active or auth is required if acl.Registry.Active.Load() != "none" || l.Config.AuthRequired { if err = authenvelope.NewChallengeWith(l.challenge.Load()). Write(l); chk.E(err) { return } } // check permissions of user accessLevel := acl.Registry.GetAccessLevel(l.authedPubkey.Load(), l.remote) // If auth is required but user is not authenticated, deny access if l.Config.AuthRequired && len(l.authedPubkey.Load()) == 0 { if err = closedenvelope.NewFrom( env.Subscription, reason.AuthRequired.F("authentication required"), ).Write(l); chk.E(err) { return } return } switch accessLevel { case "none": // For REQ denial, send a CLOSED with auth-required reason (NIP-01) if err = closedenvelope.NewFrom( env.Subscription, reason.AuthRequired.F("user not authed or has no read access"), ).Write(l); chk.E(err) { return } return default: // user has read access or better, continue } var events event.S // Create a single context for all filter queries, tied to the connection context, to prevent leaks and support timely cancellation queryCtx, queryCancel := context.WithTimeout( l.ctx, 30*time.Second, ) defer queryCancel() // Collect all events from all filters var allEvents event.S for _, f := range *env.Filters { if f != nil { // Summarize filter details for diagnostics (avoid internal fields) var kindsLen int if f.Kinds != nil { kindsLen = f.Kinds.Len() } var authorsLen int if f.Authors != nil { authorsLen = f.Authors.Len() } var idsLen int if f.Ids != nil { idsLen = f.Ids.Len() } var dtag string if f.Tags != nil { if d := f.Tags.GetFirst([]byte("d")); d != nil { dtag = string(d.Value()) } } var lim any if f.Limit != nil { lim = *f.Limit } var since any if f.Since != nil { since = f.Since.Int() } var until any if f.Until != nil { until = f.Until.Int() } log.T.C( func() string { return fmt.Sprintf( "REQ %s filter: kinds.len=%d authors.len=%d ids.len=%d d=%q limit=%v since=%v until=%v", env.Subscription, kindsLen, authorsLen, idsLen, dtag, lim, since, until, ) }, ) } if f != nil && pointers.Present(f.Limit) { if *f.Limit == 0 { continue } } var filterEvents event.S if filterEvents, err = l.QueryEvents(queryCtx, f); chk.E(err) { if errors.Is(err, badger.ErrDBClosed) { return } log.E.F("QueryEvents failed for filter: %v", err) err = nil continue } // Append events from this filter to the overall collection allEvents = append(allEvents, filterEvents...) } events = allEvents defer func() { for _, ev := range events { ev.Free() } }() var tmp event.S privCheck: for _, ev := range events { // Check for private tag first privateTags := ev.Tags.GetAll([]byte("private")) if len(privateTags) > 0 && accessLevel != "admin" { pk := l.authedPubkey.Load() if pk == nil { continue // no auth, can't access private events } // Convert authenticated pubkey to npub for comparison authedNpub, err := bech32encoding.BinToNpub(pk) if err != nil { continue // couldn't convert pubkey, skip } // Check if authenticated npub is in any private tag authorized := false for _, privateTag := range privateTags { authorizedNpubs := strings.Split( string(privateTag.Value()), ",", ) for _, npub := range authorizedNpubs { if strings.TrimSpace(npub) == string(authedNpub) { authorized = true break } } if authorized { break } } if !authorized { continue // not authorized to see this private event } tmp = append(tmp, ev) continue } if l.Config.ACLMode != "none" && (kind.IsPrivileged(ev.Kind) && accessLevel != "admin") && l.authedPubkey.Load() != nil { // admins can see all events log.T.C( func() string { return fmt.Sprintf( "checking privileged event %0x", ev.ID, ) }, ) pk := l.authedPubkey.Load() if pk == nil { continue } if utils.FastEqual(ev.Pubkey, pk) { log.T.C( func() string { return fmt.Sprintf( "privileged event %s is for logged in pubkey %0x", ev.ID, pk, ) }, ) tmp = append(tmp, ev) continue } pTags := ev.Tags.GetAll([]byte("p")) for _, pTag := range pTags { var pt []byte if pt, err = hexenc.Dec(string(pTag.Value())); chk.E(err) { continue } if utils.FastEqual(pt, pk) { log.T.C( func() string { return fmt.Sprintf( "privileged event %s is for logged in pubkey %0x", ev.ID, pk, ) }, ) tmp = append(tmp, ev) continue privCheck } } log.T.C( func() string { return fmt.Sprintf( "privileged event %s does not contain the logged in pubkey %0x", ev.ID, pk, ) }, ) } else { tmp = append(tmp, ev) } } events = tmp // Apply policy filtering for read access if policy is enabled if l.policyManager != nil && l.policyManager.Manager != nil && l.policyManager.Manager.IsEnabled() { var policyFilteredEvents event.S for _, ev := range events { allowed, policyErr := l.policyManager.CheckPolicy("read", ev, l.authedPubkey.Load(), l.remote) if chk.E(policyErr) { log.E.F("policy check failed for read: %v", policyErr) // Default to allow on policy error policyFilteredEvents = append(policyFilteredEvents, ev) continue } if allowed { policyFilteredEvents = append(policyFilteredEvents, ev) } else { log.D.F("policy filtered out event %0x for read access", ev.ID) } } events = policyFilteredEvents } // Apply managed ACL filtering for read access if managed ACL is active if acl.Registry.Active.Load() == "managed" { var aclFilteredEvents event.S for _, ev := range events { // Check if event is banned eventID := hex.EncodeToString(ev.ID) if banned, err := l.getManagedACL().IsEventBanned(eventID); err == nil && banned { log.D.F("managed ACL filtered out banned event %s", hexenc.Enc(ev.ID)) continue } // Check if event author is banned authorHex := hex.EncodeToString(ev.Pubkey) if banned, err := l.getManagedACL().IsPubkeyBanned(authorHex); err == nil && banned { log.D.F("managed ACL filtered out event %s from banned pubkey %s", hexenc.Enc(ev.ID), authorHex) continue } // Check if event kind is allowed (only if allowed kinds are configured) if allowed, err := l.getManagedACL().IsKindAllowed(int(ev.Kind)); err == nil && !allowed { allowedKinds, err := l.getManagedACL().ListAllowedKinds() if err == nil && len(allowedKinds) > 0 { log.D.F("managed ACL filtered out event %s with disallowed kind %d", hexenc.Enc(ev.ID), ev.Kind) continue } } aclFilteredEvents = append(aclFilteredEvents, ev) } events = aclFilteredEvents } seen := make(map[string]struct{}) for _, ev := range events { log.T.C( func() string { return fmt.Sprintf( "REQ %s: sending EVENT id=%s kind=%d", env.Subscription, hexenc.Enc(ev.ID), ev.Kind, ) }, ) log.T.C( func() string { return fmt.Sprintf("event:\n%s\n", ev.Serialize()) }, ) var res *eventenvelope.Result if res, err = eventenvelope.NewResultWith( env.Subscription, ev, ); chk.E(err) { return } if err = res.Write(l); chk.E(err) { return } // track the IDs we've sent (use hex encoding for stable key) seen[hexenc.Enc(ev.ID)] = struct{}{} } // write the EOSE to signal to the client that all events found have been // sent. log.T.F("sending EOSE to %s", l.remote) if err = eoseenvelope.NewFrom(env.Subscription). Write(l); chk.E(err) { return } // if the query was for just Ids, we know there can't be any more results, // so cancel the subscription. cancel := true log.T.F( "REQ %s: computing cancel/subscription; events_sent=%d", env.Subscription, len(events), ) var subbedFilters filter.S for _, f := range *env.Filters { if f.Ids.Len() < 1 { cancel = false subbedFilters = append(subbedFilters, f) } else { // remove the IDs that we already sent var notFounds [][]byte for _, id := range f.Ids.T { if _, ok := seen[hexenc.Enc(id)]; ok { continue } notFounds = append(notFounds, id) } log.T.F( "REQ %s: ids outstanding=%d of %d", env.Subscription, len(notFounds), f.Ids.Len(), ) // if all were found, don't add to subbedFilters if len(notFounds) == 0 { continue } // rewrite the filter Ids to remove the ones we already sent f.Ids = tag.NewFromBytesSlice(notFounds...) // add the filter to the list of filters we're subscribing to subbedFilters = append(subbedFilters, f) } // also, if we received the limit number of events, subscription ded if pointers.Present(f.Limit) { if len(events) >= int(*f.Limit) { cancel = true } } } receiver := make(event.C, 32) // if the subscription should be cancelled, do so if !cancel { l.publishers.Receive( &W{ Conn: l.conn, remote: l.remote, Id: string(env.Subscription), Receiver: receiver, Filters: env.Filters, AuthedPubkey: l.authedPubkey.Load(), }, ) } else { // suppress server-sent CLOSED; client will close subscription if desired } log.T.F("HandleReq: COMPLETED processing from %s", l.remote) return }