error fetching remote user details when CERNBox accesses a mesh.pondersource.com-hosted share

[michielbdejong]

on mesh.pondersource.com:

>> ocm-find-accepted-users
+----------+-----------------------+---------------------------+--------------------+
| OPAQUEID | IDP                   | MAIL                      | DISPLAYNAME        |
+----------+-----------------------+---------------------------+--------------------+
| lopresti | sm1.cernbox.cern.ch   | giuseppe.lopresti@cern.ch | Giuseppe Lo Presti |
| einstein | mesh.pondersource.org |                           | einstein           |
+----------+-----------------------+---------------------------+--------------------+
>> ocm-share-create -idp sm1.cernbox.cern.ch -grantee lopresti /home/this-should-propfind-to-reva

Then do:

curl -v  -X PROPFIND https://mesh.pondersource.com/remote.php/dav/ocm/BBc8z3piEMXNVhKMPhSyL2WslCpq78YN

In the mesh.pondersource.com logs you see:

2023-06-15 09:54:29.948 INF ../reva/internal/http/interceptors/auth/auth.go:172 > skipping auth check for: /remote.php/dav/ocm/BBc8z3piEMXNVhKMPhSyL2WslCpq78YN pid=315832 pkg=rhttp traceid=2b0b70d933404655b20bfbfa14bb435d
2023-06-15 09:54:29.949 WRN ../reva/internal/http/interceptors/auth/auth.go:218 > core access token not set pid=315832 pkg=rhttp traceid=2b0b70d933404655b20bfbfa14bb435d
2023-06-15 09:54:29.949 DBG ../reva/internal/http/interceptors/auth/auth.go:227 > error retrieving credentials error="no basic auth provided" pid=315832 pkg=rhttp traceid=2b0b70d933404655b20bfbfa14bb435d
2023-06-15 09:54:29.95 DBG ../reva/internal/http/interceptors/auth/auth.go:227 > error retrieving credentials error="no bearer auth provided" pid=315832 pkg=rhttp traceid=2b0b70d933404655b20bfbfa14bb435d
2023-06-15 09:54:29.95 DBG ../reva/internal/http/interceptors/auth/auth.go:227 > error retrieving credentials error="no public token provided" pid=315832 pkg=rhttp traceid=2b0b70d933404655b20bfbfa14bb435d
2023-06-15 09:54:29.95 INF ../reva/internal/http/interceptors/providerauthorizer/providerauthorizer.go:81 > skipping provider authorizer check for: /remote.php/dav/ocm/BBc8z3piEMXNVhKMPhSyL2WslCpq78YN pid=315832 pkg=rhttp traceid=2b0b70d933404655b20bfbfa14bb435d
2023-06-15 09:54:29.95 DBG ../reva/pkg/rhttp/rhttp.go:305 > http routing: url= pid=315832 pkg=rhttp
2023-06-15 09:54:29.951 DBG ../reva/internal/http/services/owncloud/ocdav/ocdav.go:216 > http routing head=remote.php pid=315832 pkg=rhttp tail=/dav/ocm/BBc8z3piEMXNVhKMPhSyL2WslCpq78YN traceid=2b0b70d933404655b20bfbfa14bb435d
2023-06-15 09:54:29.951 DBG ../reva/internal/grpc/interceptors/auth/auth.go:101 > skipping auth method=/cs3.gateway.v1beta1.GatewayAPI/Authenticate pid=315832 pkg=rgrpc traceid=e7f54983a4e8943acd60c300c9be194f
2023-06-15 09:54:29.952 DBG ../reva/internal/grpc/interceptors/auth/auth.go:101 > skipping auth method=/cs3.auth.registry.v1beta1.RegistryAPI/GetAuthProviders pid=315832 pkg=rgrpc traceid=412aeedd90221ef23bfc9b45d47ee3bc
2023-06-15 09:54:29.952 DBG ../reva/internal/grpc/interceptors/log/log.go:66 > unary code=OK end="15/Jun/2023:09:54:29 +0000" from=tcp://127.0.0.1:38204 pid=315832 pkg=rgrpc start="15/Jun/2023:09:54:29 +0000" time_ns=396858 traceid=412aeedd90221ef23bfc9b45d47ee3bc uri=/cs3.auth.registry.v1beta1.RegistryAPI/GetAuthProviders user-agent=grpc-go/1.52.0
2023-06-15 09:54:29.953 DBG ../reva/internal/grpc/interceptors/auth/auth.go:101 > skipping auth method=/cs3.auth.provider.v1beta1.ProviderAPI/Authenticate pid=315832 pkg=rgrpc traceid=50a979ad4dd71b407fa0e0cc7312deb1
2023-06-15 09:54:29.953 DBG ../reva/internal/grpc/interceptors/auth/auth.go:101 > skipping auth method=/cs3.gateway.v1beta1.GatewayAPI/GetOCMShareByToken pid=315832 pkg=rgrpc traceid=9eae78e9b39c4033d44dcf592b6fd4c3
2023-06-15 09:54:29.954 DBG ../reva/internal/grpc/interceptors/auth/auth.go:101 > skipping auth method=/cs3.sharing.ocm.v1beta1.OcmAPI/GetOCMShareByToken pid=315832 pkg=rgrpc traceid=641aaca6ce43ce3e46b9dcb5da24dc51
2023-06-15 09:54:29.954 INF ../reva/pkg/ocm/share/repository/nextcloud/nextcloud.go:450 > am.do https://cloud.pondersource.com/index.php/apps/sciencemesh/~nobody/api/ocm/GetSentShareByToken {"Spec":{"Token":"BBc8z3piEMXNVhKMPhSyL2WslCpq78YN"}} pid=315832 pkg=rgrpc traceid=641aaca6ce43ce3e46b9dcb5da24dc51
2023-06-15 09:54:30.051 INF ../reva/pkg/ocm/share/repository/nextcloud/nextcloud.go:471 > am.do response 200 {"id":{"opaque_id":"1"},"resource_id":{"opaque_id":"fileid-\/einstein\/files\/ponder-oc-cern"},"permissions":{"permissions":{"add_grant":false,"create_container":false,"delete":false,"get_path":false,"get_quota":false,"initiate_file_download":false,"initiate_file_upload":false}},"grantee":{"type":1,"id":{"opaque_id":"lopresti","idp":"sm1.cernbox.cern.ch"}},"owner":{"id":{"opaque_id":"unknown","idp":"unknown"}},"creator":{"id":{"opaque_id":"unknown","idp":"unknown"}},"ctime":{"seconds":0},"mtime":{"seconds":0}} pid=315832 pkg=rgrpc traceid=641aaca6ce43ce3e46b9dcb5da24dc51
2023-06-15 09:54:30.052 DBG ../reva/pkg/ocm/share/repository/nextcloud/nextcloud.go:188 > Found ShareAltMap {"id":{"opaque_id":"1"},"remote_share_id":"","permissions":{"permissions":{}},"grantee":{"id":{"idp":"sm1.cernbox.cern.ch","opaque_id":"lopresti"}},"owner":{"id":{"idp":"unknown","opaque_id":"unknown"}},"creator":{"id":{"idp":"unknown","opaque_id":"unknown"}},"ctime":{},"mtime":{}} pid=315832 pkg=rgrpc traceid=641aaca6ce43ce3e46b9dcb5da24dc51
2023-06-15 09:54:30.052 DBG ../reva/internal/grpc/interceptors/log/log.go:66 > unary code=OK end="15/Jun/2023:09:54:30 +0000" from=tcp://127.0.0.1:38216 pid=315832 pkg=rgrpc start="15/Jun/2023:09:54:29 +0000" time_ns=98681763 traceid=641aaca6ce43ce3e46b9dcb5da24dc51 uri=/cs3.sharing.ocm.v1beta1.OcmAPI/GetOCMShareByToken user-agent=grpc-go/1.52.0
2023-06-15 09:54:30.053 DBG ../reva/internal/grpc/interceptors/log/log.go:66 > unary code=OK end="15/Jun/2023:09:54:30 +0000" from=tcp://127.0.0.1:54624 pid=315832 pkg=rgrpc start="15/Jun/2023:09:54:29 +0000" time_ns=99785633 traceid=9eae78e9b39c4033d44dcf592b6fd4c3 uri=/cs3.gateway.v1beta1.GatewayAPI/GetOCMShareByToken user-agent=grpc-go/1.52.0
2023-06-15 09:54:30.054 DBG ../reva/pkg/auth/manager/ocmshares/ocmshares.go:116 >

