Skip to content

Instantly share code, notes, and snippets.

@alexcos78

alexcos78/radosgw_webid_works

Created October 27, 2022 10:32
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save alexcos78/2013bf6fa3aac9b2082f98f484828d81 to your computer and use it in GitHub Desktop.
Save alexcos78/2013bf6fa3aac9b2082f98f484828d81 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
radosgw_webid_works
2022-10-27T12:28:22.872+0200 7fe016a53700 20 CONTENT_LENGTH=545
2022-10-27T12:28:22.872+0200 7fe016a53700 20 CONTENT_TYPE=application/x-www-form-urlencoded; charset=utf-8
2022-10-27T12:28:22.872+0200 7fe016a53700 20 HTTP_ACCEPT_ENCODING=identity
2022-10-27T12:28:22.872+0200 7fe016a53700 20 HTTP_AMZ_SDK_INVOCATION_ID=a5a7dda7-6731-4d2e-bfa8-0f62d0051d92
2022-10-27T12:28:22.872+0200 7fe016a53700 20 HTTP_AMZ_SDK_REQUEST=attempt=1
2022-10-27T12:28:22.872+0200 7fe016a53700 20 HTTP_AUTHORIZATION=AWS4-HMAC-SHA256 Credential=TESTER/20221027//iam/aws4_request, SignedHeaders=content-type;host;x-amz-date, Signature=00cd0b8e194847cb140c4f68b5a2a9e8d3fd6abbbbe13a2a4c19469efad8be54
2022-10-27T12:28:22.872+0200 7fe016a53700 20 HTTP_HOST=ceph-mon01-tb.cloud.cnaf.infn.it:7480
2022-10-27T12:28:22.872+0200 7fe016a53700 20 HTTP_USER_AGENT=Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
2022-10-27T12:28:22.872+0200 7fe016a53700 20 HTTP_VERSION=1.1
2022-10-27T12:28:22.872+0200 7fe016a53700 20 HTTP_X_AMZ_DATE=20221027T102822Z
2022-10-27T12:28:22.872+0200 7fe016a53700 20 REMOTE_ADDR=131.154.97.209
2022-10-27T12:28:22.872+0200 7fe016a53700 20 REQUEST_METHOD=POST
2022-10-27T12:28:22.872+0200 7fe016a53700 20 REQUEST_URI=/
2022-10-27T12:28:22.872+0200 7fe016a53700 20 SCRIPT_URI=/
2022-10-27T12:28:22.872+0200 7fe016a53700 20 SERVER_PORT=7480
2022-10-27T12:28:22.872+0200 7fe016a53700 1 ====== starting new request req=0x7fe016a4ab90 =====
2022-10-27T12:28:22.873+0200 7fe016a53700 2 req 12796461075389066101 0.000000000s initializing for trans_id = tx00000b196298cacadab75-00635a5d46-6b044ce-default
2022-10-27T12:28:22.873+0200 7fe016a53700 10 req 12796461075389066101 0.000000000s rgw api priority: s3=8 s3website=7
2022-10-27T12:28:22.873+0200 7fe016a53700 10 req 12796461075389066101 0.000000000s host=ceph-mon01-tb.cloud.cnaf.infn.it
2022-10-27T12:28:22.873+0200 7fe016a53700 20 req 12796461075389066101 0.000000000s subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0
2022-10-27T12:28:22.873+0200 7fe016a53700 20 req 12796461075389066101 0.000000000s final domain/bucket subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0 s->info.domain= s->info.request_uri=/
2022-10-27T12:28:22.873+0200 7fe016a53700 10 req 12796461075389066101 0.000000000s meta>> HTTP_X_AMZ_DATE
2022-10-27T12:28:22.873+0200 7fe016a53700 10 req 12796461075389066101 0.000000000s x>> x-amz-date:20221027T102822Z
2022-10-27T12:28:22.873+0200 7fe016a53700 20 req 12796461075389066101 0.000000000s get_handler handler=26RGWHandler_REST_Service_S3
2022-10-27T12:28:22.873+0200 7fe016a53700 10 req 12796461075389066101 0.000000000s handler=26RGWHandler_REST_Service_S3
2022-10-27T12:28:22.873+0200 7fe016a53700 2 req 12796461075389066101 0.000000000s getting op 4
2022-10-27T12:28:22.873+0200 7fe016a53700 10 req 12796461075389066101 0.000000000s Content of POST: Action=CreateRole&Version=2010-05-08&AssumeRolePolicyDocument=%7B%22Version%22%3A%222012-10-17%22%2C%22Statement%22%3A%5B%7B%22Effect%22%3A%22Allow%22%2C%22Principal%22%3A%7B%22Federated%22%3A%5B%22arn%3Aaws%3Aiam%3A%3A%3Aoidc-provider%2Fkeycloak-demo.cloud.cnaf.infn.it%3A8443%2Frealms%2Fdemo%22%5D%7D%2C%22Action%22%3A%5B%22sts%3AAssumeRoleWithWebIdentity%22%5D%2C%22Condition%22%3A%7B%22StringEquals%22%3A%7B%22keycloak-demo.cloud.cnaf.infn.it%3A8443%2Frealms%2Fdemo%3Aapp_id%22%3A%22account%22%7D%7D%7D%5D%7D&Path=%2F&RoleName=S3Accesswebid3
2022-10-27T12:28:22.873+0200 7fe016a53700 10 req 12796461075389066101 0.000000000s Content of POST: Action=CreateRole&Version=2010-05-08&AssumeRolePolicyDocument=%7B%22Version%22%3A%222012-10-17%22%2C%22Statement%22%3A%5B%7B%22Effect%22%3A%22Allow%22%2C%22Principal%22%3A%7B%22Federated%22%3A%5B%22arn%3Aaws%3Aiam%3A%3A%3Aoidc-provider%2Fkeycloak-demo.cloud.cnaf.infn.it%3A8443%2Frealms%2Fdemo%22%5D%7D%2C%22Action%22%3A%5B%22sts%3AAssumeRoleWithWebIdentity%22%5D%2C%22Condition%22%3A%7B%22StringEquals%22%3A%7B%22keycloak-demo.cloud.cnaf.infn.it%3A8443%2Frealms%2Fdemo%3Aapp_id%22%3A%22account%22%7D%7D%7D%5D%7D&Path=%2F&RoleName=S3Accesswebid3
2022-10-27T12:28:22.873+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.123:6800/595823,v1:10.10.96.123:6801/595823] -- osd_op(unknown.0.0:25117 9.3 9:c14cb721:::script.prerequest.:head [call version.read in=11b,getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x5653642e7800 con 0x565363597800
2022-10-27T12:28:22.873+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.1 v2:10.10.96.123:6800/595823 534 ==== osd_op_reply(25117 script.prerequest. [call,getxattrs,stat] v0'0 uv0 ondisk = -2 ((2) No such file or directory)) v8 ==== 246+0+0 (crc 0 0 0) 0x565364358240 con 0x565363597800
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s iam:create_role op=13RGWCreateRole
2022-10-27T12:28:22.874+0200 7fe016a53700 2 req 12796461075389066101 0.001000015s iam:create_role verifying requester
2022-10-27T12:28:22.874+0200 7fe016a53700 20 req 12796461075389066101 0.001000015s iam:create_role rgw::auth::StrategyRegistry::s3_main_strategy_t: trying rgw::auth::s3::AWSAuthStrategy
2022-10-27T12:28:22.874+0200 7fe016a53700 20 req 12796461075389066101 0.001000015s iam:create_role rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::S3AnonymousEngine
2022-10-27T12:28:22.874+0200 7fe016a53700 20 req 12796461075389066101 0.001000015s iam:create_role rgw::auth::s3::S3AnonymousEngine denied with reason=-1
2022-10-27T12:28:22.874+0200 7fe016a53700 20 req 12796461075389066101 0.001000015s iam:create_role rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::STSAuthStrategy
2022-10-27T12:28:22.874+0200 7fe016a53700 20 req 12796461075389066101 0.001000015s iam:create_role rgw::auth::s3::STSAuthStrategy: trying rgw::auth::s3::STSEngine
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s v4 signature format = 00cd0b8e194847cb140c4f68b5a2a9e8d3fd6abbbbe13a2a4c19469efad8be54
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s v4 credential format = TESTER/20221027//iam/aws4_request
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s access key id = TESTER
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s credential scope = 20221027//iam/aws4_request
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s canonical headers format = content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102822Z
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s payload request hash = 87c547d75608cddfa5a8c1782fbb80e76d76e6209183227c2a88ca359fd2be20
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s canonical request = POST
/
content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102822Z
content-type;host;x-amz-date
87c547d75608cddfa5a8c1782fbb80e76d76e6209183227c2a88ca359fd2be20
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s canonical request hash = 67105cacf69e46d8eac3f4887ac7e77ce179de1583179086032c5a26a36ed3d4
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s string to sign = AWS4-HMAC-SHA256
20221027T102822Z
20221027//iam/aws4_request
67105cacf69e46d8eac3f4887ac7e77ce179de1583179086032c5a26a36ed3d4
2022-10-27T12:28:22.874+0200 7fe016a53700 20 req 12796461075389066101 0.001000015s iam:create_role rgw::auth::s3::STSEngine denied with reason=-13
2022-10-27T12:28:22.874+0200 7fe016a53700 20 req 12796461075389066101 0.001000015s iam:create_role rgw::auth::s3::STSAuthStrategy denied with reason=-13
2022-10-27T12:28:22.874+0200 7fe016a53700 20 req 12796461075389066101 0.001000015s iam:create_role rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::AWSv2ExternalAuthStrategy
2022-10-27T12:28:22.874+0200 7fe016a53700 20 req 12796461075389066101 0.001000015s iam:create_role rgw::auth::s3::AWSv2ExternalAuthStrategy: trying rgw::auth::keystone::EC2Engine
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s v4 signature format = 00cd0b8e194847cb140c4f68b5a2a9e8d3fd6abbbbe13a2a4c19469efad8be54
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s v4 credential format = TESTER/20221027//iam/aws4_request
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s access key id = TESTER
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s credential scope = 20221027//iam/aws4_request
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s canonical headers format = content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102822Z
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s payload request hash = 87c547d75608cddfa5a8c1782fbb80e76d76e6209183227c2a88ca359fd2be20
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s canonical request = POST
/
content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102822Z
content-type;host;x-amz-date
87c547d75608cddfa5a8c1782fbb80e76d76e6209183227c2a88ca359fd2be20
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s canonical request hash = 67105cacf69e46d8eac3f4887ac7e77ce179de1583179086032c5a26a36ed3d4
2022-10-27T12:28:22.874+0200 7fe016a53700 10 req 12796461075389066101 0.001000015s string to sign = AWS4-HMAC-SHA256
20221027T102822Z
20221027//iam/aws4_request
67105cacf69e46d8eac3f4887ac7e77ce179de1583179086032c5a26a36ed3d4
2022-10-27T12:28:22.874+0200 7fe016a53700 0 req 12796461075389066101 0.001000015s iam:create_role No stored secret string, cache miss
2022-10-27T12:28:22.874+0200 7fe016a53700 20 sending request to https://tb-cloud-api-int.cr.cnaf.infn.it:5000/v3/auth/tokens
2022-10-27T12:28:22.874+0200 7fe016a53700 20 register_request mgr=0x56536275fd40 req_data->id=88, curl_handle=0x5653645b0ec0
2022-10-27T12:28:22.874+0200 7fe03eaa3700 20 link_request req_data=0x565364954000 req_data->id=88, curl_handle=0x5653645b0ec0
2022-10-27T12:28:23.237+0200 7fe016a53700 20 sending request to https://tb-cloud-api-int.cr.cnaf.infn.it:5000/v3/s3tokens
2022-10-27T12:28:23.237+0200 7fe016a53700 20 register_request mgr=0x56536275fd40 req_data->id=89, curl_handle=0x5653645b0ec0
2022-10-27T12:28:23.237+0200 7fe03eaa3700 20 link_request req_data=0x565364954000 req_data->id=89, curl_handle=0x5653645b0ec0
2022-10-27T12:28:23.264+0200 7fe016a53700 2 req 12796461075389066101 0.391005814s iam:create_role s3 keystone: token validation ERROR: {"error":{"code":404,"message":"Could not find credential: 25a1f47c1841dce0ec7f232d32accd7d5fba23a07988e0c0763f444bd86a9548.","title":"Not Found"}}
2022-10-27T12:28:23.265+0200 7fe016a53700 20 req 12796461075389066101 0.392005831s iam:create_role rgw::auth::keystone::EC2Engine denied with reason=-2
2022-10-27T12:28:23.265+0200 7fe016a53700 20 req 12796461075389066101 0.392005831s iam:create_role rgw::auth::s3::AWSv2ExternalAuthStrategy denied with reason=-2
2022-10-27T12:28:23.265+0200 7fe016a53700 20 req 12796461075389066101 0.392005831s iam:create_role rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::LocalEngine
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s v4 signature format = 00cd0b8e194847cb140c4f68b5a2a9e8d3fd6abbbbe13a2a4c19469efad8be54
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s v4 credential format = TESTER/20221027//iam/aws4_request
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s access key id = TESTER
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s credential scope = 20221027//iam/aws4_request
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s canonical headers format = content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102822Z
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s payload request hash = 87c547d75608cddfa5a8c1782fbb80e76d76e6209183227c2a88ca359fd2be20
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s canonical request = POST
/
content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102822Z
content-type;host;x-amz-date
87c547d75608cddfa5a8c1782fbb80e76d76e6209183227c2a88ca359fd2be20
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s canonical request hash = 67105cacf69e46d8eac3f4887ac7e77ce179de1583179086032c5a26a36ed3d4
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s string to sign = AWS4-HMAC-SHA256
20221027T102822Z
20221027//iam/aws4_request
67105cacf69e46d8eac3f4887ac7e77ce179de1583179086032c5a26a36ed3d4
2022-10-27T12:28:23.265+0200 7fe016a53700 20 req 12796461075389066101 0.392005831s iam:create_role get_system_obj_state: rctx=0x7fe016a49148 obj=default.rgw.meta:users.keys:TESTER state=0x56536445dde0 s->prefetch_data=0
2022-10-27T12:28:23.265+0200 7fe016a53700 10 req 12796461075389066101 0.392005831s iam:create_role cache get: name=default.rgw.meta+users.keys+TESTER : expiry miss
2022-10-27T12:28:23.265+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25118 11.2 11:6b213cc5:users.keys::TESTER:head [getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x5653645ac000 con 0x5653635c7000
2022-10-27T12:28:23.266+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4135 ==== osd_op_reply(25118 TESTER [getxattrs out=4b,stat out=16b] v0'0 uv109 ondisk = 0) v8 ==== 192+0+20 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.266+0200 7fe016a53700 10 req 12796461075389066101 0.393005848s iam:create_role cache put: name=default.rgw.meta+users.keys+TESTER info.flags=0x6
2022-10-27T12:28:23.266+0200 7fe016a53700 10 req 12796461075389066101 0.393005848s iam:create_role adding default.rgw.meta+users.keys+TESTER to cache LRU end
2022-10-27T12:28:23.266+0200 7fe016a53700 20 req 12796461075389066101 0.393005848s iam:create_role get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.266+0200 7fe016a53700 10 req 12796461075389066101 0.393005848s iam:create_role cache get: name=default.rgw.meta+users.keys+TESTER : type miss (requested=0x1, cached=0x6)
2022-10-27T12:28:23.266+0200 7fe016a53700 20 req 12796461075389066101 0.393005848s iam:create_role rados->read ofs=0 len=0
2022-10-27T12:28:23.266+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25119 11.2 11:6b213cc5:users.keys::TESTER:head [read 0~0] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364421400 con 0x5653635c7000
2022-10-27T12:28:23.274+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4136 ==== osd_op_reply(25119 TESTER [read 0~10 out=10b] v0'0 uv109 ondisk = 0) v8 ==== 150+0+10 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.274+0200 7fe016a53700 20 req 12796461075389066101 0.401005983s iam:create_role rados_obj.operate() r=0 bl.length=10
2022-10-27T12:28:23.274+0200 7fe016a53700 10 req 12796461075389066101 0.401005983s iam:create_role cache put: name=default.rgw.meta+users.keys+TESTER info.flags=0x1
2022-10-27T12:28:23.274+0200 7fe016a53700 10 req 12796461075389066101 0.401005983s iam:create_role moving default.rgw.meta+users.keys+TESTER to cache LRU end
2022-10-27T12:28:23.274+0200 7fe016a53700 20 req 12796461075389066101 0.401005983s iam:create_role get_system_obj_state: rctx=0x7fe016a49148 obj=default.rgw.meta:users.uid:TESTER state=0x5653644249a0 s->prefetch_data=0
2022-10-27T12:28:23.274+0200 7fe016a53700 10 req 12796461075389066101 0.401005983s iam:create_role cache get: name=default.rgw.meta+users.uid+TESTER : expiry miss
2022-10-27T12:28:23.274+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25120 11.0 11:142c659c:users.uid::TESTER:head [call version.read in=11b,getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364755000 con 0x5653635c7000
2022-10-27T12:28:23.275+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4137 ==== osd_op_reply(25120 TESTER [call out=48b,getxattrs out=75b,stat out=16b] v0'0 uv43 ondisk = 0) v8 ==== 234+0+139 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.275+0200 7fe016a53700 10 req 12796461075389066101 0.402005970s iam:create_role cache put: name=default.rgw.meta+users.uid+TESTER info.flags=0x16
2022-10-27T12:28:23.275+0200 7fe016a53700 10 req 12796461075389066101 0.402005970s iam:create_role adding default.rgw.meta+users.uid+TESTER to cache LRU end
2022-10-27T12:28:23.275+0200 7fe016a53700 10 req 12796461075389066101 0.402005970s iam:create_role updating xattr: name=ceph.objclass.version bl.length()=42
2022-10-27T12:28:23.275+0200 7fe016a53700 20 req 12796461075389066101 0.402005970s iam:create_role get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.275+0200 7fe016a53700 10 req 12796461075389066101 0.402005970s iam:create_role cache get: name=default.rgw.meta+users.uid+TESTER : type miss (requested=0x11, cached=0x16)
2022-10-27T12:28:23.275+0200 7fe016a53700 20 req 12796461075389066101 0.402005970s iam:create_role rados->read ofs=0 len=0
2022-10-27T12:28:23.275+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25121 11.0 11:142c659c:users.uid::TESTER:head [call version.check_conds in=122b,call version.read in=11b,read 0~0] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x5653647f2800 con 0x5653635c7000
2022-10-27T12:28:23.283+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4138 ==== osd_op_reply(25121 TESTER [call,call out=48b,read 0~283 out=283b] v0'0 uv43 ondisk = 0) v8 ==== 234+0+331 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.283+0200 7fe016a53700 20 req 12796461075389066101 0.410006106s iam:create_role rados_obj.operate() r=0 bl.length=283
2022-10-27T12:28:23.283+0200 7fe016a53700 10 req 12796461075389066101 0.410006106s iam:create_role cache put: name=default.rgw.meta+users.uid+TESTER info.flags=0x11
2022-10-27T12:28:23.283+0200 7fe016a53700 10 req 12796461075389066101 0.410006106s iam:create_role moving default.rgw.meta+users.uid+TESTER to cache LRU end
2022-10-27T12:28:23.283+0200 7fe016a53700 10 req 12796461075389066101 0.410006106s iam:create_role chain_cache_entry: cache_locator=default.rgw.meta+users.uid+TESTER
2022-10-27T12:28:23.283+0200 7fe016a53700 10 req 12796461075389066101 0.410006106s date_k = 0c09910a6d1a529c2071e10947c39d7e0896c7a07aa5678c6c0bca15c092052a
2022-10-27T12:28:23.283+0200 7fe016a53700 10 req 12796461075389066101 0.410006106s region_k = bc9b793a8630d850965e17cf17a7b060f24297d068bbc33f6fb35a7cd24c5d26
2022-10-27T12:28:23.283+0200 7fe016a53700 10 req 12796461075389066101 0.410006106s service_k = ed2628cfe31af130dfdefb8ed41607732f12910edffda4b503a94d30e6f084cd
2022-10-27T12:28:23.283+0200 7fe016a53700 10 req 12796461075389066101 0.410006106s signing_k = 8daebe6f34f48ac97853ac7c31e3fc8f6c4c1f276baa28cf43bf28f1ced1d04c
2022-10-27T12:28:23.283+0200 7fe016a53700 10 req 12796461075389066101 0.410006106s generated signature = 00cd0b8e194847cb140c4f68b5a2a9e8d3fd6abbbbe13a2a4c19469efad8be54
2022-10-27T12:28:23.283+0200 7fe016a53700 15 req 12796461075389066101 0.410006106s iam:create_role string_to_sign=AWS4-HMAC-SHA256
20221027T102822Z
20221027//iam/aws4_request
67105cacf69e46d8eac3f4887ac7e77ce179de1583179086032c5a26a36ed3d4
2022-10-27T12:28:23.283+0200 7fe016a53700 15 req 12796461075389066101 0.410006106s iam:create_role server signature=00cd0b8e194847cb140c4f68b5a2a9e8d3fd6abbbbe13a2a4c19469efad8be54
2022-10-27T12:28:23.283+0200 7fe016a53700 15 req 12796461075389066101 0.410006106s iam:create_role client signature=00cd0b8e194847cb140c4f68b5a2a9e8d3fd6abbbbe13a2a4c19469efad8be54
2022-10-27T12:28:23.283+0200 7fe016a53700 15 req 12796461075389066101 0.410006106s iam:create_role compare=0
2022-10-27T12:28:23.283+0200 7fe016a53700 20 req 12796461075389066101 0.410006106s iam:create_role rgw::auth::s3::LocalEngine granted access
2022-10-27T12:28:23.283+0200 7fe016a53700 20 req 12796461075389066101 0.410006106s iam:create_role rgw::auth::s3::AWSAuthStrategy granted access
2022-10-27T12:28:23.283+0200 7fe016a53700 2 req 12796461075389066101 0.410006106s iam:create_role normalizing buckets and tenants
2022-10-27T12:28:23.283+0200 7fe016a53700 10 req 12796461075389066101 0.410006106s s->object=<NULL> s->bucket=
2022-10-27T12:28:23.283+0200 7fe016a53700 2 req 12796461075389066101 0.410006106s iam:create_role init permissions
2022-10-27T12:28:23.283+0200 7fe016a53700 20 req 12796461075389066101 0.410006106s iam:create_role get_system_obj_state: rctx=0x7fe016a490d8 obj=default.rgw.meta:users.uid:TESTER state=0x56536445dde0 s->prefetch_data=0
2022-10-27T12:28:23.284+0200 7fe016a53700 10 req 12796461075389066101 0.411006123s iam:create_role cache get: name=default.rgw.meta+users.uid+TESTER : type miss (requested=0x6, cached=0x13)
2022-10-27T12:28:23.284+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25122 11.0 11:142c659c:users.uid::TESTER:head [getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x5653647f2000 con 0x5653635c7000
2022-10-27T12:28:23.284+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4139 ==== osd_op_reply(25122 TESTER [getxattrs out=75b,stat out=16b] v0'0 uv43 ondisk = 0) v8 ==== 192+0+91 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.284+0200 7fe016a53700 10 req 12796461075389066101 0.411006123s iam:create_role cache put: name=default.rgw.meta+users.uid+TESTER info.flags=0x6
2022-10-27T12:28:23.284+0200 7fe016a53700 10 req 12796461075389066101 0.411006123s iam:create_role moving default.rgw.meta+users.uid+TESTER to cache LRU end
2022-10-27T12:28:23.284+0200 7fe016a53700 10 req 12796461075389066101 0.411006123s iam:create_role updating xattr: name=ceph.objclass.version bl.length()=42
2022-10-27T12:28:23.284+0200 7fe016a53700 20 req 12796461075389066101 0.411006123s iam:create_role get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.284+0200 7fe016a53700 20 req 12796461075389066101 0.411006123s iam:create_role Read xattr: user.rgw.idtag
2022-10-27T12:28:23.284+0200 7fe016a53700 10 req 12796461075389066101 0.411006123s iam:create_role cache get: name=default.rgw.meta+users.uid+TESTER : hit (requested=0x3, cached=0x7)
2022-10-27T12:28:23.284+0200 7fe016a53700 2 req 12796461075389066101 0.411006123s iam:create_role recalculating target
2022-10-27T12:28:23.284+0200 7fe016a53700 2 req 12796461075389066101 0.411006123s iam:create_role reading permissions
2022-10-27T12:28:23.284+0200 7fe016a53700 2 req 12796461075389066101 0.411006123s iam:create_role init op
2022-10-27T12:28:23.284+0200 7fe016a53700 2 req 12796461075389066101 0.411006123s iam:create_role verifying op mask
2022-10-27T12:28:23.284+0200 7fe016a53700 20 req 12796461075389066101 0.411006123s iam:create_role required_mask= 0 user.op_mask=7
2022-10-27T12:28:23.284+0200 7fe016a53700 2 req 12796461075389066101 0.411006123s iam:create_role verifying op permissions
2022-10-27T12:28:23.284+0200 7fe016a53700 2 req 12796461075389066101 0.411006123s iam:create_role verifying op params
2022-10-27T12:28:23.284+0200 7fe016a53700 2 req 12796461075389066101 0.411006123s iam:create_role pre-executing
2022-10-27T12:28:23.284+0200 7fe016a53700 2 req 12796461075389066101 0.411006123s iam:create_role executing
2022-10-27T12:28:23.285+0200 7fe016a53700 20 req 12796461075389066101 0.412006140s get_system_obj_state: rctx=0x7fe016a48500 obj=default.rgw.meta:roles:role_names.S3Accesswebid3 state=0x5653644249a0 s->prefetch_data=0
2022-10-27T12:28:23.285+0200 7fe016a53700 10 req 12796461075389066101 0.412006140s cache get: name=default.rgw.meta+roles+role_names.S3Accesswebid3 : miss
2022-10-27T12:28:23.285+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25123 11.0 11:0dfa91e6:roles::role_names.S3Accesswebid3:head [getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364860c00 con 0x5653635c7000
2022-10-27T12:28:23.285+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4140 ==== osd_op_reply(25123 role_names.S3Accesswebid3 [getxattrs,stat] v0'0 uv0 ondisk = -2 ((2) No such file or directory)) v8 ==== 211+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.285+0200 7fe016a53700 10 req 12796461075389066101 0.412006140s cache put: name=default.rgw.meta+roles+role_names.S3Accesswebid3 info.flags=0x0
2022-10-27T12:28:23.285+0200 7fe016a53700 10 req 12796461075389066101 0.412006140s adding default.rgw.meta+roles+role_names.S3Accesswebid3 to cache LRU end
2022-10-27T12:28:23.285+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25124 11.3 11:f1d6a725:roles::roles.f97dffe3-ee5c-4922-8818-46cc7ebca422:head [create,writefull 0~462 in=462b] snapc 0=[] ondisk+write+known_if_redirected e6899044) v8 -- 0x56536429d400 con 0x5653635c7000
2022-10-27T12:28:23.319+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4141 ==== osd_op_reply(25124 roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 [create,writefull 0~462] v6899044'48 uv48 ondisk = 0) v8 ==== 228+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.319+0200 7fe016a53700 10 req 12796461075389066101 0.446006656s cache put: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 info.flags=0x7
2022-10-27T12:28:23.319+0200 7fe016a53700 10 req 12796461075389066101 0.446006656s adding default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 to cache LRU end
2022-10-27T12:28:23.319+0200 7fe016a53700 10 req 12796461075389066101 0.446006656s distributing notification oid=default.rgw.control:notify.7 cni=[op: 0, obj: default.rgw.meta:roles:roles.f97dffe3-ee5c-4922-8818-46cc7ebca422, ofs0, ns]
2022-10-27T12:28:23.319+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25125 6.1 6:93e5b521:::notify.7:head [notify cookie 94916164022656 in=661b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x56536479dc00 con 0x5653635c7c00
2022-10-27T12:28:23.319+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17367 ==== watch-notify(notify (1) cookie 94916149218560 notify 29631168353665262 ret 0) v3 ==== 691+0+0 (crc 0 0 0) 0x565363613ba0 con 0x5653635c7c00
2022-10-27T12:28:23.320+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17368 ==== osd_op_reply(25125 notify.7 [notify cookie 94916164022656 out=8b] v0'0 uv4662 ondisk = 0) v8 ==== 152+0+8 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.320+0200 7fe03c29e700 10 rgw watcher librados: RGWWatcher::handle_notify() notify_id 29631168353665262 cookie 94916149218560 notifier 112215246 bl.length()=649
2022-10-27T12:28:23.320+0200 7fe03c29e700 10 rgw watcher librados: cache put: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 info.flags=0x7
2022-10-27T12:28:23.320+0200 7fe03c29e700 10 rgw watcher librados: moving default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 to cache LRU end
2022-10-27T12:28:23.320+0200 7fe03c29e700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25126 6.1 6:93e5b521:::notify.7:head [notify-ack in=20b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x5653642fd000 con 0x5653635c7c00
2022-10-27T12:28:23.320+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17369 ==== watch-notify(notify_complete (2) cookie 94916164022656 notify 29631168353665262 ret 0) v3 ==== 42+0+68 (crc 0 0 0) 0x565363613d40 con 0x5653635c7c00
2022-10-27T12:28:23.320+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17370 ==== osd_op_reply(25126 notify.7 [notify-ack] v0'0 uv4662 ondisk = 0) v8 ==== 152+0+0 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.320+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25127 11.0 11:0dfa91e6:roles::role_names.S3Accesswebid3:head [create,writefull 0~46 in=46b] snapc 0=[] ondisk+write+known_if_redirected e6899044) v8 -- 0x56536436e800 con 0x5653635c7000
2022-10-27T12:28:23.356+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4142 ==== osd_op_reply(25127 role_names.S3Accesswebid3 [create,writefull 0~46] v6899044'72 uv72 ondisk = 0) v8 ==== 211+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.356+0200 7fe016a53700 10 req 12796461075389066101 0.483007193s cache put: name=default.rgw.meta+roles+role_names.S3Accesswebid3 info.flags=0x7
2022-10-27T12:28:23.356+0200 7fe016a53700 10 req 12796461075389066101 0.483007193s moving default.rgw.meta+roles+role_names.S3Accesswebid3 to cache LRU end
2022-10-27T12:28:23.356+0200 7fe016a53700 10 req 12796461075389066101 0.483007193s distributing notification oid=default.rgw.control:notify.0 cni=[op: 0, obj: default.rgw.meta:roles:role_names.S3Accesswebid3, ofs0, ns]
2022-10-27T12:28:23.356+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25128 6.3 6:f95f44c2:::notify.0:head [notify cookie 94916164022656 in=228b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x5653627a9800 con 0x5653635c7c00
2022-10-27T12:28:23.357+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17371 ==== watch-notify(notify (1) cookie 94916149210496 notify 29631168353665263 ret 0) v3 ==== 258+0+0 (crc 0 0 0) 0x565363613d40 con 0x5653635c7c00
2022-10-27T12:28:23.357+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17372 ==== osd_op_reply(25128 notify.0 [notify cookie 94916164022656 out=8b] v0'0 uv3278 ondisk = 0) v8 ==== 152+0+8 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.357+0200 7fe03c29e700 10 rgw watcher librados: RGWWatcher::handle_notify() notify_id 29631168353665263 cookie 94916149210496 notifier 112215246 bl.length()=216
2022-10-27T12:28:23.357+0200 7fe03c29e700 10 rgw watcher librados: cache put: name=default.rgw.meta+roles+role_names.S3Accesswebid3 info.flags=0x7
2022-10-27T12:28:23.357+0200 7fe03c29e700 10 rgw watcher librados: moving default.rgw.meta+roles+role_names.S3Accesswebid3 to cache LRU end
2022-10-27T12:28:23.357+0200 7fe03c29e700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25129 6.3 6:f95f44c2:::notify.0:head [notify-ack in=20b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364660000 con 0x5653635c7c00
2022-10-27T12:28:23.357+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17373 ==== watch-notify(notify_complete (2) cookie 94916164022656 notify 29631168353665263 ret 0) v3 ==== 42+0+68 (crc 0 0 0) 0x565363614000 con 0x5653635c7c00
2022-10-27T12:28:23.357+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17374 ==== osd_op_reply(25129 notify.0 [notify-ack] v0'0 uv3278 ondisk = 0) v8 ==== 152+0+0 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.357+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25130 11.2 11:5fefc51b:roles::role_paths.%2froles.f97dffe3-ee5c-4922-8818-46cc7ebca422:head [create,writefull 0~0] snapc 0=[] ondisk+write+known_if_redirected e6899044) v8 -- 0x5653647c3000 con 0x5653635c7000
2022-10-27T12:28:23.381+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4143 ==== osd_op_reply(25130 role_paths./roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 [create,writefull 0~0] v6899044'117 uv117 ondisk = 0) v8 ==== 240+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.381+0200 7fe016a53700 10 req 12796461075389066101 0.508007526s cache put: name=default.rgw.meta+roles+role_paths./roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 info.flags=0x7
2022-10-27T12:28:23.381+0200 7fe016a53700 10 req 12796461075389066101 0.508007526s adding default.rgw.meta+roles+role_paths./roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 to cache LRU end
2022-10-27T12:28:23.381+0200 7fe016a53700 10 req 12796461075389066101 0.508007526s distributing notification oid=default.rgw.control:notify.0 cni=[op: 0, obj: default.rgw.meta:roles:role_paths./roles.f97dffe3-ee5c-4922-8818-46cc7ebca422, ofs0, ns]
2022-10-27T12:28:23.381+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25131 6.3 6:f95f44c2:::notify.0:head [notify cookie 94916164022656 in=211b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364791400 con 0x5653635c7c00
2022-10-27T12:28:23.382+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17375 ==== watch-notify(notify (1) cookie 94916149210496 notify 29631168353665264 ret 0) v3 ==== 241+0+0 (crc 0 0 0) 0x565363614000 con 0x5653635c7c00
2022-10-27T12:28:23.382+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17376 ==== osd_op_reply(25131 notify.0 [notify cookie 94916164022656 out=8b] v0'0 uv3278 ondisk = 0) v8 ==== 152+0+8 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.382+0200 7fe03c29e700 10 rgw watcher librados: RGWWatcher::handle_notify() notify_id 29631168353665264 cookie 94916149210496 notifier 112215246 bl.length()=199
2022-10-27T12:28:23.382+0200 7fe03c29e700 10 rgw watcher librados: cache put: name=default.rgw.meta+roles+role_paths./roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 info.flags=0x7
2022-10-27T12:28:23.382+0200 7fe03c29e700 10 rgw watcher librados: moving default.rgw.meta+roles+role_paths./roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 to cache LRU end
2022-10-27T12:28:23.382+0200 7fe03c29e700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25132 6.3 6:f95f44c2:::notify.0:head [notify-ack in=20b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364660c00 con 0x5653635c7c00
2022-10-27T12:28:23.382+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17377 ==== watch-notify(notify_complete (2) cookie 94916164022656 notify 29631168353665264 ret 0) v3 ==== 42+0+68 (crc 0 0 0) 0x56536361fd40 con 0x5653635c7c00
2022-10-27T12:28:23.382+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17378 ==== osd_op_reply(25132 notify.0 [notify-ack] v0'0 uv3278 ondisk = 0) v8 ==== 152+0+0 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.382+0200 7fe016a53700 2 req 12796461075389066101 0.509007573s iam:create_role completing
2022-10-27T12:28:23.382+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.123:6800/595823,v1:10.10.96.123:6801/595823] -- osd_op(unknown.0.0:25133 9.3 9:fb11263f:::script.postrequest.:head [call version.read in=11b,getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x5653647a0c00 con 0x565363597800
2022-10-27T12:28:23.383+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.1 v2:10.10.96.123:6800/595823 535 ==== osd_op_reply(25133 script.postrequest. [call,getxattrs,stat] v0'0 uv0 ondisk = -2 ((2) No such file or directory)) v8 ==== 247+0+0 (crc 0 0 0) 0x565364358240 con 0x565363597800
2022-10-27T12:28:23.383+0200 7fe016a53700 2 req 12796461075389066101 0.510007560s iam:create_role op status=0
2022-10-27T12:28:23.383+0200 7fe016a53700 2 req 12796461075389066101 0.510007560s iam:create_role http status=200
2022-10-27T12:28:23.383+0200 7fe016a53700 1 ====== req done req=0x7fe016a4ab90 op status=0 http_status=200 latency=0.510007560s ======
2022-10-27T12:28:23.383+0200 7fe016a53700 1 civetweb: 0x56536388a000: 131.154.97.209 - - [27/Oct/2022:12:28:22 +0200] "POST / HTTP/1.1" 200 1120 - Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
2022-10-27T12:28:23.388+0200 7fe016a53700 20 CONTENT_LENGTH=273
2022-10-27T12:28:23.388+0200 7fe016a53700 20 CONTENT_TYPE=application/x-www-form-urlencoded; charset=utf-8
2022-10-27T12:28:23.388+0200 7fe016a53700 20 HTTP_ACCEPT_ENCODING=identity
2022-10-27T12:28:23.388+0200 7fe016a53700 20 HTTP_AMZ_SDK_INVOCATION_ID=655e4c1e-9d26-4efb-862b-ce5c0c6ac254
2022-10-27T12:28:23.388+0200 7fe016a53700 20 HTTP_AMZ_SDK_REQUEST=attempt=1
2022-10-27T12:28:23.388+0200 7fe016a53700 20 HTTP_AUTHORIZATION=AWS4-HMAC-SHA256 Credential=TESTER/20221027//iam/aws4_request, SignedHeaders=content-type;host;x-amz-date, Signature=fa952a9265d89acb81ecbad78fd9f5b79738059439b6725a6c016358a9181e7e
2022-10-27T12:28:23.388+0200 7fe016a53700 20 HTTP_HOST=ceph-mon01-tb.cloud.cnaf.infn.it:7480
2022-10-27T12:28:23.388+0200 7fe016a53700 20 HTTP_USER_AGENT=Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
2022-10-27T12:28:23.388+0200 7fe016a53700 20 HTTP_VERSION=1.1
2022-10-27T12:28:23.388+0200 7fe016a53700 20 HTTP_X_AMZ_DATE=20221027T102823Z
2022-10-27T12:28:23.388+0200 7fe016a53700 20 REMOTE_ADDR=131.154.97.209
2022-10-27T12:28:23.388+0200 7fe016a53700 20 REQUEST_METHOD=POST
2022-10-27T12:28:23.388+0200 7fe016a53700 20 REQUEST_URI=/
2022-10-27T12:28:23.388+0200 7fe016a53700 20 SCRIPT_URI=/
2022-10-27T12:28:23.388+0200 7fe016a53700 20 SERVER_PORT=7480
2022-10-27T12:28:23.388+0200 7fe016a53700 1 ====== starting new request req=0x7fe016a4ab90 =====
2022-10-27T12:28:23.388+0200 7fe016a53700 2 req 6390965372935311081 0.000000000s initializing for trans_id = tx0000058b1452559a18ee9-00635a5d47-6b044ce-default
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s rgw api priority: s3=8 s3website=7
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s host=ceph-mon01-tb.cloud.cnaf.infn.it
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s final domain/bucket subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0 s->info.domain= s->info.request_uri=/
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s meta>> HTTP_X_AMZ_DATE
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s x>> x-amz-date:20221027T102823Z
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s get_handler handler=26RGWHandler_REST_Service_S3
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s handler=26RGWHandler_REST_Service_S3
2022-10-27T12:28:23.388+0200 7fe016a53700 2 req 6390965372935311081 0.000000000s getting op 4
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s Content of POST: Action=PutRolePolicy&Version=2010-05-08&RoleName=S3Accesswebid3&PolicyName=Policy1&PolicyDocument=%7B%22Version%22%3A%222012-10-17%22%2C%22Statement%22%3A%7B%22Effect%22%3A%22Allow%22%2C%22Action%22%3A%22s3%3A%2A%22%2C%22Resource%22%3A%22arn%3Aaws%3As3%3A%3A%3A%2A%22%7D%7D
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s Content of POST: Action=PutRolePolicy&Version=2010-05-08&RoleName=S3Accesswebid3&PolicyName=Policy1&PolicyDocument=%7B%22Version%22%3A%222012-10-17%22%2C%22Statement%22%3A%7B%22Effect%22%3A%22Allow%22%2C%22Action%22%3A%22s3%3A%2A%22%2C%22Resource%22%3A%22arn%3Aaws%3As3%3A%3A%3A%2A%22%7D%7D
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s iam:put_role_policy op=16RGWPutRolePolicy
2022-10-27T12:28:23.388+0200 7fe016a53700 2 req 6390965372935311081 0.000000000s iam:put_role_policy verifying requester
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s iam:put_role_policy rgw::auth::StrategyRegistry::s3_main_strategy_t: trying rgw::auth::s3::AWSAuthStrategy
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s iam:put_role_policy rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::S3AnonymousEngine
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s iam:put_role_policy rgw::auth::s3::S3AnonymousEngine denied with reason=-1
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s iam:put_role_policy rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::STSAuthStrategy
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s iam:put_role_policy rgw::auth::s3::STSAuthStrategy: trying rgw::auth::s3::STSEngine
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s v4 signature format = fa952a9265d89acb81ecbad78fd9f5b79738059439b6725a6c016358a9181e7e
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s v4 credential format = TESTER/20221027//iam/aws4_request
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s access key id = TESTER
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s credential scope = 20221027//iam/aws4_request
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s canonical headers format = content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102823Z
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s payload request hash = 6df672637084b898189d55363421cf18b799b568805efff06255af90ab09edcd
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s canonical request = POST
/
content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102823Z
content-type;host;x-amz-date
6df672637084b898189d55363421cf18b799b568805efff06255af90ab09edcd
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s canonical request hash = 8690370b37461da80ac954c01b5eceb50d3ad92d3c240dc3b31dd96e8ba01d70
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s string to sign = AWS4-HMAC-SHA256
20221027T102823Z
20221027//iam/aws4_request
8690370b37461da80ac954c01b5eceb50d3ad92d3c240dc3b31dd96e8ba01d70
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s iam:put_role_policy rgw::auth::s3::STSEngine denied with reason=-13
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s iam:put_role_policy rgw::auth::s3::STSAuthStrategy denied with reason=-13
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s iam:put_role_policy rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::AWSv2ExternalAuthStrategy
2022-10-27T12:28:23.388+0200 7fe016a53700 20 req 6390965372935311081 0.000000000s iam:put_role_policy rgw::auth::s3::AWSv2ExternalAuthStrategy: trying rgw::auth::keystone::EC2Engine
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s v4 signature format = fa952a9265d89acb81ecbad78fd9f5b79738059439b6725a6c016358a9181e7e
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s v4 credential format = TESTER/20221027//iam/aws4_request
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s access key id = TESTER
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s credential scope = 20221027//iam/aws4_request
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s canonical headers format = content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102823Z
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s payload request hash = 6df672637084b898189d55363421cf18b799b568805efff06255af90ab09edcd
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s canonical request = POST
/
content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102823Z
content-type;host;x-amz-date
6df672637084b898189d55363421cf18b799b568805efff06255af90ab09edcd
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s canonical request hash = 8690370b37461da80ac954c01b5eceb50d3ad92d3c240dc3b31dd96e8ba01d70
2022-10-27T12:28:23.388+0200 7fe016a53700 10 req 6390965372935311081 0.000000000s string to sign = AWS4-HMAC-SHA256
20221027T102823Z
20221027//iam/aws4_request
8690370b37461da80ac954c01b5eceb50d3ad92d3c240dc3b31dd96e8ba01d70
2022-10-27T12:28:23.388+0200 7fe016a53700 0 req 6390965372935311081 0.000000000s iam:put_role_policy No stored secret string, cache miss
2022-10-27T12:28:23.388+0200 7fe016a53700 20 sending request to https://tb-cloud-api-int.cr.cnaf.infn.it:5000/v3/auth/tokens
2022-10-27T12:28:23.388+0200 7fe016a53700 20 register_request mgr=0x56536275fd40 req_data->id=90, curl_handle=0x5653645b0ec0
2022-10-27T12:28:23.388+0200 7fe03eaa3700 20 link_request req_data=0x565364954b40 req_data->id=90, curl_handle=0x5653645b0ec0
2022-10-27T12:28:23.738+0200 7fe016a53700 20 sending request to https://tb-cloud-api-int.cr.cnaf.infn.it:5000/v3/s3tokens
2022-10-27T12:28:23.738+0200 7fe016a53700 20 register_request mgr=0x56536275fd40 req_data->id=91, curl_handle=0x5653645b0ec0
2022-10-27T12:28:23.738+0200 7fe03eaa3700 20 link_request req_data=0x565364954b40 req_data->id=91, curl_handle=0x5653645b0ec0
2022-10-27T12:28:23.768+0200 7fe016a53700 2 req 6390965372935311081 0.380005658s iam:put_role_policy s3 keystone: token validation ERROR: {"error":{"code":404,"message":"Could not find credential: 25a1f47c1841dce0ec7f232d32accd7d5fba23a07988e0c0763f444bd86a9548.","title":"Not Found"}}
YaHR0cDovL29jc3AuY29tb2RvY2EuY29tMA0GCSqGSIb3DQEBDAUA
A4IBAQAYh1HcdCE9nIrgJ7cz0C7M7PDmy14R3iJvm3WOnnL+5Nb+qh+cli3vA0p+
rvSNb3I8QzvAP+u431yqqcau8vzY7qN7Q/aGNnwU4M309z/+3ri0ivCRlv79Q2R+
/czSAaF9ffgZGclCKxO/WIu6pKJmBHaIkU4MiRTOok3JMrO66BQavHHxW/BBC5gA
CiIDEOUMsfnNkjcZ7Tvx5Dq2+UUTJnWvu6rvP3t3O9LEApE9GQDTF1w52z97GA1F
zZOFli9d31kWTz9RvdVFGD/tSo7oBmF0Ixa1DVBzJ0RHfxBdiSprhTEUxOipakyA
vGp4z7h/jnZymQyd/teRCBaho1+V
-----END CERTIFICATE-----
wRDELMAkGA1UEBhMCTkwxGTAXBgNV
BAoTEEdFQU5UIFZlcmVuaWdpbmcxGjAYBgNVBAMTEUdFQU5UIE9WIFJTQSBDQSA0
MIICIjANBgkqhkiG9w0BAQEFAAOCAg8AMIICCgKCAgEApYhi1aEiPsg9ZKRMAw9Q
r8Mthsr6R20VSfFeh7TgwtLQi6RSRLOh4or4EMG/1th8lijv7xnBMVZkTysFiPmT
PiLOfvz+QwO1NwjvgY+Jrs7fSoVA/TQkXzcxu4Tl3WHi+qJmKLJVu/JOuHud6mOp
LWkIbhODSzOxANJ24IGPx9h4OXDyy6/342eE6UPXCtJ8AzeumTG6Dfv5KVx24lCF
TGUzHUB+j+g0lSKg/Sf1OzgCajJV9enmZ/84ydh48wPp6vbWf1H0O3Rd3LhpMSVn
TqFTLKZSbQeLcx/l9DOKZfBCC9ghWxsgTqW9gQ7v3T3aIfSaVC9rnwVxO0VjmDdP
FNbdoxnh0zYwf45nV1QQgpRwZJ93yWedhp4ch1a6Ajwqs+wv4mZzmBSjovtV0mKw
d+CQbSToalEUP4QeJq4Udz5WNmNMI4OYP6cgrnlJ50aa0DZPlJqrKQPGL69KQQz1
2WgxvhCuVU70y6ZWAPopBa1ykbsttpLxADZre5cH573lIuLHdjx7NjpYIXRx2+QJ
URnX2qx37eZIxYXz8ggM+wXH6RDbU3V2o5DP67hXPHSAbA+p0orjAocpk2osxHKo
NSE3LCjNx8WVdxnXvuQ28tKdaK69knfm3bB7xpdfsNNTPH9ElcjscWZxpeZ5Iij8
lyrCG1z0vSWtSBsgSnUyG/sCAwEAAaOCAYswggGHMB8GA1UdIwQYMBaAFFN5v1qq
K0rPVIDh2JvAnfKyA2bLMB0GA1UdDgQWBBRvHTVJEGwy+lmgnryK6B+VvnF6DDAO
BgNVHQ8BAf8EBAMCAYYwEgYDVR0TAQH/BAgwBgEB/wIBADAdBgNVHSUEFjAUBggr
BgEFBQcDAQYIKwYBBQUHAwIwOAYDVR0gBDEwLzAtBgRVHSAAMCUwIwYIKwYBBQUH
AgEWF2h0dHBzOi8vc2VjdGlnby5jb20vQ1BTMFAGA1UdHwRJMEcwRaBDoEGGP2h0
dHA6Ly9jcmwudXNlcnRydXN0LmNvbS9VU0VSVHJ1c3RSU0FDZXJ0aWZpY2F0aW9u
QXV0aG9yaXR5LmNybDB2BggrBgEFBQcBAQRqMGgwPwYIKwYBBQUHMAKGM2h0dHA6
Ly9jcnQudXNlcnRydXN0LmNvbS9VU0VSVHJ1c3RSU0FBZGRUcnVzdENBLmNydDAl
BggrBgEFBQcwAYYZaHR0cDovL29jc3AudXNlcnRydXN0LmNvbTANBgkqhkiG9w0B
AQwFAAOCAgEAUtlC3e0xj/1BMfPhdQhUXeLjb0xp8UE28kzWE5xDzGKbfGgnrT2R
lw5gLIx+/cNVrad//+MrpTppMlxq59AsXYZW3xRasrvkjGfNR3vt/1RAl8iI31lG
hIg6dfIX5N4esLkrQeN8HiyHKH6khm4966IkVVtnxz5CgUPqEYn4eQ+4eeESrWBh
AqXaiv7HRvpsdwLYekAhnrlGpioZ/CJIT2PTTxf+GHM6cuUnNqdUzfvrQgA8kt1/
ASXx2od/M+c8nlJqrGz29lrJveJOSEMX0c/ts02WhsfMhkYa6XujUZLmvR1Eq08r
48/EZ4l+t5L4wt0DV8VaPbsEBF1EOFpz/YS2H6mSwcFaNJbnYqqJHIvm3PLJHkFm
EoLXRVrQXdCT+3wgBfgU6heCV5CYBz/YkrdWES7tiiT8sVUDqXmVlTsbiRNiyLs2
bmEWWFUl76jViIJog5fongEqN3jLIGTG/mXrJT1UyymIcobnIGrbwwRVz/mpFQo0
vBYIi1k2ThVh0Dx88BbF9YiP84dd8Fkn5wbE6FxXYJ287qfRTgmhePecPc73Yrzt
apdRcsKVGkOpaTIJP/l+lAHRLZxk/dUtyN95G++bOSQqnOCpVPabUGl2E/OEyFrp
Ipwgu2L/WJclvd6g+ZA/iWkLSMcpnFb+uX6QBqvD6+RNxul1FaB5iHY=
-----END CERTIFICATE-----
-----BEGIN CERTIFICATE-----
MIIFgTCCBGmgAwIBAgIQOXJEOvkit1HX02wQ3TE1lTANBgkqhkiG9w0BAQwFADB7
MQswCQYDVQQGEwJHQjEbMBkGA1UECAwSR3JlYXRlciBNYW5jaGVzdGVyMRAwDgYD
VQQHDAdTYWxmb3JkMRowGAYDVQQKDBFDb21vZG8gQ0EgTGltaXRlZDEhMB8GA1UE
AwwYQUFBIENlcnRpZmljYXRlIFNlcnZpY2VzMB4XDTE5MDMxMjAwMDAwMFoXDTI4
MTIzMTIzNTk1OVowgYgxCzAJBgNVBAYTAlVTMRMwEQYDVQQIEwpOZXcgSmVyc2V5
MRQwEgYDVQQHEwtKZXJzZXkgQ2l0eTEeMBwGA1UEChMVVGhlIFVTRVJUUlVTVCBO
ZXR3b3JrMS4wLAYDVQQDEyVVU0VSVHJ1c3QgUlNBIENlcnRpZmljYXRpb24gQXV0
aG9yaXR5MIICIjANBgkqhkiG9w0BAQEFAAOCAg8AMIICCgKCAgEAgBJlFzYOw9sI
s9CsVw127c0n00ytUINh4qogTQktZAnczomfzD2p7PbPwdzx07HWezcoEStH2jnG
vDoZtF+mvX2do2NCtnbyqTsrkfjib9DsFiCQCT7i6HTJGLSR1GJk23+jBvGIGGqQ
Ijy8/hPwhxR79uQfjtTkUcYRZ0YIUcuGFFQ/vDP+fmyc/xadGL1RjjWmp2bIcmfb
IWax1Jt4A8BQOujM8Ny8nkz+rwWWNR9XWrf/zvk9tyy29lTdyOcSOk2uTIq3XJq0
tyA9yn8iNK5+O2hmAUTnAU5GU5szYPeUvlM3kHND8zLDU+/bqv50TmnHa4xgk97E
xwzf4TKuzJM7UXiVZ4vuPVb+DNBpDxsP8yUmazNt925H+nND5X4OpWaxKXwyhGNV
icQNwZNUMBkTrNN9N6frXTpsNVzbQdcS2qlJC9/YgIoJk2KOtWbPJYjNhLixP6Q5
D9kCnusSTJV882sFqV4Wg8y4Z+LoE53MW4LTTLPtW//e5XOsIzstAL81VXQJSdhJ
WBp/kjbmUZIO8yZ9HE0XvMnsQybQv0FfQKlERPSZ51eHnlAfV1SoPv10Yy+xUGUJ
5lhCLkMaTLTwJUdZ+gQek9QmRkpQgbLevni3/GcV4clXhB4PY9bpYrrWX1Uu6lzG
KAgEJTm4Diup8kyXHAc/DVL17e8vgg8CAwEAAaOB8jCB7zAfBgNVHSMEGDAWgBSg
EQojPpbxB+zirynvgqV/0DCktDA�RMW�
2022-10-27T12:28:23.768+0200 7fe016a53700 20 req 6390965372935311081 0.380005658s iam:put_role_policy rgw::auth::keystone::EC2Engine denied with reason=-2
2022-10-27T12:28:23.768+0200 7fe016a53700 20 req 6390965372935311081 0.380005658s iam:put_role_policy rgw::auth::s3::AWSv2ExternalAuthStrategy denied with reason=-2
2022-10-27T12:28:23.768+0200 7fe016a53700 20 req 6390965372935311081 0.380005658s iam:put_role_policy rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::LocalEngine
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s v4 signature format = fa952a9265d89acb81ecbad78fd9f5b79738059439b6725a6c016358a9181e7e
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s v4 credential format = TESTER/20221027//iam/aws4_request
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s access key id = TESTER
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s credential scope = 20221027//iam/aws4_request
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s canonical headers format = content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102823Z
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s payload request hash = 6df672637084b898189d55363421cf18b799b568805efff06255af90ab09edcd
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s canonical request = POST
/
content-type:application/x-www-form-urlencoded; charset=utf-8
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-date:20221027T102823Z
content-type;host;x-amz-date
6df672637084b898189d55363421cf18b799b568805efff06255af90ab09edcd
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s canonical request hash = 8690370b37461da80ac954c01b5eceb50d3ad92d3c240dc3b31dd96e8ba01d70
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s string to sign = AWS4-HMAC-SHA256
20221027T102823Z
20221027//iam/aws4_request
8690370b37461da80ac954c01b5eceb50d3ad92d3c240dc3b31dd96e8ba01d70
2022-10-27T12:28:23.768+0200 7fe016a53700 20 req 6390965372935311081 0.380005658s iam:put_role_policy get_system_obj_state: rctx=0x7fe016a49148 obj=default.rgw.meta:users.keys:TESTER state=0x56536445dde0 s->prefetch_data=0
2022-10-27T12:28:23.768+0200 7fe016a53700 10 req 6390965372935311081 0.380005658s iam:put_role_policy cache get: name=default.rgw.meta+users.keys+TESTER : type miss (requested=0x6, cached=0x3)
2022-10-27T12:28:23.768+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25134 11.2 11:6b213cc5:users.keys::TESTER:head [getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364889400 con 0x5653635c7000
2022-10-27T12:28:23.769+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4144 ==== osd_op_reply(25134 TESTER [getxattrs out=4b,stat out=16b] v0'0 uv109 ondisk = 0) v8 ==== 192+0+20 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.769+0200 7fe016a53700 10 req 6390965372935311081 0.381005675s iam:put_role_policy cache put: name=default.rgw.meta+users.keys+TESTER info.flags=0x6
2022-10-27T12:28:23.769+0200 7fe016a53700 10 req 6390965372935311081 0.381005675s iam:put_role_policy moving default.rgw.meta+users.keys+TESTER to cache LRU end
2022-10-27T12:28:23.769+0200 7fe016a53700 20 req 6390965372935311081 0.381005675s iam:put_role_policy get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.769+0200 7fe016a53700 10 req 6390965372935311081 0.381005675s iam:put_role_policy cache get: name=default.rgw.meta+users.keys+TESTER : hit (requested=0x1, cached=0x7)
2022-10-27T12:28:23.769+0200 7fe016a53700 20 req 6390965372935311081 0.381005675s iam:put_role_policy get_system_obj_state: rctx=0x7fe016a49148 obj=default.rgw.meta:users.uid:TESTER state=0x565364424760 s->prefetch_data=0
2022-10-27T12:28:23.769+0200 7fe016a53700 10 req 6390965372935311081 0.381005675s iam:put_role_policy cache get: name=default.rgw.meta+users.uid+TESTER : type miss (requested=0x16, cached=0x7)
2022-10-27T12:28:23.769+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25135 11.0 11:142c659c:users.uid::TESTER:head [call version.read in=11b,getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364898400 con 0x5653635c7000
2022-10-27T12:28:23.769+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4145 ==== osd_op_reply(25135 TESTER [call out=48b,getxattrs out=75b,stat out=16b] v0'0 uv43 ondisk = 0) v8 ==== 234+0+139 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.769+0200 7fe016a53700 10 req 6390965372935311081 0.381005675s iam:put_role_policy cache put: name=default.rgw.meta+users.uid+TESTER info.flags=0x16
2022-10-27T12:28:23.769+0200 7fe016a53700 10 req 6390965372935311081 0.381005675s iam:put_role_policy moving default.rgw.meta+users.uid+TESTER to cache LRU end
2022-10-27T12:28:23.769+0200 7fe016a53700 10 req 6390965372935311081 0.381005675s iam:put_role_policy updating xattr: name=ceph.objclass.version bl.length()=42
2022-10-27T12:28:23.769+0200 7fe016a53700 20 req 6390965372935311081 0.381005675s iam:put_role_policy get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.769+0200 7fe016a53700 10 req 6390965372935311081 0.381005675s iam:put_role_policy cache get: name=default.rgw.meta+users.uid+TESTER : hit (requested=0x11, cached=0x17)
2022-10-27T12:28:23.769+0200 7fe016a53700 10 req 6390965372935311081 0.381005675s iam:put_role_policy chain_cache_entry: cache_locator=default.rgw.meta+users.uid+TESTER
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s date_k = 0c09910a6d1a529c2071e10947c39d7e0896c7a07aa5678c6c0bca15c092052a
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s region_k = bc9b793a8630d850965e17cf17a7b060f24297d068bbc33f6fb35a7cd24c5d26
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s service_k = ed2628cfe31af130dfdefb8ed41607732f12910edffda4b503a94d30e6f084cd
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s signing_k = 8daebe6f34f48ac97853ac7c31e3fc8f6c4c1f276baa28cf43bf28f1ced1d04c
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s generated signature = fa952a9265d89acb81ecbad78fd9f5b79738059439b6725a6c016358a9181e7e
2022-10-27T12:28:23.770+0200 7fe016a53700 15 req 6390965372935311081 0.382005692s iam:put_role_policy string_to_sign=AWS4-HMAC-SHA256
20221027T102823Z
20221027//iam/aws4_request
8690370b37461da80ac954c01b5eceb50d3ad92d3c240dc3b31dd96e8ba01d70
2022-10-27T12:28:23.770+0200 7fe016a53700 15 req 6390965372935311081 0.382005692s iam:put_role_policy server signature=fa952a9265d89acb81ecbad78fd9f5b79738059439b6725a6c016358a9181e7e
2022-10-27T12:28:23.770+0200 7fe016a53700 15 req 6390965372935311081 0.382005692s iam:put_role_policy client signature=fa952a9265d89acb81ecbad78fd9f5b79738059439b6725a6c016358a9181e7e
2022-10-27T12:28:23.770+0200 7fe016a53700 15 req 6390965372935311081 0.382005692s iam:put_role_policy compare=0
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s iam:put_role_policy rgw::auth::s3::LocalEngine granted access
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s iam:put_role_policy rgw::auth::s3::AWSAuthStrategy granted access
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy normalizing buckets and tenants
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s s->object=<NULL> s->bucket=
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy init permissions
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s iam:put_role_policy get_system_obj_state: rctx=0x7fe016a490d8 obj=default.rgw.meta:users.uid:TESTER state=0x56536445dde0 s->prefetch_data=0
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s iam:put_role_policy cache get: name=default.rgw.meta+users.uid+TESTER : hit (requested=0x6, cached=0x17)
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s iam:put_role_policy get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s iam:put_role_policy Read xattr: user.rgw.idtag
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s iam:put_role_policy cache get: name=default.rgw.meta+users.uid+TESTER : hit (requested=0x3, cached=0x17)
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy recalculating target
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy reading permissions
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy init op
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy verifying op mask
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s iam:put_role_policy required_mask= 0 user.op_mask=7
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy verifying op permissions
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s get_system_obj_state: rctx=0x7fe016a49ab0 obj=default.rgw.meta:roles:role_names.S3Accesswebid3 state=0x565364424760 s->prefetch_data=0
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s cache get: name=default.rgw.meta+roles+role_names.S3Accesswebid3 : hit (requested=0x6, cached=0x7)
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s cache get: name=default.rgw.meta+roles+role_names.S3Accesswebid3 : hit (requested=0x1, cached=0x7)
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s get_system_obj_state: rctx=0x7fe016a49ab0 obj=default.rgw.meta:roles:roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 state=0x565364424760 s->prefetch_data=0
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s cache get: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 : hit (requested=0x6, cached=0x7)
2022-10-27T12:28:23.770+0200 7fe016a53700 20 req 6390965372935311081 0.382005692s get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.770+0200 7fe016a53700 10 req 6390965372935311081 0.382005692s cache get: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 : hit (requested=0x1, cached=0x7)
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy verifying op params
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy pre-executing
2022-10-27T12:28:23.770+0200 7fe016a53700 2 req 6390965372935311081 0.382005692s iam:put_role_policy executing
2022-10-27T12:28:23.770+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25136 11.3 11:f1d6a725:roles::roles.f97dffe3-ee5c-4922-8818-46cc7ebca422:head [delete,create,writefull 0~576 in=576b] snapc 0=[] ondisk+write+known_if_redirected e6899044) v8 -- 0x5653646aa800 con 0x5653635c7000
2022-10-27T12:28:23.800+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4146 ==== osd_op_reply(25136 roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 [delete,create,writefull 0~576] v6899044'49 uv49 ondisk = 0) v8 ==== 270+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.800+0200 7fe016a53700 10 req 6390965372935311081 0.412006140s iam:put_role_policy cache put: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 info.flags=0x7
2022-10-27T12:28:23.800+0200 7fe016a53700 10 req 6390965372935311081 0.412006140s iam:put_role_policy moving default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 to cache LRU end
2022-10-27T12:28:23.800+0200 7fe016a53700 10 req 6390965372935311081 0.412006140s iam:put_role_policy distributing notification oid=default.rgw.control:notify.7 cni=[op: 0, obj: default.rgw.meta:roles:roles.f97dffe3-ee5c-4922-8818-46cc7ebca422, ofs0, ns]
2022-10-27T12:28:23.800+0200 7fe016a53700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25137 6.1 6:93e5b521:::notify.7:head [notify cookie 94916164022656 in=775b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x5653647bcc00 con 0x5653635c7c00
2022-10-27T12:28:23.800+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17379 ==== watch-notify(notify (1) cookie 94916149218560 notify 29631168353665265 ret 0) v3 ==== 805+0+0 (crc 0 0 0) 0x56536361fd40 con 0x5653635c7c00
2022-10-27T12:28:23.801+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17380 ==== osd_op_reply(25137 notify.7 [notify cookie 94916164022656 out=8b] v0'0 uv4662 ondisk = 0) v8 ==== 152+0+8 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.801+0200 7fe03c29e700 10 rgw watcher librados: RGWWatcher::handle_notify() notify_id 29631168353665265 cookie 94916149218560 notifier 112215246 bl.length()=763
2022-10-27T12:28:23.801+0200 7fe03c29e700 10 rgw watcher librados: cache put: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 info.flags=0x7
2022-10-27T12:28:23.801+0200 7fe03c29e700 10 rgw watcher librados: moving default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 to cache LRU end
2022-10-27T12:28:23.801+0200 7fe03c29e700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25138 6.1 6:93e5b521:::notify.7:head [notify-ack in=20b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x56536444b800 con 0x5653635c7c00
2022-10-27T12:28:23.801+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17381 ==== watch-notify(notify_complete (2) cookie 94916164022656 notify 29631168353665265 ret 0) v3 ==== 42+0+68 (crc 0 0 0) 0x565363620000 con 0x5653635c7c00
2022-10-27T12:28:23.801+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17382 ==== osd_op_reply(25138 notify.7 [notify-ack] v0'0 uv4662 ondisk = 0) v8 ==== 152+0+0 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.801+0200 7fe016a53700 2 req 6390965372935311081 0.413006157s iam:put_role_policy completing
2022-10-27T12:28:23.801+0200 7fe016a53700 2 req 6390965372935311081 0.413006157s iam:put_role_policy op status=0
2022-10-27T12:28:23.801+0200 7fe016a53700 2 req 6390965372935311081 0.413006157s iam:put_role_policy http status=200
2022-10-27T12:28:23.801+0200 7fe016a53700 1 ====== req done req=0x7fe016a4ab90 op status=0 http_status=200 latency=0.413006157s ======
2022-10-27T12:28:23.801+0200 7fe016a53700 1 civetweb: 0x56536388a000: 131.154.97.209 - - [27/Oct/2022:12:28:22 +0200] "POST / HTTP/1.1" 200 335 - Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
2022-10-27T12:28:23.850+0200 7fe016252700 20 CONTENT_LENGTH=1492
2022-10-27T12:28:23.850+0200 7fe016252700 20 CONTENT_TYPE=application/x-www-form-urlencoded; charset=utf-8
2022-10-27T12:28:23.850+0200 7fe016252700 20 HTTP_ACCEPT_ENCODING=identity
2022-10-27T12:28:23.850+0200 7fe016252700 20 HTTP_AMZ_SDK_INVOCATION_ID=3dcdd610-68a7-468c-8677-72e381c60fd6
2022-10-27T12:28:23.850+0200 7fe016252700 20 HTTP_AMZ_SDK_REQUEST=attempt=1
2022-10-27T12:28:23.850+0200 7fe016252700 20 HTTP_HOST=ceph-mon01-tb.cloud.cnaf.infn.it:7480
2022-10-27T12:28:23.850+0200 7fe016252700 20 HTTP_USER_AGENT=Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
2022-10-27T12:28:23.850+0200 7fe016252700 20 HTTP_VERSION=1.1
2022-10-27T12:28:23.850+0200 7fe016252700 20 REMOTE_ADDR=131.154.97.209
2022-10-27T12:28:23.850+0200 7fe016252700 20 REQUEST_METHOD=POST
2022-10-27T12:28:23.850+0200 7fe016252700 20 REQUEST_URI=/
2022-10-27T12:28:23.850+0200 7fe016252700 20 SCRIPT_URI=/
2022-10-27T12:28:23.850+0200 7fe016252700 20 SERVER_PORT=7480
2022-10-27T12:28:23.850+0200 7fe016252700 1 ====== starting new request req=0x7fe016249b90 =====
2022-10-27T12:28:23.850+0200 7fe016252700 2 req 313203872194829433 0.000000000s initializing for trans_id = tx000000458b94328731c79-00635a5d47-6b044ce-default
2022-10-27T12:28:23.850+0200 7fe016252700 10 req 313203872194829433 0.000000000s rgw api priority: s3=8 s3website=7
2022-10-27T12:28:23.850+0200 7fe016252700 10 req 313203872194829433 0.000000000s host=ceph-mon01-tb.cloud.cnaf.infn.it
2022-10-27T12:28:23.850+0200 7fe016252700 20 req 313203872194829433 0.000000000s subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0
2022-10-27T12:28:23.850+0200 7fe016252700 20 req 313203872194829433 0.000000000s final domain/bucket subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0 s->info.domain= s->info.request_uri=/
2022-10-27T12:28:23.850+0200 7fe016252700 20 req 313203872194829433 0.000000000s get_handler handler=26RGWHandler_REST_Service_S3
2022-10-27T12:28:23.850+0200 7fe016252700 10 req 313203872194829433 0.000000000s handler=26RGWHandler_REST_Service_S3
2022-10-27T12:28:23.850+0200 7fe016252700 2 req 313203872194829433 0.000000000s getting op 4
2022-10-27T12:28:23.850+0200 7fe016252700 10 req 313203872194829433 0.000000000s Content of POST: Action=AssumeRoleWithWebIdentity&Version=2011-06-15&RoleArn=arn%3Aaws%3Aiam%3A%3A%3Arole%2FS3Accesswebid3&RoleSessionName=Bob2&DurationSeconds=3600&WebIdentityToken=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJVaXBGTGo1WV8wS0YyZUFIUFFRMGZiLTBHa0RvVjE0dXdCSTVoblN6MTI0In0.eyJleHAiOjE2NjY4NjY3NDksImlhdCI6MTY2Njg2NjQ0OSwianRpIjoiMDllNTQyM2UtYTYyYy00YjNkLThmODctYTA2OWFjNGVmY2FmIiwiaXNzIjoiaHR0cHM6Ly9rZXljbG9hay1kZW1vLmNsb3VkLmNuYWYuaW5mbi5pdDo4NDQzL3JlYWxtcy9kZW1vIiwiYXVkIjoiYWNjb3VudCIsInN1YiI6ImY5YmRlMmYwLTFmYzUtNDYwYS05ZjBkLWUxYmNmNTY0ZWY2YSIsInR5cCI6IkJlYXJlciIsImF6cCI6InRlc3RpbmciLCJhY3IiOiIxIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iLCJkZWZhdWx0LXJvbGVzLWRlbW8iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ0ZXN0aW5nIjp7InJvbGVzIjpbInVtYV9wcm90ZWN0aW9uIl19LCJhY2NvdW50Ijp7InJvbGVzIjpbIm1hbmFnZS1hY2NvdW50IiwibWFuYWdlLWFjY291bnQtbGlua3MiLCJ2aWV3LXByb2ZpbGUiXX19LCJzY29wZSI6Im9wZW5pZCBlbWFpbCBwcm9maWxlIiwiZW1haWxfdmVyaWZpZWQiOmZhbHNlLCJjbGllbnRJZCI6InRlc3RpbmciLCJjbGllbnRIb3N0IjoiMTMxLjE1NC45Ny4yMDkiLCJwcmVmZXJyZWRfdXNlcm5hbWUiOiJzZXJ2aWNlLWFjY291bnQtdGVzdGluZyIsImNsaWVudEFkZHJlc3MiOiIxMzEuMTU0Ljk3LjIwOSJ9.baC9irZNqYhfKi9EoSQHZRq2YbZdLYxfTqK0S7vU1J8lQ5n92obcIBgqBPK3qAtrGrz1hB8a2H4H2t-COVCNfm5LYvmKXWjxv8RSmmxRO44A6rPOLVO9ni_JPgEP_9me6eRNq_AbYdl0BmTOn-KEHPy3at3MCXEqz7tDeFX3yMo0lA5v1v-Dw3_Slo-104IUo17ekRv-NjxHKPDo0IVT9-UyJZrxET0LCk1UZmeiwUfRW6E_aF07sQqQbDzoKdd952zXavicwbtgp5DiubE-sUn03kz37NB5K9h3AyZV2exNYg-8lxOKORf0IuBhob2zw1p3uSPpX-ZNwWlH-u9vUA
2022-10-27T12:28:23.850+0200 7fe016252700 10 req 313203872194829433 0.000000000s sts:assume_role_web_identity op=31RGWSTSAssumeRoleWithWebIdentity
2022-10-27T12:28:23.850+0200 7fe016252700 2 req 313203872194829433 0.000000000s sts:assume_role_web_identity verifying requester
2022-10-27T12:28:23.850+0200 7fe016252700 20 req 313203872194829433 0.000000000s sts:assume_role_web_identity rgw::auth::sts::DefaultStrategy: trying rgw::auth::sts::WebTokenEngine
2022-10-27T12:28:23.850+0200 7fe016252700 20 req 313203872194829433 0.000000000s sts:assume_role_web_identity payload = {"exp":1666866749,"iat":1666866449,"jti":"09e5423e-a62c-4b3d-8f87-a069ac4efcaf","iss":"https://keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo","aud":"account","sub":"f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a","typ":"Bearer","azp":"testing","acr":"1","realm_access":{"roles":["offline_access","uma_authorization","default-roles-demo"]},"resource_access":{"testing":{"roles":["uma_protection"]},"account":{"roles":["manage-account","manage-account-links","view-profile"]}},"scope":"openid email profile","email_verified":false,"clientId":"testing","clientHost":"131.154.97.209","preferred_username":"service-account-testing","clientAddress":"131.154.97.209"}
2022-10-27T12:28:23.850+0200 7fe016252700 20 req 313203872194829433 0.000000000s sts:assume_role_web_identity get_system_obj_state: rctx=0x7fe016248140 obj=default.rgw.meta:oidc:oidc_url.keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo state=0x56536462e2e0 s->prefetch_data=0
2022-10-27T12:28:23.850+0200 7fe016252700 10 req 313203872194829433 0.000000000s sts:assume_role_web_identity cache get: name=default.rgw.meta+oidc+oidc_url.keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo : expiry miss
2022-10-27T12:28:23.850+0200 7fe016252700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25139 11.0 11:2db8f34f:oidc::oidc_url.keycloak-demo.cloud.cnaf.infn.it%3a8443%2frealms%2fdemo:head [getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364899800 con 0x5653635c7000
2022-10-27T12:28:23.851+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4147 ==== osd_op_reply(25139 oidc_url.keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo [getxattrs out=4b,stat out=16b] v0'0 uv48 ondisk = 0) v8 ==== 244+0+20 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.851+0200 7fe016252700 10 req 313203872194829433 0.001000015s sts:assume_role_web_identity cache put: name=default.rgw.meta+oidc+oidc_url.keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo info.flags=0x6
2022-10-27T12:28:23.851+0200 7fe016252700 10 req 313203872194829433 0.001000015s sts:assume_role_web_identity adding default.rgw.meta+oidc+oidc_url.keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo to cache LRU end
2022-10-27T12:28:23.851+0200 7fe016252700 20 req 313203872194829433 0.001000015s sts:assume_role_web_identity get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.851+0200 7fe016252700 10 req 313203872194829433 0.001000015s sts:assume_role_web_identity cache get: name=default.rgw.meta+oidc+oidc_url.keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo : type miss (requested=0x1, cached=0x6)
2022-10-27T12:28:23.851+0200 7fe016252700 20 req 313203872194829433 0.001000015s sts:assume_role_web_identity rados->read ofs=0 len=0
2022-10-27T12:28:23.851+0200 7fe016252700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25140 11.0 11:2db8f34f:oidc::oidc_url.keycloak-demo.cloud.cnaf.infn.it%3a8443%2frealms%2fdemo:head [read 0~0] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364643c00 con 0x5653635c7000
2022-10-27T12:28:23.855+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4148 ==== osd_op_reply(25140 oidc_url.keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo [read 0~247 out=247b] v0'0 uv48 ondisk = 0) v8 ==== 202+0+247 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.855+0200 7fe016252700 20 req 313203872194829433 0.005000075s sts:assume_role_web_identity rados_obj.operate() r=0 bl.length=247
2022-10-27T12:28:23.855+0200 7fe016252700 10 req 313203872194829433 0.005000075s sts:assume_role_web_identity cache put: name=default.rgw.meta+oidc+oidc_url.keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo info.flags=0x1
2022-10-27T12:28:23.855+0200 7fe016252700 10 req 313203872194829433 0.005000075s sts:assume_role_web_identity moving default.rgw.meta+oidc+oidc_url.keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo to cache LRU end
2022-10-27T12:28:23.855+0200 7fe016252700 20 sending request to https://keycloak-demo.cloud.cnaf.infn.it:8443/realms/demo/protocol/openid-connect/certs
2022-10-27T12:28:23.855+0200 7fe016252700 20 register_request mgr=0x56536275fd40 req_data->id=92, curl_handle=0x5653645b0ec0
2022-10-27T12:28:23.855+0200 7fe03eaa3700 20 link_request req_data=0x56536475da40 req_data->id=92, curl_handle=0x5653645b0ec0
2022-10-27T12:28:23.859+0200 7fe016252700 20 req 313203872194829433 0.009000134s sts:assume_role_web_identity HTTP status: 200
2022-10-27T12:28:23.859+0200 7fe016252700 20 req 313203872194829433 0.009000134s sts:assume_role_web_identity JSON Response is: {"keys":[{"kid":"UipFLj5Y_0KF2eAHPQQ0fb-0GkDoV14uwBI5hnSz124","kty":"RSA","alg":"RS256","use":"sig","n":"1FrcELdOzfj7RX7oQ6vP47CXrlQKuuzE8fiesJDxaUoWDqmimddcLjCObl5lLZiLpxvVqIRKTh0TwA2qJiXifIxetBB8tNnMb_NtZX7H3MLkkvvnvIxmpYL4DraXQQi-ebiFyCDgMG1aqzXSlpItHRRX8JCP7a7IcLKDDYHre1IjOElfcxdS1GrOEdy4iyTpx27fMsx0vBQPogNGpl3R_UcAJzHMSuWpbXfehT01GhHEAqaG7PuwaelfjK0n4Mvm3E-XAa4NTlePSbR9oZq1yomzCKI_4zV38cMWel2VxZKUv701Xb_hMxIWePDXSDW_4L-8ka4yGru-idQCrmPL1w","e":"AQAB","x5c":["MIIClzCCAX8CBgGEFCiAijANBgkqhkiG9w0BAQsFADAPMQ0wCwYDVQQDDARkZW1vMB4XDTIyMTAyNjExNTY0OVoXDTMyMTAyNjExNTgyOVowDzENMAsGA1UEAwwEZGVtbzCCASIwDQYJKoZIhvcNAQEBBQADggEPADCCAQoCggEBANRa3BC3Ts34+0V+6EOrz+Owl65UCrrsxPH4nrCQ8WlKFg6popnXXC4wjm5eZS2Yi6cb1aiESk4dE8ANqiYl4nyMXrQQfLTZzG/zbWV+x9zC5JL757yMZqWC+A62l0EIvnm4hcgg4DBtWqs10paSLR0UV/CQj+2uyHCygw2B63tSIzhJX3MXUtRqzhHcuIsk6cdu3zLMdLwUD6IDRqZd0f1HACcxzErlqW133oU9NRoRxAKmhuz7sGnpX4ytJ+DL5txPlwGuDU5Xj0m0faGatcqJswiiP+M1d/HDFnpdlcWSlL+9NV2/4TMSFnjw10g1v+C/vJGuMhq7vonUAq5jy9cCAwEAATANBgkqhkiG9w0BAQsFAAOCAQEACOh3EV1eqi3FWRl3bLq6cqW3A3/J3lJx/ZOWA+06steM4eileiKdiErwW6qt3OiqL4G3QsttD3saWLCftYZuApxX3D2x5nUwYVr+xsKKK4iWaZ+21/n2RA/Sux2aY/Sc8y/WQVGXeStWqjll5mOToPImCLibHmx42pcSPJkj7vodBJyjJWf2jfvV0qvFWv47pTlmHEchypkUIkXejFeJS6SyjWMErEUC5DRorx8vz3/KD34wCBQUSVWN1qfGvIgnzYmMpaCzg/kSzbpQK+H7yO27uxzK0i/+PkHykh8gRBYwsvQ49dlUomOaNcCh/7e1O0VI4O3/x0csH+mZTO8z+A=="],"x5t":"LIR-D0hdLWLFB7hDspQHSvNglCU","x5t#S256":"g3-eS81-HLsbMQrRTf3B01mAdXBAQ5LcCUSzScctRKM"},{"kid":"XD-wohiPQe1EFG24eTzuGQI-g-GPUIlP1pE4hBzMtN8","kty":"RSA","alg":"RSA-OAEP","use":"enc","n":"uLoNIVFu3LTUtmUnma1CgrztG1ZmFQ8P5OXrT0VlcbkKF38LqUQPvVecBwZUpsrXiVvZQYwOhp4H7UcvmsdSfIF5C_NDo-VRnwyBXkK-fRb3GYcKqDu-nAdVA4ZuG2P2315Mup1bBGjx_8oRvd-uSW_2FeTcfxWQ3-4sUnyF9w-lgAq65D6w-zKjhqe7gfKgH_XiJDps3Ymj-1lgIPfQqB2BOg0MjYGZHak070hT-mHXjEage2H92ujBaQZ1HBAEH-j8GKFl9JniSjBxc6sPrp_rlFYibVPMPxpCCtRLQW3HNnUCuzO9KB79WtTB-zDjmTYk20iNKMyct86Tlwdo3Q","e":"AQAB","x5c":["MIIClzCCAX8CBgGEFCiBbDANBgkqhkiG9w0BAQsFADAPMQ0wCwYDVQQDDARkZW1vMB4XDTIyMTAyNjExNTY0OVoXDTMyMTAyNjExNTgyOVowDzENMAsGA1UEAwwEZGVtbzCCASIwDQYJKoZIhvcNAQEBBQADggEPADCCAQoCggEBALi6DSFRbty01LZlJ5mtQoK87RtWZhUPD+Tl609FZXG5Chd/C6lED71XnAcGVKbK14lb2UGMDoaeB+1HL5rHUnyBeQvzQ6PlUZ8MgV5Cvn0W9xmHCqg7vpwHVQOGbhtj9t9eTLqdWwRo8f/KEb3frklv9hXk3H8VkN/uLFJ8hfcPpYAKuuQ+sPsyo4anu4HyoB/14iQ6bN2Jo/tZYCD30KgdgToNDI2BmR2pNO9IU/ph14xGoHth/drowWkGdRwQBB/o/BihZfSZ4kowcXOrD66f65RWIm1TzD8aQgrUS0FtxzZ1ArszvSge/VrUwfsw45k2JNtIjSjMnLfOk5cHaN0CAwEAATANBgkqhkiG9w0BAQsFAAOCAQEAjkk2F7/sb73E8LHV4TTjpl7j3IKTtDWB7GgSFonJs1KLienK/1zUKyjdR8gJ/F6BIOrSsxsMV5sDRmz0zXPhQSo1Y2pP2FRquUCYf4EFR2X8/HvcL0rbFVybfc/N0kUo80dTAoKla/l0P+bvf/eOAR6saP0CD9buGnkANGTaPswOxFGaQP8LPYrVBJYCeOagMgQ4irFYzQRbsdN4q86phOjq1Y4IoXV7b2o5RDKAAewkuWDNvlVsya//xGeEOxeP875KIo74xvTUKk12W7ELAT+ZUqLNouAxj1sthMwYTWPXAbnt5tJD2uXoKSJjX25WTZj9l8e1OwOSu6aMFIrWAw=="],"x5t":"vVkkPwlaJFh6duz-pVDaeqs0TzA","x5t#S256":"Q-6aXA6XCqjeSQ7HhknwYqgG0fDR9AyEHZDpdD699wI"}]}
2022-10-27T12:28:23.859+0200 7fe016252700 20 req 313203872194829433 0.009000134s sts:assume_role_web_identity Certificate is: -----BEGIN CERTIFICATE-----
MIIClzCCAX8CBgGEFCiAijANBgkqhkiG9w0BAQsFADAPMQ0wCwYDVQQDDARkZW1vMB4XDTIyMTAyNjExNTY0OVoXDTMyMTAyNjExNTgyOVowDzENMAsGA1UEAwwEZGVtbzCCASIwDQYJKoZIhvcNAQEBBQADggEPADCCAQoCggEBANRa3BC3Ts34+0V+6EOrz+Owl65UCrrsxPH4nrCQ8WlKFg6popnXXC4wjm5eZS2Yi6cb1aiESk4dE8ANqiYl4nyMXrQQfLTZzG/zbWV+x9zC5JL757yMZqWC+A62l0EIvnm4hcgg4DBtWqs10paSLR0UV/CQj+2uyHCygw2B63tSIzhJX3MXUtRqzhHcuIsk6cdu3zLMdLwUD6IDRqZd0f1HACcxzErlqW133oU9NRoRxAKmhuz7sGnpX4ytJ+DL5txPlwGuDU5Xj0m0faGatcqJswiiP+M1d/HDFnpdlcWSlL+9NV2/4TMSFnjw10g1v+C/vJGuMhq7vonUAq5jy9cCAwEAATANBgkqhkiG9w0BAQsFAAOCAQEACOh3EV1eqi3FWRl3bLq6cqW3A3/J3lJx/ZOWA+06steM4eileiKdiErwW6qt3OiqL4G3QsttD3saWLCftYZuApxX3D2x5nUwYVr+xsKKK4iWaZ+21/n2RA/Sux2aY/Sc8y/WQVGXeStWqjll5mOToPImCLibHmx42pcSPJkj7vodBJyjJWf2jfvV0qvFWv47pTlmHEchypkUIkXejFeJS6SyjWMErEUC5DRorx8vz3/KD34wCBQUSVWN1qfGvIgnzYmMpaCzg/kSzbpQK+H7yO27uxzK0i/+PkHykh8gRBYwsvQ49dlUomOaNcCh/7e1O0VI4O3/x0csH+mZTO8z+A==
-----END CERTIFICATE-----
2022-10-27T12:28:23.860+0200 7fe016252700 20 req 313203872194829433 0.010000149s sts:assume_role_web_identity rgw::auth::sts::WebTokenEngine granted access
2022-10-27T12:28:23.860+0200 7fe016252700 20 req 313203872194829433 0.010000149s sts:assume_role_web_identity get_system_obj_state: rctx=0x7fe016248548 obj=default.rgw.meta:users.uid:$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a state=0x56536462e2e0 s->prefetch_data=0
2022-10-27T12:28:23.860+0200 7fe016252700 10 req 313203872194829433 0.010000149s sts:assume_role_web_identity cache get: name=default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a : expiry miss
2022-10-27T12:28:23.860+0200 7fe016252700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25141 11.3 11:eb7775b6:users.uid::$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a:head [getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x56536476ec00 con 0x5653635c7000
2022-10-27T12:28:23.860+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4149 ==== osd_op_reply(25141 $oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a [getxattrs out=75b,stat out=16b] v0'0 uv43 ondisk = 0) v8 ==== 228+0+91 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.860+0200 7fe016252700 10 req 313203872194829433 0.010000149s sts:assume_role_web_identity cache put: name=default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a info.flags=0x6
2022-10-27T12:28:23.860+0200 7fe016252700 10 req 313203872194829433 0.010000149s sts:assume_role_web_identity adding default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a to cache LRU end
2022-10-27T12:28:23.860+0200 7fe016252700 10 req 313203872194829433 0.010000149s sts:assume_role_web_identity updating xattr: name=ceph.objclass.version bl.length()=42
2022-10-27T12:28:23.860+0200 7fe016252700 20 req 313203872194829433 0.010000149s sts:assume_role_web_identity get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.860+0200 7fe016252700 10 req 313203872194829433 0.010000149s sts:assume_role_web_identity cache get: name=default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a : type miss (requested=0x1, cached=0x6)
2022-10-27T12:28:23.860+0200 7fe016252700 20 req 313203872194829433 0.010000149s sts:assume_role_web_identity rados->read ofs=0 len=0
2022-10-27T12:28:23.860+0200 7fe016252700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25142 11.3 11:eb7775b6:users.uid::$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a:head [read 0~0] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x56536476e800 con 0x5653635c7000
2022-10-27T12:28:23.867+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4150 ==== osd_op_reply(25142 $oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a [read 0~257 out=257b] v0'0 uv43 ondisk = 0) v8 ==== 186+0+257 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.867+0200 7fe016252700 20 req 313203872194829433 0.017000252s sts:assume_role_web_identity rados_obj.operate() r=0 bl.length=257
2022-10-27T12:28:23.867+0200 7fe016252700 10 req 313203872194829433 0.017000252s sts:assume_role_web_identity cache put: name=default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a info.flags=0x1
2022-10-27T12:28:23.867+0200 7fe016252700 10 req 313203872194829433 0.017000252s sts:assume_role_web_identity moving default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a to cache LRU end
2022-10-27T12:28:23.867+0200 7fe016252700 2 req 313203872194829433 0.017000252s sts:assume_role_web_identity normalizing buckets and tenants
2022-10-27T12:28:23.867+0200 7fe016252700 10 req 313203872194829433 0.017000252s s->object=<NULL> s->bucket=
2022-10-27T12:28:23.867+0200 7fe016252700 2 req 313203872194829433 0.017000252s sts:assume_role_web_identity init permissions
2022-10-27T12:28:23.867+0200 7fe016252700 20 req 313203872194829433 0.017000252s sts:assume_role_web_identity get_system_obj_state: rctx=0x7fe0162480d8 obj=default.rgw.meta:users.uid:$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a state=0x56536462e2e0 s->prefetch_data=0
2022-10-27T12:28:23.867+0200 7fe016252700 10 req 313203872194829433 0.017000252s sts:assume_role_web_identity cache get: name=default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a : type miss (requested=0x6, cached=0x3)
2022-10-27T12:28:23.867+0200 7fe016252700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25143 11.3 11:eb7775b6:users.uid::$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a:head [getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x56536476f400 con 0x5653635c7000
2022-10-27T12:28:23.868+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4151 ==== osd_op_reply(25143 $oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a [getxattrs out=75b,stat out=16b] v0'0 uv43 ondisk = 0) v8 ==== 228+0+91 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.868+0200 7fe016252700 10 req 313203872194829433 0.018000267s sts:assume_role_web_identity cache put: name=default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a info.flags=0x6
2022-10-27T12:28:23.868+0200 7fe016252700 10 req 313203872194829433 0.018000267s sts:assume_role_web_identity moving default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a to cache LRU end
2022-10-27T12:28:23.868+0200 7fe016252700 10 req 313203872194829433 0.018000267s sts:assume_role_web_identity updating xattr: name=ceph.objclass.version bl.length()=42
2022-10-27T12:28:23.868+0200 7fe016252700 20 req 313203872194829433 0.018000267s sts:assume_role_web_identity get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.868+0200 7fe016252700 20 req 313203872194829433 0.018000267s sts:assume_role_web_identity Read xattr: user.rgw.idtag
2022-10-27T12:28:23.868+0200 7fe016252700 10 req 313203872194829433 0.018000267s sts:assume_role_web_identity cache get: name=default.rgw.meta+users.uid+$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a : hit (requested=0x3, cached=0x7)
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity recalculating target
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity reading permissions
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity init op
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity verifying op mask
2022-10-27T12:28:23.868+0200 7fe016252700 20 req 313203872194829433 0.018000267s sts:assume_role_web_identity required_mask= 0 user.op_mask=7
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity verifying op permissions
2022-10-27T12:28:23.868+0200 7fe016252700 20 req 313203872194829433 0.018000267s get_system_obj_state: rctx=0x7fe0162485d0 obj=default.rgw.meta:roles:role_names.S3Accesswebid3 state=0x56536462e520 s->prefetch_data=0
2022-10-27T12:28:23.868+0200 7fe016252700 10 req 313203872194829433 0.018000267s cache get: name=default.rgw.meta+roles+role_names.S3Accesswebid3 : hit (requested=0x6, cached=0x7)
2022-10-27T12:28:23.868+0200 7fe016252700 20 req 313203872194829433 0.018000267s get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.868+0200 7fe016252700 10 req 313203872194829433 0.018000267s cache get: name=default.rgw.meta+roles+role_names.S3Accesswebid3 : hit (requested=0x1, cached=0x7)
2022-10-27T12:28:23.868+0200 7fe016252700 20 req 313203872194829433 0.018000267s get_system_obj_state: rctx=0x7fe0162485d0 obj=default.rgw.meta:roles:roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 state=0x56536462e520 s->prefetch_data=0
2022-10-27T12:28:23.868+0200 7fe016252700 10 req 313203872194829433 0.018000267s cache get: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 : hit (requested=0x6, cached=0x7)
2022-10-27T12:28:23.868+0200 7fe016252700 20 req 313203872194829433 0.018000267s get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.868+0200 7fe016252700 10 req 313203872194829433 0.018000267s cache get: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 : hit (requested=0x1, cached=0x7)
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity verifying op params
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity pre-executing
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity executing
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity completing
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity op status=0
2022-10-27T12:28:23.868+0200 7fe016252700 2 req 313203872194829433 0.018000267s sts:assume_role_web_identity http status=200
2022-10-27T12:28:23.868+0200 7fe016252700 1 ====== req done req=0x7fe016249b90 op status=0 http_status=200 latency=0.018000267s ======
2022-10-27T12:28:23.868+0200 7fe016252700 1 civetweb: 0x56536388a9d8: 131.154.97.209 - - [27/Oct/2022:12:28:23 +0200] "POST / HTTP/1.1" 200 1485 - Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
2022-10-27T12:28:23.912+0200 7fe015a51700 20 CONTENT_LENGTH=0
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_ACCEPT_ENCODING=identity
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_AMZ_SDK_INVOCATION_ID=f405308b-6536-40d8-892f-a1cc91576fd5
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_AMZ_SDK_REQUEST=attempt=1
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_AUTHORIZATION=AWS4-HMAC-SHA256 Credential=MKC48AZuyDFKzcnQYx0/20221027//s3/aws4_request, SignedHeaders=host;x-amz-content-sha256;x-amz-date;x-amz-security-token, Signature=6d56d7a481ab19eca0dcc82fc4e620f3ffc5086a3a3963ff8a6b57304a777988
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_HOST=ceph-mon01-tb.cloud.cnaf.infn.it:7480
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_USER_AGENT=Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_VERSION=1.1
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_X_AMZ_CONTENT_SHA256=e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_X_AMZ_DATE=20221027T102823Z
2022-10-27T12:28:23.912+0200 7fe015a51700 20 HTTP_X_AMZ_SECURITY_TOKEN=RXgL3jnjlrfpTBy52si0Mwz94FzoRk11N0J7McThk9NhsXxqR3XL+dTKrslnshu3eVr1eMVkela/UEY2ysvbI3U5XKrhPdTIIfBzm1+OdQpjik1+ywzHyPxElPZkF+Kvten7W7/MvG4LobZkMnyZhVppzlWkhk8ilBO1H6NlYw9II+uO0LGqYnLGjTmRw0drorM8ezeoaHHDvSl0O2dqBx7EwODHNXU5bIECCsn80JlzpWxSB2YzHEqbc0B7mkCLSEogEvkrIU02nhckYvcEIXMYsxpq5DL+LQHYg252bPkxU5fFQWzbueDpZhgUt/71sa0QyF94S+eP1qN8+yH98NJ6hlasmgwlw5SEl2vgXAkwlHQQehGHpHL/5oqo6otet26cCN6U5k8d+4q+hnPrBI810lt1BQNuzH/f8ltmrLKR2lwJx2W/dd37MpCWimgB77G5zsMDpUk4Rb167HmjR7RJvmN4Q97q+VS9lTBDE3JCT5JDzi4EEqFNjDq74Okesmz5fq6K/GvC/vq89OJcBA==
2022-10-27T12:28:23.912+0200 7fe015a51700 20 REMOTE_ADDR=131.154.97.209
2022-10-27T12:28:23.912+0200 7fe015a51700 20 REQUEST_METHOD=PUT
2022-10-27T12:28:23.912+0200 7fe015a51700 20 REQUEST_URI=/my-bucket2
2022-10-27T12:28:23.912+0200 7fe015a51700 20 SCRIPT_URI=/my-bucket2
2022-10-27T12:28:23.912+0200 7fe015a51700 20 SERVER_PORT=7480
2022-10-27T12:28:23.912+0200 7fe015a51700 1 ====== starting new request req=0x7fe015a48b90 =====
2022-10-27T12:28:23.912+0200 7fe015a51700 2 req 2563305308445588829 0.000000000s initializing for trans_id = tx000002392b098d8c5bd5d-00635a5d47-6b044ce-default
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s rgw api priority: s3=8 s3website=7
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s host=ceph-mon01-tb.cloud.cnaf.infn.it
2022-10-27T12:28:23.912+0200 7fe015a51700 20 req 2563305308445588829 0.000000000s subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0
2022-10-27T12:28:23.912+0200 7fe015a51700 20 req 2563305308445588829 0.000000000s final domain/bucket subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0 s->info.domain= s->info.request_uri=/my-bucket2
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s meta>> HTTP_X_AMZ_CONTENT_SHA256
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s meta>> HTTP_X_AMZ_DATE
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s meta>> HTTP_X_AMZ_SECURITY_TOKEN
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s x>> x-amz-date:20221027T102823Z
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s x>> x-amz-security-token:RXgL3jnjlrfpTBy52si0Mwz94FzoRk11N0J7McThk9NhsXxqR3XL+dTKrslnshu3eVr1eMVkela/UEY2ysvbI3U5XKrhPdTIIfBzm1+OdQpjik1+ywzHyPxElPZkF+Kvten7W7/MvG4LobZkMnyZhVppzlWkhk8ilBO1H6NlYw9II+uO0LGqYnLGjTmRw0drorM8ezeoaHHDvSl0O2dqBx7EwODHNXU5bIECCsn80JlzpWxSB2YzHEqbc0B7mkCLSEogEvkrIU02nhckYvcEIXMYsxpq5DL+LQHYg252bPkxU5fFQWzbueDpZhgUt/71sa0QyF94S+eP1qN8+yH98NJ6hlasmgwlw5SEl2vgXAkwlHQQehGHpHL/5oqo6otet26cCN6U5k8d+4q+hnPrBI810lt1BQNuzH/f8ltmrLKR2lwJx2W/dd37MpCWimgB77G5zsMDpUk4Rb167HmjR7RJvmN4Q97q+VS9lTBDE3JCT5JDzi4EEqFNjDq74Okesmz5fq6K/GvC/vq89OJcBA==
2022-10-27T12:28:23.912+0200 7fe015a51700 20 req 2563305308445588829 0.000000000s get_handler handler=25RGWHandler_REST_Bucket_S3
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s handler=25RGWHandler_REST_Bucket_S3
2022-10-27T12:28:23.912+0200 7fe015a51700 2 req 2563305308445588829 0.000000000s getting op 1
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s s3:create_bucket op=27RGWCreateBucket_ObjStore_S3
2022-10-27T12:28:23.912+0200 7fe015a51700 2 req 2563305308445588829 0.000000000s s3:create_bucket verifying requester
2022-10-27T12:28:23.912+0200 7fe015a51700 20 req 2563305308445588829 0.000000000s s3:create_bucket rgw::auth::StrategyRegistry::s3_main_strategy_t: trying rgw::auth::s3::AWSAuthStrategy
2022-10-27T12:28:23.912+0200 7fe015a51700 20 req 2563305308445588829 0.000000000s s3:create_bucket rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::S3AnonymousEngine
2022-10-27T12:28:23.912+0200 7fe015a51700 20 req 2563305308445588829 0.000000000s s3:create_bucket rgw::auth::s3::S3AnonymousEngine denied with reason=-1
2022-10-27T12:28:23.912+0200 7fe015a51700 20 req 2563305308445588829 0.000000000s s3:create_bucket rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::STSAuthStrategy
2022-10-27T12:28:23.912+0200 7fe015a51700 20 req 2563305308445588829 0.000000000s s3:create_bucket rgw::auth::s3::STSAuthStrategy: trying rgw::auth::s3::STSEngine
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s v4 signature format = 6d56d7a481ab19eca0dcc82fc4e620f3ffc5086a3a3963ff8a6b57304a777988
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s v4 credential format = MKC48AZuyDFKzcnQYx0/20221027//s3/aws4_request
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s access key id = MKC48AZuyDFKzcnQYx0
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s credential scope = 20221027//s3/aws4_request
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s canonical headers format = host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20221027T102823Z
x-amz-security-token:RXgL3jnjlrfpTBy52si0Mwz94FzoRk11N0J7McThk9NhsXxqR3XL+dTKrslnshu3eVr1eMVkela/UEY2ysvbI3U5XKrhPdTIIfBzm1+OdQpjik1+ywzHyPxElPZkF+Kvten7W7/MvG4LobZkMnyZhVppzlWkhk8ilBO1H6NlYw9II+uO0LGqYnLGjTmRw0drorM8ezeoaHHDvSl0O2dqBx7EwODHNXU5bIECCsn80JlzpWxSB2YzHEqbc0B7mkCLSEogEvkrIU02nhckYvcEIXMYsxpq5DL+LQHYg252bPkxU5fFQWzbueDpZhgUt/71sa0QyF94S+eP1qN8+yH98NJ6hlasmgwlw5SEl2vgXAkwlHQQehGHpHL/5oqo6otet26cCN6U5k8d+4q+hnPrBI810lt1BQNuzH/f8ltmrLKR2lwJx2W/dd37MpCWimgB77G5zsMDpUk4Rb167HmjR7RJvmN4Q97q+VS9lTBDE3JCT5JDzi4EEqFNjDq74Okesmz5fq6K/GvC/vq89OJcBA==
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s payload request hash = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s canonical request = PUT
/my-bucket2
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20221027T102823Z
x-amz-security-token:RXgL3jnjlrfpTBy52si0Mwz94FzoRk11N0J7McThk9NhsXxqR3XL+dTKrslnshu3eVr1eMVkela/UEY2ysvbI3U5XKrhPdTIIfBzm1+OdQpjik1+ywzHyPxElPZkF+Kvten7W7/MvG4LobZkMnyZhVppzlWkhk8ilBO1H6NlYw9II+uO0LGqYnLGjTmRw0drorM8ezeoaHHDvSl0O2dqBx7EwODHNXU5bIECCsn80JlzpWxSB2YzHEqbc0B7mkCLSEogEvkrIU02nhckYvcEIXMYsxpq5DL+LQHYg252bPkxU5fFQWzbueDpZhgUt/71sa0QyF94S+eP1qN8+yH98NJ6hlasmgwlw5SEl2vgXAkwlHQQehGHpHL/5oqo6otet26cCN6U5k8d+4q+hnPrBI810lt1BQNuzH/f8ltmrLKR2lwJx2W/dd37MpCWimgB77G5zsMDpUk4Rb167HmjR7RJvmN4Q97q+VS9lTBDE3JCT5JDzi4EEqFNjDq74Okesmz5fq6K/GvC/vq89OJcBA==
host;x-amz-content-sha256;x-amz-date;x-amz-security-token
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s canonical request hash = 9cbd4cba5de3778d4e36e61104d696a937fb53d1da8e67bda495cdcd22e4470b
2022-10-27T12:28:23.912+0200 7fe015a51700 10 req 2563305308445588829 0.000000000s string to sign = AWS4-HMAC-SHA256
20221027T102823Z
20221027//s3/aws4_request
9cbd4cba5de3778d4e36e61104d696a937fb53d1da8e67bda495cdcd22e4470b
2022-10-27T12:28:23.913+0200 7fe015a51700 10 req 2563305308445588829 0.001000015s date_k = fe984fd19cc25cb9a9764ac6a366a22b5f23191b2bf2a10dfeb62b0bc0fa756e
2022-10-27T12:28:23.913+0200 7fe015a51700 10 req 2563305308445588829 0.001000015s region_k = b3773ffb30a95210211e745c307528a61abb0c559242e73f1c388d967679c3aa
2022-10-27T12:28:23.913+0200 7fe015a51700 10 req 2563305308445588829 0.001000015s service_k = 87295edd803ddb5fbce879d15d9bfd4d2f6953ac4ed4657859210bca82d38b2f
2022-10-27T12:28:23.913+0200 7fe015a51700 10 req 2563305308445588829 0.001000015s signing_k = 93071aaca289afa59c7d687383b140d87871d9c9066ead410c95e90e372004f4
2022-10-27T12:28:23.913+0200 7fe015a51700 10 req 2563305308445588829 0.001000015s generated signature = 6d56d7a481ab19eca0dcc82fc4e620f3ffc5086a3a3963ff8a6b57304a777988
2022-10-27T12:28:23.913+0200 7fe015a51700 15 req 2563305308445588829 0.001000015s s3:create_bucket string_to_sign=AWS4-HMAC-SHA256
20221027T102823Z
20221027//s3/aws4_request
9cbd4cba5de3778d4e36e61104d696a937fb53d1da8e67bda495cdcd22e4470b
2022-10-27T12:28:23.914+0200 7fe015a51700 15 req 2563305308445588829 0.002000030s s3:create_bucket server signature=6d56d7a481ab19eca0dcc82fc4e620f3ffc5086a3a3963ff8a6b57304a777988
2022-10-27T12:28:23.914+0200 7fe015a51700 15 req 2563305308445588829 0.002000030s s3:create_bucket client signature=6d56d7a481ab19eca0dcc82fc4e620f3ffc5086a3a3963ff8a6b57304a777988
2022-10-27T12:28:23.914+0200 7fe015a51700 15 req 2563305308445588829 0.002000030s s3:create_bucket compare=0
2022-10-27T12:28:23.914+0200 7fe015a51700 20 req 2563305308445588829 0.002000030s s3:create_bucket get_system_obj_state: rctx=0x7fe015a46f80 obj=default.rgw.meta:roles:roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 state=0x5653648ac760 s->prefetch_data=0
2022-10-27T12:28:23.914+0200 7fe015a51700 10 req 2563305308445588829 0.002000030s s3:create_bucket cache get: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 : hit (requested=0x6, cached=0x7)
2022-10-27T12:28:23.914+0200 7fe015a51700 20 req 2563305308445588829 0.002000030s s3:create_bucket get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:23.914+0200 7fe015a51700 10 req 2563305308445588829 0.002000030s s3:create_bucket cache get: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 : hit (requested=0x1, cached=0x7)
2022-10-27T12:28:23.914+0200 7fe015a51700 20 req 2563305308445588829 0.002000030s s3:create_bucket rgw::auth::s3::STSEngine granted access
2022-10-27T12:28:23.914+0200 7fe015a51700 20 req 2563305308445588829 0.002000030s s3:create_bucket rgw::auth::s3::STSAuthStrategy granted access
2022-10-27T12:28:23.914+0200 7fe015a51700 20 req 2563305308445588829 0.002000030s s3:create_bucket rgw::auth::s3::AWSAuthStrategy granted access
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket normalizing buckets and tenants
2022-10-27T12:28:23.914+0200 7fe015a51700 10 req 2563305308445588829 0.002000030s s->object=<NULL> s->bucket=my-bucket2
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket init permissions
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket recalculating target
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket reading permissions
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket init op
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket verifying op mask
2022-10-27T12:28:23.914+0200 7fe015a51700 20 req 2563305308445588829 0.002000030s s3:create_bucket required_mask= 2 user.op_mask=7
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket verifying op permissions
2022-10-27T12:28:23.914+0200 7fe015a51700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25144 11.2 11:5666016f:users.uid::$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a.buckets:head [call user.list_buckets in=34b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364970400 con 0x5653635c7000
2022-10-27T12:28:23.914+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4152 ==== osd_op_reply(25144 $oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a.buckets [call] v0'0 uv0 ondisk = -2 ((2) No such file or directory)) v8 ==== 194+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket verifying op params
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket pre-executing
2022-10-27T12:28:23.914+0200 7fe015a51700 2 req 2563305308445588829 0.002000030s s3:create_bucket executing
2022-10-27T12:28:23.914+0200 7fe015a51700 5 req 2563305308445588829 0.002000030s s3:create_bucket NOTICE: call to do_aws4_auth_completion
2022-10-27T12:28:23.914+0200 7fe015a51700 20 req 2563305308445588829 0.002000030s s3:create_bucket get_system_obj_state: rctx=0x7fe015a477d0 obj=default.rgw.meta:root:my-bucket2 state=0x5653648ac520 s->prefetch_data=0
2022-10-27T12:28:23.914+0200 7fe015a51700 10 req 2563305308445588829 0.002000030s s3:create_bucket cache get: name=default.rgw.meta+root+my-bucket2 : miss
2022-10-27T12:28:23.914+0200 7fe015a51700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25145 11.0 11:081ce58a:root::my-bucket2:head [call version.read in=11b,getxattrs,stat] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364970800 con 0x5653635c7000
2022-10-27T12:28:23.915+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4153 ==== osd_op_reply(25145 my-bucket2 [call,getxattrs,stat] v0'0 uv0 ondisk = -2 ((2) No such file or directory)) v8 ==== 238+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.915+0200 7fe015a51700 10 req 2563305308445588829 0.003000045s s3:create_bucket cache put: name=default.rgw.meta+root+my-bucket2 info.flags=0x0
2022-10-27T12:28:23.915+0200 7fe015a51700 10 req 2563305308445588829 0.003000045s s3:create_bucket adding default.rgw.meta+root+my-bucket2 to cache LRU end
2022-10-27T12:28:23.915+0200 7fe015a51700 10 req 2563305308445588829 0.003000045s s3:create_bucket user=$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a bucket=:my-bucket2[])
2022-10-27T12:28:23.915+0200 7fe015a51700 20 req 2563305308445588829 0.003000045s s3:create_bucket get_system_obj_state: rctx=0x7fe015a46f90 obj=default.rgw.meta:root:my-bucket2 state=0x5653648ac520 s->prefetch_data=0
2022-10-27T12:28:23.915+0200 7fe015a51700 10 req 2563305308445588829 0.003000045s s3:create_bucket cache get: name=default.rgw.meta+root+my-bucket2 : hit (negative entry)
2022-10-27T12:28:23.915+0200 7fe015a51700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25146 18.0 18:0dd9ce65:::.dir.d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1:head [create,call rgw.bucket_init_index in=20b] snapc 0=[] ondisk+write+known_if_redirected e6899044) v8 -- 0x565364970c00 con 0x5653635c7c00
2022-10-27T12:28:23.939+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17383 ==== osd_op_reply(25146 .dir.d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1 [create,call] v6899044'9 uv9 ondisk = 0) v8 ==== 239+0+0 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.940+0200 7fe015a51700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25147 11.3 11:db45f2f9:root::.bucket.meta.my-bucket2%3ad2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1:head [create,call version.set in=58b,writefull 0~404 in=404b,setxattr user.rgw.acl (273) in=285b] snapc 0=[] ondisk+write+known_if_redirected e6899044) v8 -- 0x565364971000 con 0x5653635c7000
2022-10-27T12:28:23.969+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4154 ==== osd_op_reply(25147 .bucket.meta.my-bucket2:d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1 [create,call,writefull 0~404,setxattr (273)] v6899044'50 uv50 ondisk = 0) v8 ==== 342+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:23.969+0200 7fe015a51700 10 req 2563305308445588829 0.057000849s s3:create_bucket cache put: name=default.rgw.meta+root+.bucket.meta.my-bucket2:d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1 info.flags=0x17
2022-10-27T12:28:23.969+0200 7fe015a51700 10 req 2563305308445588829 0.057000849s s3:create_bucket adding default.rgw.meta+root+.bucket.meta.my-bucket2:d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1 to cache LRU end
2022-10-27T12:28:23.969+0200 7fe015a51700 10 req 2563305308445588829 0.057000849s s3:create_bucket updating xattr: name=user.rgw.acl bl.length()=273
2022-10-27T12:28:23.969+0200 7fe015a51700 10 req 2563305308445588829 0.057000849s s3:create_bucket distributing notification oid=default.rgw.control:notify.4 cni=[op: 0, obj: default.rgw.meta:root:.bucket.meta.my-bucket2:d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1, ofs0, ns]
2022-10-27T12:28:23.969+0200 7fe015a51700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25148 6.1 6:b4812045:::notify.4:head [notify cookie 94916170039296 in=949b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364971400 con 0x5653635c7c00
2022-10-27T12:28:23.970+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17384 ==== watch-notify(notify (1) cookie 94916149215104 notify 29631168353665266 ret 0) v3 ==== 979+0+0 (crc 0 0 0) 0x565363620000 con 0x5653635c7c00
2022-10-27T12:28:23.970+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17385 ==== osd_op_reply(25148 notify.4 [notify cookie 94916170039296 out=8b] v0'0 uv4660 ondisk = 0) v8 ==== 152+0+8 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.970+0200 7fe03ca9f700 10 rgw watcher librados: RGWWatcher::handle_notify() notify_id 29631168353665266 cookie 94916149215104 notifier 112215246 bl.length()=937
2022-10-27T12:28:23.970+0200 7fe03ca9f700 10 rgw watcher librados: cache put: name=default.rgw.meta+root+.bucket.meta.my-bucket2:d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1 info.flags=0x17
2022-10-27T12:28:23.970+0200 7fe03ca9f700 10 rgw watcher librados: moving default.rgw.meta+root+.bucket.meta.my-bucket2:d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1 to cache LRU end
2022-10-27T12:28:23.970+0200 7fe03ca9f700 10 rgw watcher librados: updating xattr: name=user.rgw.acl bl.length()=273
2022-10-27T12:28:23.970+0200 7fe03ca9f700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25149 6.1 6:b4812045:::notify.4:head [notify-ack in=20b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364971800 con 0x5653635c7c00
2022-10-27T12:28:23.970+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17386 ==== watch-notify(notify_complete (2) cookie 94916170039296 notify 29631168353665266 ret 0) v3 ==== 42+0+68 (crc 0 0 0) 0x5653636201a0 con 0x5653635c7c00
2022-10-27T12:28:23.970+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17387 ==== osd_op_reply(25149 notify.4 [notify-ack] v0'0 uv4660 ondisk = 0) v8 ==== 152+0+0 (crc 0 0 0) 0x5653635bfb00 con 0x5653635c7c00
2022-10-27T12:28:23.970+0200 7fe015a51700 20 req 2563305308445588829 0.058000863s s3:create_bucket handle_bi_update(): bucket=:my-bucket2[d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1]): orig_sources= new_sources=
2022-10-27T12:28:23.970+0200 7fe015a51700 20 req 2563305308445588829 0.058000863s s3:create_bucket handle_bi_update(): bucket=:my-bucket2[d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1]): potential sources added=[] removed=[]
2022-10-27T12:28:23.970+0200 7fe015a51700 20 req 2563305308445588829 0.058000863s s3:create_bucket handle_bi_update(): bucket=:my-bucket2[d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1]): orig_dests= new_dests=
2022-10-27T12:28:23.970+0200 7fe015a51700 20 req 2563305308445588829 0.058000863s s3:create_bucket handle_bi_update(): bucket=:my-bucket2[d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1]): potential dests added=[] removed=[]
2022-10-27T12:28:23.970+0200 7fe015a51700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25150 11.0 11:081ce58a:root::my-bucket2:head [create,call version.set in=58b,writefull 0~250 in=250b] snapc 0=[] ondisk+write+known_if_redirected e6899044) v8 -- 0x56536499e000 con 0x5653635c7000
2022-10-27T12:28:24.007+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4155 ==== osd_op_reply(25150 my-bucket2 [create,call,writefull 0~250] v6899044'73 uv73 ondisk = 0) v8 ==== 238+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:24.007+0200 7fe015a51700 10 req 2563305308445588829 0.095001414s s3:create_bucket cache put: name=default.rgw.meta+root+my-bucket2 info.flags=0x17
2022-10-27T12:28:24.007+0200 7fe015a51700 10 req 2563305308445588829 0.095001414s s3:create_bucket moving default.rgw.meta+root+my-bucket2 to cache LRU end
2022-10-27T12:28:24.007+0200 7fe015a51700 10 req 2563305308445588829 0.095001414s s3:create_bucket distributing notification oid=default.rgw.control:notify.1 cni=[op: 0, obj: default.rgw.meta:root:my-bucket2, ofs0, ns]
2022-10-27T12:28:24.007+0200 7fe015a51700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25151 6.0 6:05bf5b68:::notify.1:head [notify cookie 94916170039296 in=440b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x56536499e400 con 0x5653635c7c00
2022-10-27T12:28:24.007+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17388 ==== watch-notify(notify (1) cookie 94916149211648 notify 29631168353665267 ret 0) v3 ==== 470+0+0 (crc 0 0 0) 0x5653636201a0 con 0x5653635c7c00
2022-10-27T12:28:24.007+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17389 ==== osd_op_reply(25151 notify.1 [notify cookie 94916170039296 out=8b] v0'0 uv3098 ondisk = 0) v8 ==== 152+0+8 (crc 0 0 0) 0x565364676900 con 0x5653635c7c00
2022-10-27T12:28:24.007+0200 7fe03ca9f700 10 rgw watcher librados: RGWWatcher::handle_notify() notify_id 29631168353665267 cookie 94916149211648 notifier 112215246 bl.length()=428
2022-10-27T12:28:24.007+0200 7fe03ca9f700 10 rgw watcher librados: cache put: name=default.rgw.meta+root+my-bucket2 info.flags=0x17
2022-10-27T12:28:24.007+0200 7fe03ca9f700 10 rgw watcher librados: moving default.rgw.meta+root+my-bucket2 to cache LRU end
2022-10-27T12:28:24.007+0200 7fe03ca9f700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.127:6800/598091,v1:10.10.96.127:6801/598091] -- osd_op(unknown.0.0:25152 6.0 6:05bf5b68:::notify.1:head [notify-ack in=20b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x565364971c00 con 0x5653635c7c00
2022-10-27T12:28:24.007+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17390 ==== watch-notify(notify_complete (2) cookie 94916170039296 notify 29631168353665267 ret 0) v3 ==== 42+0+68 (crc 0 0 0) 0x565363620340 con 0x5653635c7c00
2022-10-27T12:28:24.007+0200 7fe0422aa700 1 -- 10.10.96.132:0/4039468555 <== osd.0 v2:10.10.96.127:6800/598091 17391 ==== osd_op_reply(25152 notify.1 [notify-ack] v0'0 uv3098 ondisk = 0) v8 ==== 152+0+0 (crc 0 0 0) 0x565364676900 con 0x5653635c7c00
2022-10-27T12:28:24.007+0200 7fe015a51700 20 req 2563305308445588829 0.095001414s s3:create_bucket rgw_create_bucket returned ret=0 bucket=:my-bucket2[d2d663d7-8482-4da5-a302-7d2857fde80a.112215252.1])
2022-10-27T12:28:24.007+0200 7fe015a51700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25153 11.2 11:5666016f:users.uid::$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a.buckets:head [call user.set_buckets_info in=222b] snapc 0=[] ondisk+write+known_if_redirected e6899044) v8 -- 0x56536499e800 con 0x5653635c7000
2022-10-27T12:28:24.031+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4156 ==== osd_op_reply(25153 $oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a.buckets [call] v6899044'118 uv118 ondisk = 0) v8 ==== 194+0+0 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:24.031+0200 7fe015a51700 2 req 2563305308445588829 0.119001776s s3:create_bucket completing
2022-10-27T12:28:24.031+0200 7fe015a51700 2 req 2563305308445588829 0.119001776s s3:create_bucket op status=0
2022-10-27T12:28:24.031+0200 7fe015a51700 2 req 2563305308445588829 0.119001776s s3:create_bucket http status=200
2022-10-27T12:28:24.031+0200 7fe015a51700 1 ====== req done req=0x7fe015a48b90 op status=0 http_status=200 latency=0.119001776s ======
2022-10-27T12:28:24.031+0200 7fe015a51700 1 civetweb: 0x56536388b3b0: 131.154.97.209 - - [27/Oct/2022:12:28:23 +0200] "PUT /my-bucket2 HTTP/1.1" 200 145 - Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_ACCEPT_ENCODING=identity
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_AMZ_SDK_INVOCATION_ID=06637fc7-27c6-45ab-876f-be39da66cfb1
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_AMZ_SDK_REQUEST=attempt=1
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_AUTHORIZATION=AWS4-HMAC-SHA256 Credential=MKC48AZuyDFKzcnQYx0/20221027//s3/aws4_request, SignedHeaders=host;x-amz-content-sha256;x-amz-date;x-amz-security-token, Signature=e191c419a75ebedb5fbbbd878978f0e13942f987088aa4757b14de697cbd60c1
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_HOST=ceph-mon01-tb.cloud.cnaf.infn.it:7480
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_USER_AGENT=Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_VERSION=1.1
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_X_AMZ_CONTENT_SHA256=e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_X_AMZ_DATE=20221027T102824Z
2022-10-27T12:28:24.035+0200 7fe015a51700 20 HTTP_X_AMZ_SECURITY_TOKEN=RXgL3jnjlrfpTBy52si0Mwz94FzoRk11N0J7McThk9NhsXxqR3XL+dTKrslnshu3eVr1eMVkela/UEY2ysvbI3U5XKrhPdTIIfBzm1+OdQpjik1+ywzHyPxElPZkF+Kvten7W7/MvG4LobZkMnyZhVppzlWkhk8ilBO1H6NlYw9II+uO0LGqYnLGjTmRw0drorM8ezeoaHHDvSl0O2dqBx7EwODHNXU5bIECCsn80JlzpWxSB2YzHEqbc0B7mkCLSEogEvkrIU02nhckYvcEIXMYsxpq5DL+LQHYg252bPkxU5fFQWzbueDpZhgUt/71sa0QyF94S+eP1qN8+yH98NJ6hlasmgwlw5SEl2vgXAkwlHQQehGHpHL/5oqo6otet26cCN6U5k8d+4q+hnPrBI810lt1BQNuzH/f8ltmrLKR2lwJx2W/dd37MpCWimgB77G5zsMDpUk4Rb167HmjR7RJvmN4Q97q+VS9lTBDE3JCT5JDzi4EEqFNjDq74Okesmz5fq6K/GvC/vq89OJcBA==
2022-10-27T12:28:24.035+0200 7fe015a51700 20 REMOTE_ADDR=131.154.97.209
2022-10-27T12:28:24.035+0200 7fe015a51700 20 REQUEST_METHOD=GET
2022-10-27T12:28:24.035+0200 7fe015a51700 20 REQUEST_URI=/
2022-10-27T12:28:24.035+0200 7fe015a51700 20 SCRIPT_URI=/
2022-10-27T12:28:24.035+0200 7fe015a51700 20 SERVER_PORT=7480
2022-10-27T12:28:24.035+0200 7fe015a51700 1 ====== starting new request req=0x7fe015a48b90 =====
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s initializing for trans_id = tx00000d310a765887cbcd3-00635a5d48-6b044ce-default
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s rgw api priority: s3=8 s3website=7
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s host=ceph-mon01-tb.cloud.cnaf.infn.it
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s final domain/bucket subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0 s->info.domain= s->info.request_uri=/
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s meta>> HTTP_X_AMZ_CONTENT_SHA256
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s meta>> HTTP_X_AMZ_DATE
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s meta>> HTTP_X_AMZ_SECURITY_TOKEN
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s x>> x-amz-date:20221027T102824Z
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s x>> x-amz-security-token:RXgL3jnjlrfpTBy52si0Mwz94FzoRk11N0J7McThk9NhsXxqR3XL+dTKrslnshu3eVr1eMVkela/UEY2ysvbI3U5XKrhPdTIIfBzm1+OdQpjik1+ywzHyPxElPZkF+Kvten7W7/MvG4LobZkMnyZhVppzlWkhk8ilBO1H6NlYw9II+uO0LGqYnLGjTmRw0drorM8ezeoaHHDvSl0O2dqBx7EwODHNXU5bIECCsn80JlzpWxSB2YzHEqbc0B7mkCLSEogEvkrIU02nhckYvcEIXMYsxpq5DL+LQHYg252bPkxU5fFQWzbueDpZhgUt/71sa0QyF94S+eP1qN8+yH98NJ6hlasmgwlw5SEl2vgXAkwlHQQehGHpHL/5oqo6otet26cCN6U5k8d+4q+hnPrBI810lt1BQNuzH/f8ltmrLKR2lwJx2W/dd37MpCWimgB77G5zsMDpUk4Rb167HmjR7RJvmN4Q97q+VS9lTBDE3JCT5JDzi4EEqFNjDq74Okesmz5fq6K/GvC/vq89OJcBA==
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s get_handler handler=26RGWHandler_REST_Service_S3
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s handler=26RGWHandler_REST_Service_S3
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s getting op 0
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s s3:list_buckets op=26RGWListBuckets_ObjStore_S3
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets verifying requester
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets rgw::auth::StrategyRegistry::s3_main_strategy_t: trying rgw::auth::s3::AWSAuthStrategy
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::S3AnonymousEngine
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets rgw::auth::s3::S3AnonymousEngine denied with reason=-1
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::STSAuthStrategy
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets rgw::auth::s3::STSAuthStrategy: trying rgw::auth::s3::STSEngine
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s v4 signature format = e191c419a75ebedb5fbbbd878978f0e13942f987088aa4757b14de697cbd60c1
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s v4 credential format = MKC48AZuyDFKzcnQYx0/20221027//s3/aws4_request
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s access key id = MKC48AZuyDFKzcnQYx0
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s credential scope = 20221027//s3/aws4_request
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s canonical headers format = host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20221027T102824Z
x-amz-security-token:RXgL3jnjlrfpTBy52si0Mwz94FzoRk11N0J7McThk9NhsXxqR3XL+dTKrslnshu3eVr1eMVkela/UEY2ysvbI3U5XKrhPdTIIfBzm1+OdQpjik1+ywzHyPxElPZkF+Kvten7W7/MvG4LobZkMnyZhVppzlWkhk8ilBO1H6NlYw9II+uO0LGqYnLGjTmRw0drorM8ezeoaHHDvSl0O2dqBx7EwODHNXU5bIECCsn80JlzpWxSB2YzHEqbc0B7mkCLSEogEvkrIU02nhckYvcEIXMYsxpq5DL+LQHYg252bPkxU5fFQWzbueDpZhgUt/71sa0QyF94S+eP1qN8+yH98NJ6hlasmgwlw5SEl2vgXAkwlHQQehGHpHL/5oqo6otet26cCN6U5k8d+4q+hnPrBI810lt1BQNuzH/f8ltmrLKR2lwJx2W/dd37MpCWimgB77G5zsMDpUk4Rb167HmjR7RJvmN4Q97q+VS9lTBDE3JCT5JDzi4EEqFNjDq74Okesmz5fq6K/GvC/vq89OJcBA==
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s payload request hash = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s canonical request = GET
/
host:ceph-mon01-tb.cloud.cnaf.infn.it:7480
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20221027T102824Z
x-amz-security-token:RXgL3jnjlrfpTBy52si0Mwz94FzoRk11N0J7McThk9NhsXxqR3XL+dTKrslnshu3eVr1eMVkela/UEY2ysvbI3U5XKrhPdTIIfBzm1+OdQpjik1+ywzHyPxElPZkF+Kvten7W7/MvG4LobZkMnyZhVppzlWkhk8ilBO1H6NlYw9II+uO0LGqYnLGjTmRw0drorM8ezeoaHHDvSl0O2dqBx7EwODHNXU5bIECCsn80JlzpWxSB2YzHEqbc0B7mkCLSEogEvkrIU02nhckYvcEIXMYsxpq5DL+LQHYg252bPkxU5fFQWzbueDpZhgUt/71sa0QyF94S+eP1qN8+yH98NJ6hlasmgwlw5SEl2vgXAkwlHQQehGHpHL/5oqo6otet26cCN6U5k8d+4q+hnPrBI810lt1BQNuzH/f8ltmrLKR2lwJx2W/dd37MpCWimgB77G5zsMDpUk4Rb167HmjR7RJvmN4Q97q+VS9lTBDE3JCT5JDzi4EEqFNjDq74Okesmz5fq6K/GvC/vq89OJcBA==
host;x-amz-content-sha256;x-amz-date;x-amz-security-token
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s canonical request hash = 07667218c7f833f070d21d3a850e6e5af40798244546a299be3bf48f73404fda
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s string to sign = AWS4-HMAC-SHA256
20221027T102824Z
20221027//s3/aws4_request
07667218c7f833f070d21d3a850e6e5af40798244546a299be3bf48f73404fda
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s date_k = fe984fd19cc25cb9a9764ac6a366a22b5f23191b2bf2a10dfeb62b0bc0fa756e
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s region_k = b3773ffb30a95210211e745c307528a61abb0c559242e73f1c388d967679c3aa
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s service_k = 87295edd803ddb5fbce879d15d9bfd4d2f6953ac4ed4657859210bca82d38b2f
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s signing_k = 93071aaca289afa59c7d687383b140d87871d9c9066ead410c95e90e372004f4
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s generated signature = e191c419a75ebedb5fbbbd878978f0e13942f987088aa4757b14de697cbd60c1
2022-10-27T12:28:24.035+0200 7fe015a51700 15 req 15208839996153576659 0.000000000s s3:list_buckets string_to_sign=AWS4-HMAC-SHA256
20221027T102824Z
20221027//s3/aws4_request
07667218c7f833f070d21d3a850e6e5af40798244546a299be3bf48f73404fda
2022-10-27T12:28:24.035+0200 7fe015a51700 15 req 15208839996153576659 0.000000000s s3:list_buckets server signature=e191c419a75ebedb5fbbbd878978f0e13942f987088aa4757b14de697cbd60c1
2022-10-27T12:28:24.035+0200 7fe015a51700 15 req 15208839996153576659 0.000000000s s3:list_buckets client signature=e191c419a75ebedb5fbbbd878978f0e13942f987088aa4757b14de697cbd60c1
2022-10-27T12:28:24.035+0200 7fe015a51700 15 req 15208839996153576659 0.000000000s s3:list_buckets compare=0
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets get_system_obj_state: rctx=0x7fe015a46f80 obj=default.rgw.meta:roles:roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 state=0x5653648ac520 s->prefetch_data=0
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s s3:list_buckets cache get: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 : hit (requested=0x6, cached=0x7)
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets get_system_obj_state: s->obj_tag was set empty
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s s3:list_buckets cache get: name=default.rgw.meta+roles+roles.f97dffe3-ee5c-4922-8818-46cc7ebca422 : hit (requested=0x1, cached=0x7)
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets rgw::auth::s3::STSEngine granted access
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets rgw::auth::s3::STSAuthStrategy granted access
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets rgw::auth::s3::AWSAuthStrategy granted access
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets normalizing buckets and tenants
2022-10-27T12:28:24.035+0200 7fe015a51700 10 req 15208839996153576659 0.000000000s s->object=<NULL> s->bucket=
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets init permissions
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets recalculating target
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets reading permissions
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets init op
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets verifying op mask
2022-10-27T12:28:24.035+0200 7fe015a51700 20 req 15208839996153576659 0.000000000s s3:list_buckets required_mask= 1 user.op_mask=7
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets verifying op permissions
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets verifying op params
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets pre-executing
2022-10-27T12:28:24.035+0200 7fe015a51700 2 req 15208839996153576659 0.000000000s s3:list_buckets executing
2022-10-27T12:28:24.035+0200 7fe015a51700 1 -- 10.10.96.132:0/4039468555 --> [v2:10.10.96.115:6800/3364353,v1:10.10.96.115:6801/3364353] -- osd_op(unknown.0.0:25154 11.2 11:5666016f:users.uid::$oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a.buckets:head [call user.list_buckets in=34b] snapc 0=[] ondisk+read+known_if_redirected e6899044) v8 -- 0x56536499ec00 con 0x5653635c7000
2022-10-27T12:28:24.036+0200 7fe0412a8700 1 -- 10.10.96.132:0/4039468555 <== osd.3 v2:10.10.96.115:6800/3364353 4157 ==== osd_op_reply(25154 $oidc$f9bde2f0-1fc5-460a-9f0d-e1bcf564ef6a.buckets [call out=198b] v0'0 uv118 ondisk = 0) v8 ==== 194+0+198 (crc 0 0 0) 0x565364358240 con 0x5653635c7000
2022-10-27T12:28:24.036+0200 7fe015a51700 2 req 15208839996153576659 0.001000015s s3:list_buckets completing
2022-10-27T12:28:24.036+0200 7fe015a51700 2 req 15208839996153576659 0.001000015s s3:list_buckets op status=0
2022-10-27T12:28:24.036+0200 7fe015a51700 2 req 15208839996153576659 0.001000015s s3:list_buckets http status=200
2022-10-27T12:28:24.036+0200 7fe015a51700 1 ====== req done req=0x7fe015a48b90 op status=0 http_status=200 latency=0.001000015s ======
2022-10-27T12:28:24.037+0200 7fe015a51700 1 civetweb: 0x56536388b3b0: 131.154.97.209 - - [27/Oct/2022:12:28:23 +0200] "GET / HTTP/1.1" 200 549 - Boto3/1.23.10 Python/3.6.9 Linux/4.15.0-193-generic Botocore/1.26.10
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment