[OCM] invite cannot be accepted after it was once deleted

[jnweiger]

Describe the bug

Setup two machines with e.g.

env OCIS_DNSNAME=cat-700-DATE OCIS_VERSION=v7.0.0 ./deploy_ocis_bare_metal.sh
env OCIS_DNSNAME=dog-700-DATE OCIS_VERSION=v7.0.0 ./deploy_ocis_bare_metal.sh

• The machines are mutually registered in /etc/ocis/ocmproviders.json .
• User bob at cat visits the sciencemesh app and create an invite token for user carol at dog.
• User carol accepts the invite.
• User carol (accidentially) deletes the federated connection.
• User carol tries to accept the same token again (while the token is still valid), but fails. BAD
• User bob creates a fresh invite token and passes that to carol.
• User carol tries to accept the new token, but fails again. BAD

Expected behavior

• When one user deletes a connection, the connection should disappear for the other user too.
• As long as the token is valid, carol should be able to accept and delete as often as she likes.
• When bob creates a fresh invite token, this token should work.

Actual behavior

• An error occurs when trying to accept a token a second time. BAD
• The error is meaningless to a end user, and of little value to an admin. BAD
• bob still sees a perfectly fine connection with carol, even after carol deleted it. This is inconsistent. BAD

Workaround

• User bob must delete the federated connection also from his side.
• Then he can create a fresh token and pass it to carol.
• Now carol and bob are again connected, and can start sharing.

Logs excerpt

root@dog-700rc5:~# journalctl -u ocis  | grep 648dcbbe-d4e1-479b-8ed5-e2b6306e96f2
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"ocm","pkg":"rhttp","traceid":"84fdc7ffb17aeed12bd5693b28aefab5","request-id":"648dcbbe-d4e1-479b-8ed5-e2b6306e96f2","path":"/accept-invite","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/http/services/sciencemesh/sciencemesh.go:139","message":"sciencemesh routing"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"info","service":"proxy","proto":"HTTP/1.1","request-id":"648dcbbe-d4e1-479b-8ed5-e2b6306e96f2","traceid":"83203707e791769225fb0fbb74c63572","remote-addr":"217.84.12.64","method":"POST","status":409,"path":"/sciencemesh/accept-invite","duration":32.077332,"bytes":64,"time":"2024-12-30T17:37:55Z","line":"github.com/owncloud/ocis/v2/services/proxy/pkg/middleware/accesslog.go:34","message":"access-log"}