ocmshares found grantee 'lopresti' at 'sm1.cernbox.cern.ch'

from: {"status":{"code":"CODE_OK","trace":"2b0b70d933404655b20bfbfa14bb435d"},"share":{"id":{"opaqueId":"1"},"grantee":{"type":"GRANTEE_TYPE_USER","userId":{"idp":"sm1.cernbox.cern.ch","opaqueId":"lopresti"}},"owner":{"idp":"unknown","opaqueId":"unknown"},"creator":{"idp":"unknown","opaqueId":"unknown"},"ctime":{},"mtime":{}}} pid=315832 pkg=rgrpc token=BBc8z3piEMXNVhKMPhSyL2WslCpq78YN traceid=50a979ad4dd71b407fa0e0cc7312deb1
2023-06-15 09:54:30.054 DBG ../reva/internal/grpc/interceptors/auth/auth.go:101 > skipping auth method=/cs3.gateway.v1beta1.GatewayAPI/GetAcceptedUser pid=315832 pkg=rgrpc traceid=1c77ab62b45326289369a36a5fe8a186
2023-06-15 09:54:30.055 DBG ../reva/internal/grpc/interceptors/auth/auth.go:101 > skipping auth method=/cs3.ocm.invite.v1beta1.InviteAPI/GetAcceptedUser pid=315832 pkg=rgrpc traceid=693b77c66418c6096fbc83cd7e6be19e
2023-06-15 09:54:30.056 ERR ../reva/internal/grpc/services/ocminvitemanager/ocminvitemanager.go:326 >

