Project

General

Profile

Bug #65179 » rgw-log.log

Taha Jahangir, 03/27/2024 02:24 PM

 
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_ACCEPT_ENCODING=identity
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_AMZ_SDK_INVOCATION_ID=d0b8677c-bcd2-4e65-8bf5-7499e7706396
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_AMZ_SDK_REQUEST=attempt=1
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_AUTHORIZATION=AWS4-HMAC-SHA256 Credential=R612WE7A53PNXNZB4SUW/20240327/us-east-1/s3/aws4_request, SignedHeaders=host;x-amz-cache;x-amz-content-sha256;x-amz-date, Signature=96e28625695376dbc58054a66aeeed40520ad06237357280619e32c8ad30e8d3
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_CONNECTION=close
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_HOST=myrgw.domain.com
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_RANGE=bytes=0-5242879
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_USER_AGENT=Boto3/1.34.23 md/Botocore#1.34.23 ua/2.0 os/linux#6.6.22-1-lts md/arch#x86_64 lang/python#3.9.16 md/pyimpl#CPython Botocore/1.34.23 Resource
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_VERSION=1.1
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_AMZ_CACHE=HOSTmyrgw.domain.comRANGEbytes=10-20X-AMZ-CONTENT-SHA256e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855X-AMZ-DATE20240327T134301ZAuthorizationAWS4-HMAC-SHA256 Credential=30L3R8XQD6OHW1LPFJM3/20240327/us-east-1/s3/aws4_request, SignedHeaders=host;range;x-amz-content-sha256;x-amz-date, Signature=237e66a684dbd0c45741b7b9ab62d1e294dd564f5a7167ca304401725235b3bc
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_AMZ_CONTENT_SHA256=e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_AMZ_DATE=20240327T134301Z
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_FORWARDED_FOR=10.76.74.227
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_FORWARDED_HOST=myrgw.domain.com
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_FORWARDED_PORT=443
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_FORWARDED_PROTO=https
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_FORWARDED_SCHEME=https
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_REAL_IP=10.76.74.227
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_REQUEST_ID=ba471c53e0256f05585a26ba988968fc
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 HTTP_X_SCHEME=https
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 REMOTE_ADDR=10.0.72.38
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 REQUEST_METHOD=GET
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 REQUEST_URI=/temp/testfile
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 SCRIPT_URI=/temp/testfile
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 SERVER_PORT=32003
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 1 ====== starting new request req=0x7f54e9277620 =====
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 2 req 11732919009407265169 0.000000000s initializing for trans_id = tx00000a2d3b3ace625e991-0066042265-6b3e37f-myrgw
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s rgw api priority: s3=8 s3website=7
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s host=myrgw.domain.com
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s final domain/bucket subdomain= domain= in_hosted_domain=0 in_hosted_domain_s3website=0 s->info.domain= s->info.request_uri=/temp/testfile
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s meta>> HTTP_X_AMZ_CACHE
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s meta>> HTTP_X_AMZ_CONTENT_SHA256
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s meta>> HTTP_X_AMZ_DATE
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s x>> x-amz-cache:HOSTmyrgw.domain.comRANGEbytes=10-20X-AMZ-CONTENT-SHA256e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855X-AMZ-DATE20240327T134301ZAuthorizationAWS4-HMAC-SHA256 Credential=30L3R8XQD6OHW1LPFJM3/20240327/us-east-1/s3/aws4_request, SignedHeaders=host;range;x-amz-content-sha256;x-amz-date, Signature=237e66a684dbd0c45741b7b9ab62d1e294dd564f5a7167ca304401725235b3bc
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s x>> x-amz-date:20240327T134301Z
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s get_handler handler=22RGWHandler_REST_Obj_S3
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s handler=22RGWHandler_REST_Obj_S3
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 2 req 11732919009407265169 0.000000000s getting op 0
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj scheduling with throttler client=2 cost=1
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj op=21RGWGetObj_ObjStore_S3
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 2 req 11732919009407265169 0.000000000s s3:get_obj verifying requester
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::StrategyRegistry::s3_main_strategy_t: trying rgw::auth::s3::AWSAuthStrategy
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::S3AnonymousEngine
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::S3AnonymousEngine denied with reason=-1
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::LocalEngine
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s v4 signature format = 96e28625695376dbc58054a66aeeed40520ad06237357280619e32c8ad30e8d3
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s v4 credential format = R612WE7A53PNXNZB4SUW/20240327/us-east-1/s3/aws4_request
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s access key id = R612WE7A53PNXNZB4SUW
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s credential scope = 20240327/us-east-1/s3/aws4_request
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s canonical headers format = host:myrgw.domain.com
x-amz-cache:HOSTmyrgw.domain.comRANGEbytes=10-20X-AMZ-CONTENT-SHA256e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855X-AMZ-DATE20240327T134301ZAuthorizationAWS4-HMAC-SHA256 Credential=30L3R8XQD6OHW1LPFJM3/20240327/us-east-1/s3/aws4_request, SignedHeaders=host;range;x-amz-content-sha256;x-amz-date, Signature=237e66a684dbd0c45741b7b9ab62d1e294dd564f5a7167ca304401725235b3bc
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20240327T134301Z

debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s payload request hash = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s canonical request = GET
/temp/testfile

host:myrgw.domain.com
x-amz-cache:HOSTmyrgw.domain.comRANGEbytes=10-20X-AMZ-CONTENT-SHA256e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855X-AMZ-DATE20240327T134301ZAuthorizationAWS4-HMAC-SHA256 Credential=30L3R8XQD6OHW1LPFJM3/20240327/us-east-1/s3/aws4_request, SignedHeaders=host;range;x-amz-content-sha256;x-amz-date, Signature=237e66a684dbd0c45741b7b9ab62d1e294dd564f5a7167ca304401725235b3bc
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20240327T134301Z

host;x-amz-cache;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s canonical request hash = 32d68e060297362bc7de6f9ba8780692396b22feca5fddbb8774bb67322c35a9
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s string to sign = AWS4-HMAC-SHA256
20240327T134301Z
20240327/us-east-1/s3/aws4_request
32d68e060297362bc7de6f9ba8780692396b22feca5fddbb8774bb67322c35a9
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s date_k = 0b9540e669703dde15957e9e233ca667737a070e4772ff49b428f1c177622ab2
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s region_k = 2af95323fef7b2672e506fae490a4c86465c8f3b453de4e66d26808c4978d2c2
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s service_k = 6958c804787869ca0aed3008c7ef75cc9705a0aaa46f51854c532b9dac5cf735
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s signing_k = 30288f64fa9e403f8fbff6e076ff015a929bde73bf3d217440f304a37826f456
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s generated signature = 96e28625695376dbc58054a66aeeed40520ad06237357280619e32c8ad30e8d3
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 15 req 11732919009407265169 0.000000000s s3:get_obj string_to_sign=AWS4-HMAC-SHA256
20240327T134301Z
20240327/us-east-1/s3/aws4_request
32d68e060297362bc7de6f9ba8780692396b22feca5fddbb8774bb67322c35a9
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 15 req 11732919009407265169 0.000000000s s3:get_obj server signature=96e28625695376dbc58054a66aeeed40520ad06237357280619e32c8ad30e8d3
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 15 req 11732919009407265169 0.000000000s s3:get_obj client signature=96e28625695376dbc58054a66aeeed40520ad06237357280619e32c8ad30e8d3
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 15 req 11732919009407265169 0.000000000s s3:get_obj compare=0
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::LocalEngine granted access
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::AWSAuthStrategy granted access
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj cache override headers
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj after splitting cache kv key: HTTP_HOST myrgw.domain.com
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj after splitting cache kv key: HTTP_RANGE bytes=10-20
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj after splitting cache kv key: HTTP_X_AMZ_CONTENT_SHA256 e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj after splitting cache kv key: HTTP_X_AMZ_DATE 20240327T134301Z
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj after splitting cache kv key: HTTP_Authorization AWS4-HMAC-SHA256 Credential=30L3R8XQD6OHW1LPFJM3/20240327/us-east-1/s3/aws4_request, SignedHeaders=host;range;x-amz-content-sha256;x-amz-date, Signature=237e66a684dbd0c45741b7b9ab62d1e294dd564f5a7167ca304401725235b3bc
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::StrategyRegistry::s3_main_strategy_t: trying rgw::auth::s3::AWSAuthStrategy
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::S3AnonymousEngine
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::S3AnonymousEngine denied with reason=-1
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::AWSAuthStrategy: trying rgw::auth::s3::LocalEngine
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s v4 signature format = 237e66a684dbd0c45741b7b9ab62d1e294dd564f5a7167ca304401725235b3bc
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s v4 credential format = 30L3R8XQD6OHW1LPFJM3/20240327/us-east-1/s3/aws4_request
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s access key id = 30L3R8XQD6OHW1LPFJM3
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s credential scope = 20240327/us-east-1/s3/aws4_request
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s canonical headers format = host:myrgw.domain.com
range:bytes=10-20
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20240327T134301Z

debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s payload request hash = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s canonical request = GET
/temp/testfile

host:myrgw.domain.com
range:bytes=10-20
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20240327T134301Z