root@dog-700rc5:~# journalctl -u ocis  | grep 83203707e791769225fb0fbb74c63572
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","method":"/cs3.gateway.v1beta1.GatewayAPI/Authenticate","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","method":"/cs3.auth.registry.v1beta1.RegistryAPI/GetAuthProviders","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:60612","uri":"/cs3.auth.registry.v1beta1.RegistryAPI/GetAuthProviders","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":34035,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"auth-machine","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","method":"/cs3.auth.provider.v1beta1.ProviderAPI/Authenticate","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","method":"/cs3.gateway.v1beta1.GatewayAPI/GetUserByClaim","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","method":"/cs3.identity.user.v1beta1.UserAPI/GetUserByClaim","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","claim":"username","value":"carol","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/pkg/user/manager/ldap/ldap.go:137","message":"GetUserByClaim"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","backend":"ldap","basedn":"ou=users,o=libregraph-idm","filter":"(&(objectclass=inetOrgPerson)(uid=carol)(!(ownCloudUserEnabled=FALSE)))","scope":2,"time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/pkg/utils/ldap/identity.go:217","message":"LDAP Search"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","entry":{"DN":"uid=carol,ou=users,o=libregraph-idm","Attributes":[{"Name":"displayName","Values":["Carol Lewis"],"ByteValues":["Q2Fyb2wgTGV3aXM="]},{"Name":"mail","Values":["[email protected]"],"ByteValues":["Y2Fyb2xAbGV3aXMub3Jn"]},{"Name":"ownCloudUserType","Values":["Member"],"ByteValues":["TWVtYmVy"]},{"Name":"owncloudUUID","Values":["c8ca39f0-4ff8-4da6-96a5-0f9fa20aebcc"],"ByteValues":["YzhjYTM5ZjAtNGZmOC00ZGE2LTk2YTUtMGY5ZmEyMGFlYmNj"]},{"Name":"uid","Values":["carol"],"ByteValues":["Y2Fyb2w="]}]},"time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/pkg/user/manager/ldap/ldap.go:144","message":"entries"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","backend":"ldap","basedn":"ou=groups,o=libregraph-idm","filter":"(&(objectclass=groupOfNames)(member=uid=carol,ou=users,o=libregraph-idm))","scope":2,"time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/pkg/utils/ldap/identity.go:349","message":"LDAP Search"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:57168","uri":"/cs3.identity.user.v1beta1.UserAPI/GetUserByClaim","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":2912217,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:58500","uri":"/cs3.gateway.v1beta1.GatewayAPI/GetUserByClaim","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":3453019,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"info","service":"auth-machine","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/services/authprovider/authprovider.go:146","message":"user idp:\"https://dog-700rc5-20241217.jw-qa.owncloud.works\" opaque_id:\"c8ca39f0-4ff8-4da6-96a5-0f9fa20aebcc\" type:USER_TYPE_PRIMARY authenticated"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"auth-machine","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:39326","uri":"/cs3.auth.provider.v1beta1.ProviderAPI/Authenticate","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":4165304,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:58500","uri":"/cs3.gateway.v1beta1.GatewayAPI/Authenticate","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":5377245,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","method":"/cs3.gateway.v1beta1.GatewayAPI/CreateHome","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:60618","uri":"/cs3.storage.registry.v1beta1.RegistryAPI/GetStorageProviders","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":160501,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:58500","uri":"/cs3.gateway.v1beta1.GatewayAPI/CreateHome","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":1179099,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","method":"/cs3.gateway.v1beta1.GatewayAPI/GetInfoByDomain","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"ocm","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:131.0) Gecko/20100101 Firefox/131.0","from":"tcp://127.0.0.1:41516","uri":"/cs3.ocm.provider.v1beta1.ProviderAPI/GetInfoByDomain","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":99616,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:131.0) Gecko/20100101 Firefox/131.0","from":"tcp://127.0.0.1:58500","uri":"/cs3.gateway.v1beta1.GatewayAPI/GetInfoByDomain","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":1021284,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","method":"/cs3.gateway.v1beta1.GatewayAPI/ForwardInvite","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"ocm","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:131.0) Gecko/20100101 Firefox/131.0","from":"tcp://127.0.0.1:41592","uri":"/cs3.ocm.invite.v1beta1.InviteAPI/ForwardInvite","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":16764261,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"83203707e791769225fb0fbb74c63572","user-agent":"Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:131.0) Gecko/20100101 Firefox/131.0","from":"tcp://127.0.0.1:58500","uri":"/cs3.gateway.v1beta1.GatewayAPI/ForwardInvite","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":17832543,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 dog-700rc5 ocis[6802]: {"level":"info","service":"proxy","proto":"HTTP/1.1","request-id":"648dcbbe-d4e1-479b-8ed5-e2b6306e96f2","traceid":"83203707e791769225fb0fbb74c63572","remote-addr":"217.84.12.64","method":"POST","status":409,"path":"/sciencemesh/accept-invite","duration":32.077332,"bytes":64,"time":"2024-12-30T17:37:55Z","line":"github.com/owncloud/ocis/v2/services/proxy/pkg/middleware/accesslog.go:34","message":"access-log"}

FYI: @hodyroff

[jnweiger]

The other machine also logs a 409 around the same time. Possibly related:

root@cat-ocis7rc5:~# journalctl -u ocis | grep '2024-12-30T17:37:55Z' 
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"proxy","policy":"ocis","method":"POST","prefix":"/ocm/","path":"/ocm/invite-accepted","routeType":"prefix","time":"2024-12-30T17:37:55Z","line":"github.com/owncloud/ocis/v2/services/proxy/pkg/router/router.go:222","message":"rewrite hook found"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"info","service":"ocm","pkg":"rhttp","traceid":"f52ac45d12c90e0fc3242ed7f0d4d500","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/http/interceptors/auth/auth.go:195","message":"skipping auth check for: /ocm/invite-accepted"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"warn","service":"ocm","pkg":"rhttp","traceid":"f52ac45d12c90e0fc3242ed7f0d4d500","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/http/interceptors/auth/auth.go:248","message":"core access token not set"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"info","service":"ocm","pkg":"rhttp","traceid":"f52ac45d12c90e0fc3242ed7f0d4d500","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/http/interceptors/providerauthorizer/providerauthorizer.go:83","message":"skipping provider authorizer check for: /ocm/invite-accepted"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"ocm","pkg":"rhttp","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/pkg/rhttp/rhttp.go:248","message":"http routing: head=ocm tail=/invite-accepted svc=ocm"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"ocm","pkg":"rhttp","traceid":"f52ac45d12c90e0fc3242ed7f0d4d500","request-id":"cat-ocis7rc5/wnlXdXtkMO-008032","path":"/invite-accepted","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/http/services/ocmd/ocm.go:112","message":"ocm routing"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","method":"/cs3.gateway.v1beta1.GatewayAPI/IsProviderAllowed","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"ocm","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","method":"/cs3.ocm.provider.v1beta1.ProviderAPI/IsProviderAllowed","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"ocm","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:55666","uri":"/cs3.ocm.provider.v1beta1.ProviderAPI/IsProviderAllowed","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":34406,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:51820","uri":"/cs3.gateway.v1beta1.GatewayAPI/IsProviderAllowed","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":475746,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","method":"/cs3.gateway.v1beta1.GatewayAPI/AcceptInvite","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"ocm","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","method":"/cs3.ocm.invite.v1beta1.InviteAPI/AcceptInvite","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","method":"/cs3.gateway.v1beta1.GatewayAPI/GetUser","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","method":"/cs3.identity.user.v1beta1.UserAPI/GetUser","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/auth/auth.go:122","message":"skipping auth"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","id":{"idp":"https://cat-700rc5-20241217.jw-qa.owncloud.works","opaque_id":"258b3742-e486-4833-b5ea-e8ee1e293e72","type":1},"time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/pkg/user/manager/ldap/ldap.go:101","message":"GetUser"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","backend":"ldap","basedn":"ou=users,o=libregraph-idm","filter":"(&(objectclass=inetOrgPerson)(ownclouduuid=258b3742-e486-4833-b5ea-e8ee1e293e72))","scope":2,"time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/pkg/utils/ldap/identity.go:217","message":"LDAP Search"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"idm","attrs":["displayname","ownclouduuid","mail","uid","uidNumber","gidNumber","ownCloudUserEnabled","ownCloudUserType"],"basedn":"ou=users,o=libregraph-idm","binddn":"uid=reva,ou=sysusers,o=libregraph-idm","filter":"(&(objectclass=inetOrgPerson)(ownclouduuid=258b3742-e486-4833-b5ea-e8ee1e293e72))","op":"search","time":"2024-12-30T17:37:55Z","line":"github.com/owncloud/ocis/v2/ocis-pkg/log/logrus_wrapper.go:50","message":"Calling boltdb search"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"idm","attrs":["displayname","ownclouduuid","mail","uid","uidNumber","gidNumber","ownCloudUserEnabled","ownCloudUserType"],"basedn":"ou=users,o=libregraph-idm","binddn":"uid=reva,ou=sysusers,o=libregraph-idm","filter":"(&(objectclass=inetOrgPerson)(ownclouduuid=258b3742-e486-4833-b5ea-e8ee1e293e72))","op":"search","time":"2024-12-30T17:37:55Z","line":"github.com/owncloud/ocis/v2/ocis-pkg/log/logrus_wrapper.go:50","message":"boltdb search returned 11 entries"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","entry":{"DN":"uid=bob,ou=users,o=libregraph-idm","Attributes":[{"Name":"displayName","Values":["Bob Builder"],"ByteValues":["Qm9iIEJ1aWxkZXI="]},{"Name":"mail","Values":["[email protected]"],"ByteValues":["Ym9iQGJ1aWxkZXIub3Jn"]},{"Name":"ownCloudUserType","Values":["Member"],"ByteValues":["TWVtYmVy"]},{"Name":"owncloudUUID","Values":["258b3742-e486-4833-b5ea-e8ee1e293e72"],"ByteValues":["MjU4YjM3NDItZTQ4Ni00ODMzLWI1ZWEtZThlZTFlMjkzZTcy"]},{"Name":"uid","Values":["bob"],"ByteValues":["Ym9i"]}]},"time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/pkg/user/manager/ldap/ldap.go:112","message":"entries"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","backend":"ldap","basedn":"ou=groups,o=libregraph-idm","filter":"(&(objectclass=groupOfNames)(member=uid=bob,ou=users,o=libregraph-idm))","scope":2,"time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/pkg/utils/ldap/identity.go:349","message":"LDAP Search"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"idm","attrs":["ownclouduuid"],"basedn":"ou=groups,o=libregraph-idm","binddn":"uid=reva,ou=sysusers,o=libregraph-idm","filter":"(&(objectclass=groupOfNames)(member=uid=bob,ou=users,o=libregraph-idm))","op":"search","time":"2024-12-30T17:37:55Z","line":"github.com/owncloud/ocis/v2/ocis-pkg/log/logrus_wrapper.go:50","message":"Calling boltdb search"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"idm","attrs":["ownclouduuid"],"basedn":"ou=groups,o=libregraph-idm","binddn":"uid=reva,ou=sysusers,o=libregraph-idm","filter":"(&(objectclass=groupOfNames)(member=uid=bob,ou=users,o=libregraph-idm))","op":"search","time":"2024-12-30T17:37:55Z","line":"github.com/owncloud/ocis/v2/ocis-pkg/log/logrus_wrapper.go:50","message":"boltdb search returned 9 entries"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"users","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:46672","uri":"/cs3.identity.user.v1beta1.UserAPI/GetUser","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":1945545,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:51820","uri":"/cs3.gateway.v1beta1.GatewayAPI/GetUser","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":2393539,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"ocm","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:43614","uri":"/cs3.ocm.invite.v1beta1.InviteAPI/AcceptInvite","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":2891117,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"debug","service":"gateway","pkg":"rgrpc","traceid":"893bbc4a237491b7e016fcaecd4206cd","user-agent":"grpc-go/1.68.0","from":"tcp://127.0.0.1:51820","uri":"/cs3.gateway.v1beta1.GatewayAPI/AcceptInvite","start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":3395306,"code":"OK","time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/grpc/interceptors/log/log.go:69","message":"unary"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"warn","service":"ocm","pkg":"rhttp","traceid":"f52ac45d12c90e0fc3242ed7f0d4d500","host":"127.0.0.1","method":"POST","uri":"/ocm/invite-accepted","url":"/invite-accepted","proto":"HTTP/1.1","status":409,"size":64,"start":"30/Dec/2024:17:37:55 +0000","end":"30/Dec/2024:17:37:55 +0000","time_ns":5154591,"time":"2024-12-30T17:37:55Z","line":"github.com/cs3org/reva/[email protected]/internal/http/interceptors/log/log.go:112","message":"http"}
Dec 30 17:37:55 cat-ocis7rc5 ocis[10005]: {"level":"info","service":"proxy","proto":"HTTP/1.1","request-id":"cat-ocis7rc5/wnlXdXtkMO-008031","traceid":"893bbc4a237491b7e016fcaecd4206cd","remote-addr":"37.27.15.205","method":"POST","status":409,"path":"/ocm/invite-accepted","duration":5.814344,"bytes":64,"time":"2024-12-30T17:37:55Z","line":"github.com/owncloud/ocis/v2/services/proxy/pkg/middleware/accesslog.go:34","message":"access-log"}

[hodyroff]

@kobergj can we work on this?
cc: @marcelmockkw

[marcelmockkw]

we should start working on it ASAP, because it's also part of the Jan 15th release for ByCS.