error fetching remote user details error="error: not found: lopresti"

pid=315832 pkg=rgrpc traceid=693b77c66418c6096fbc83cd7e6be19e
2023-06-15 09:54:30.056 DBG ../reva/internal/grpc/interceptors/log/log.go:66 > unary code=OK end="15/Jun/2023:09:54:30 +0000" from=tcp://127.0.0.1:38228 pid=315832 pkg=rgrpc start="15/Jun/2023:09:54:30 +0000" time_ns=775419 traceid=693b77c66418c6096fbc83cd7e6be19e uri=/cs3.ocm.invite.v1beta1.InviteAPI/GetAcceptedUser user-agent=grpc-go/1.52.0
2023-06-15 09:54:30.056 DBG ../reva/internal/grpc/interceptors/log/log.go:66 > unary code=OK end="15/Jun/2023:09:54:30 +0000" from=tcp://127.0.0.1:54624 pid=315832 pkg=rgrpc start="15/Jun/2023:09:54:30 +0000" time_ns=2024198 traceid=1c77ab62b45326289369a36a5fe8a186 uri=/cs3.gateway.v1beta1.GatewayAPI/GetAcceptedUser user-agent=grpc-go/1.52.0
2023-06-15 09:54:30.057 WRN ../reva/internal/grpc/services/authprovider/authprovider.go:170 > error authenticating user error="authsvc: error in Authenticate: internal error: error fetching remote user details" pid=315832 pkg=rgrpc traceid=50a979ad4dd71b407fa0e0cc7312deb1
2023-06-15 09:54:30.057 DBG ../reva/internal/grpc/interceptors/log/log.go:66 > unary code=OK end="15/Jun/2023:09:54:30 +0000" from=tcp://127.0.0.1:50216 pid=315832 pkg=rgrpc start="15/Jun/2023:09:54:29 +0000" time_ns=104427060 traceid=50a979ad4dd71b407fa0e0cc7312deb1 uri=/cs3.auth.provider.v1beta1.ProviderAPI/Authenticate user-agent=grpc-go/1.52.0
2023-06-15 09:54:30.058 DBG ../reva/internal/grpc/interceptors/log/log.go:66 > unary code=OK end="15/Jun/2023:09:54:30 +0000" from=tcp://127.0.0.1:54624 pid=315832 pkg=rgrpc start="15/Jun/2023:09:54:29 +0000" time_ns=106389151 traceid=e7f54983a4e8943acd60c300c9be194f uri=/cs3.gateway.v1beta1.GatewayAPI/Authenticate user-agent=grpc-go/1.52.0
2023-06-15 09:54:30.058 DBG ../reva/internal/http/services/owncloud/ocdav/dav.go:215 > unauthorized pid=315832 pkg=rhttp token=BBc8z3piEMXNVhKMPhSyL2WslCpq78YN traceid=2b0b70d933404655b20bfbfa14bb435d
2023-06-15 09:54:30.058 WRN ../reva/internal/http/interceptors/log/log.go:108 > http end="15/Jun/2023:09:54:30 +0000" host=167.99.80.220 method=PROPFIND pid=315832 pkg=rhttp proto=HTTP/2.0 size=0 start="15/Jun/2023:09:54:29 +0000" status=401 time_ns=110144766 traceid=2b0b70d933404655b20bfbfa14bb435d uri=/remote.php/dav/ocm/BBc8z3piEMXNVhKMPhSyL2WslCpq78YN url=/BBc8z3piEMXNVhKMPhSyL2WslCpq78YN