host;range;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s canonical request hash = cfd448de2adbcef95c5032c39bf259f0ad276d03299cab749f8c79d248afffe6
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s string to sign = AWS4-HMAC-SHA256
20240327T134301Z
20240327/us-east-1/s3/aws4_request
cfd448de2adbcef95c5032c39bf259f0ad276d03299cab749f8c79d248afffe6
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s date_k = eafb1b3a927aa843303d70662fadc0339ffa36f331f19b0960d19cab14f54b6a
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s region_k = ce0e2f56243fb943cd7e7419fb5291065f80a4e8cba56876d241fcaf96b8108d
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s service_k = c638fd1bafa7fb2351c70ea60641dc53e4152cae74b72ca3c038b470dcc221f0
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s signing_k = 3829719d9617128bc27612d22886ce7cf9de21ec7d70c755c62712b21bd6a272
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s generated signature = 237e66a684dbd0c45741b7b9ab62d1e294dd564f5a7167ca304401725235b3bc
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 15 req 11732919009407265169 0.000000000s s3:get_obj string_to_sign=AWS4-HMAC-SHA256
20240327T134301Z
20240327/us-east-1/s3/aws4_request
cfd448de2adbcef95c5032c39bf259f0ad276d03299cab749f8c79d248afffe6
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 15 req 11732919009407265169 0.000000000s s3:get_obj server signature=237e66a684dbd0c45741b7b9ab62d1e294dd564f5a7167ca304401725235b3bc
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 15 req 11732919009407265169 0.000000000s s3:get_obj client signature=237e66a684dbd0c45741b7b9ab62d1e294dd564f5a7167ca304401725235b3bc
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 15 req 11732919009407265169 0.000000000s s3:get_obj compare=0
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::LocalEngine granted access
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj rgw::auth::s3::AWSAuthStrategy granted access
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 2 req 11732919009407265169 0.000000000s s3:get_obj normalizing buckets and tenants
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s->object=testfile s->bucket=temp
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 2 req 11732919009407265169 0.000000000s s3:get_obj init permissions
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj get_system_obj_state: rctx=0x7f54e9275fb0 obj=myrgw.rgw.meta:root:temp state=0x55cb8a6dbba0 s->prefetch_data=0
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj cache get: touching lru, lru_counter=3603 promotion_ts=3600
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj moving myrgw.rgw.meta+root+temp to cache LRU end
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj cache get: name=myrgw.rgw.meta+root+temp : hit (requested=0x16, cached=0x17)
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj get_system_obj_state: s->obj_tag was set empty
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj cache get: name=myrgw.rgw.meta+root+temp : hit (requested=0x11, cached=0x17)
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 15 req 11732919009407265169 0.000000000s s3:get_obj decode_policy Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>testuser</ID><DisplayName>TestUser</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>testuser</ID><DisplayName>TestUser</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj get_system_obj_state: rctx=0x7f54e9275a28 obj=myrgw.rgw.meta:users.uid:testuser state=0x55cb8a6dbba0 s->prefetch_data=0
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj cache get: touching lru, lru_counter=3604 promotion_ts=3601
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj moving myrgw.rgw.meta+users.uid+testuser to cache LRU end
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj cache get: name=myrgw.rgw.meta+users.uid+testuser : hit (requested=0x6, cached=0x17)
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj get_system_obj_state: s->obj_tag was set empty
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj Read xattr: user.rgw.idtag
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 10 req 11732919009407265169 0.000000000s s3:get_obj cache get: name=myrgw.rgw.meta+users.uid+testuser : hit (requested=0x3, cached=0x17)
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 2 req 11732919009407265169 0.000000000s s3:get_obj recalculating target
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 2 req 11732919009407265169 0.000000000s s3:get_obj reading permissions
debug 2024-03-27T13:43:01.872+0000 7f53c9b8f700 20 req 11732919009407265169 0.000000000s s3:get_obj get_obj_state: rctx=0x7f54e9276a30 obj=temp:testfile state=0x55cb8bf261e8 s->prefetch_data=0
debug 2024-03-27T13:43:01.876+0000 7f548e518700 10 req 11732919009407265169 0.004000053s s3:get_obj manifest: total_size = 1371344
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj get_obj_state: setting s->obj_tag to f5130d3d-d406-42f9-aac9-80cff91d9a66.27514286.4684440739599018300
debug 2024-03-27T13:43:01.876+0000 7f548e518700 15 req 11732919009407265169 0.004000053s s3:get_obj decode_policy Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>testuser</ID><DisplayName>TestUser</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>testuser</ID><DisplayName>TestUser</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
debug 2024-03-27T13:43:01.876+0000 7f548e518700 2 req 11732919009407265169 0.004000053s s3:get_obj init op
debug 2024-03-27T13:43:01.876+0000 7f548e518700 2 req 11732919009407265169 0.004000053s s3:get_obj verifying op mask
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj required_mask= 1 user.op_mask=7
debug 2024-03-27T13:43:01.876+0000 7f548e518700 2 req 11732919009407265169 0.004000053s s3:get_obj verifying op permissions
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj -- Getting permissions begin with perm_mask=49
debug 2024-03-27T13:43:01.876+0000 7f548e518700 5 req 11732919009407265169 0.004000053s s3:get_obj Searching permissions for identity=rgw::auth::SysReqApplier -> rgw::auth::LocalApplier(acct_user=testuser, acct_name=TestUser, subuser=, perm_mask=15, is_admin=0) mask=49
debug 2024-03-27T13:43:01.876+0000 7f548e518700 5 req 11732919009407265169 0.004000053s s3:get_obj Searching permissions for uid=testuser
debug 2024-03-27T13:43:01.876+0000 7f548e518700 5 req 11732919009407265169 0.004000053s s3:get_obj Found permission: 15
debug 2024-03-27T13:43:01.876+0000 7f548e518700 5 req 11732919009407265169 0.004000053s s3:get_obj Searching permissions for group=1 mask=49
debug 2024-03-27T13:43:01.876+0000 7f548e518700 5 req 11732919009407265169 0.004000053s s3:get_obj Permissions for group not found
debug 2024-03-27T13:43:01.876+0000 7f548e518700 5 req 11732919009407265169 0.004000053s s3:get_obj Searching permissions for group=2 mask=49
debug 2024-03-27T13:43:01.876+0000 7f548e518700 5 req 11732919009407265169 0.004000053s s3:get_obj Permissions for group not found
debug 2024-03-27T13:43:01.876+0000 7f548e518700 5 req 11732919009407265169 0.004000053s s3:get_obj -- Getting permissions done for identity=rgw::auth::SysReqApplier -> rgw::auth::LocalApplier(acct_user=testuser, acct_name=TestUser, subuser=, perm_mask=15, is_admin=0), owner=testuser, perm=1
debug 2024-03-27T13:43:01.876+0000 7f548e518700 10 req 11732919009407265169 0.004000053s s3:get_obj identity=rgw::auth::SysReqApplier -> rgw::auth::LocalApplier(acct_user=testuser, acct_name=TestUser, subuser=, perm_mask=15, is_admin=0) requested perm (type)=1, policy perm=1, user_perm_mask=15, acl perm=1
debug 2024-03-27T13:43:01.876+0000 7f548e518700 2 req 11732919009407265169 0.004000053s s3:get_obj verifying op params
debug 2024-03-27T13:43:01.876+0000 7f548e518700 2 req 11732919009407265169 0.004000053s s3:get_obj pre-executing
debug 2024-03-27T13:43:01.876+0000 7f548e518700 2 req 11732919009407265169 0.004000053s s3:get_obj executing
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj get_obj_state: rctx=0x7f54e9276a30 obj=temp:testfile state=0x55cb8bf261e8 s->prefetch_data=0
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.acl
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.content_type
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.etag
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.idtag
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.manifest
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.pg_ver
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.source_zone
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.tail_tag
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.x-amz-acl
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.x-amz-content-sha256
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.x-amz-date
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj Read xattr rgw_rados: user.rgw.x-amz-meta-mtime
debug 2024-03-27T13:43:01.876+0000 7f548e518700 15 req 11732919009407265169 0.004000053s Encryption mode:
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj get_obj_state: rctx=0x7f54e9276a30 obj=temp:testfile state=0x55cb8bf261e8 s->prefetch_data=0
debug 2024-03-27T13:43:01.876+0000 7f548e518700 20 req 11732919009407265169 0.004000053s s3:get_obj rados->get_obj_iterate_cb oid=f5130d3d-d406-42f9-aac9-80cff91d9a66.12410012.4_testfile obj-ofs=10 read_ofs=10 len=11
debug 2024-03-27T13:43:01.876+0000 7f53c2380700 2 req 11732919009407265169 0.004000053s s3:get_obj completing
debug 2024-03-27T13:43:01.876+0000 7f53c2380700 2 req 11732919009407265169 0.004000053s s3:get_obj op status=0
debug 2024-03-27T13:43:01.876+0000 7f53c2380700 2 req 11732919009407265169 0.004000053s s3:get_obj http status=206
debug 2024-03-27T13:43:01.876+0000 7f53c2380700 1 ====== req done req=0x7f54e9277620 op status=0 http_status=206 latency=0.004000053s ======
debug 2024-03-27T13:43:01.876+0000 7f53c2380700 1 beast: 0x7f54e9277620: 10.0.72.38 - testuser [27/Mar/2024:13:43:01.872 +0000] "GET /temp/testfile HTTP/1.1" 206 11 - "Boto3/1.34.23 md/Botocore#1.34.23 ua/2.0 os/linux#6.6.22-1-lts md/arch#x86_64 lang/python#3.9.16 md/pyimpl#CPython Botocore/1.34.23 Resource" bytes=0-5242879 latency=0.004000053s
(2-2/2)