Project

General

Profile

Bug #51767 » rgw-shard23.log

rgw/beast log when testing with postman, 23 shards - JS Landry, 07/21/2021 03:04 PM

 

2021-07-20T17:34:20.475-0400 7fe306fde700 2 RGWDataChangesLog::ChangesRenewThread: start
2021-07-20T17:34:42.475-0400 7fe306fde700 2 RGWDataChangesLog::ChangesRenewThread: start
2021-07-20T17:35:04.476-0400 7fe306fde700 2 RGWDataChangesLog::ChangesRenewThread: start
2021-07-20T17:35:10.662-0400 7fe25667d700 1 ====== starting new request req=0x7fe3337246d0 =====
2021-07-20T17:35:10.662-0400 7fe25667d700 2 req 1717 0s initializing for trans_id = tx0000000000000000006b5-0060f7418e-69e0a08-campusprcc
2021-07-20T17:35:10.662-0400 7fe25667d700 2 req 1717 0s getting op 0
2021-07-20T17:35:10.662-0400 7fe25667d700 2 req 1717 0s s3:list_bucket verifying requester
2021-07-20T17:35:10.662-0400 7fe25667d700 2 req 1717 0s s3:list_bucket normalizing buckets and tenants
2021-07-20T17:35:10.663-0400 7fe25667d700 2 req 1717 0.001000035s s3:list_bucket init permissions
2021-07-20T17:35:10.663-0400 7fe2cef6e700 2 req 1717 0.001000035s s3:list_bucket recalculating target
2021-07-20T17:35:10.663-0400 7fe2cef6e700 2 req 1717 0.001000035s s3:list_bucket reading permissions
2021-07-20T17:35:10.663-0400 7fe2cef6e700 2 req 1717 0.001000035s s3:list_bucket init op
2021-07-20T17:35:10.663-0400 7fe2cef6e700 2 req 1717 0.001000035s s3:list_bucket verifying op mask
2021-07-20T17:35:10.663-0400 7fe2cef6e700 2 req 1717 0.001000035s s3:list_bucket verifying op permissions
2021-07-20T17:35:10.663-0400 7fe2cef6e700 5 req 1717 0.001000035s s3:list_bucket Searching permissions for identity=rgw::auth::SysReqApplier -> rgw::auth::LocalApplier(acct_user=ownerid, acct_name=ownername, subuser=, perm_mask=15, is_admin=0) mask=49
2021-07-20T17:35:10.663-0400 7fe2cef6e700 5 Searching permissions for uid=ownerid
2021-07-20T17:35:10.663-0400 7fe2cef6e700 5 Found permission: 15
2021-07-20T17:35:10.663-0400 7fe2cef6e700 5 Searching permissions for group=1 mask=49
2021-07-20T17:35:10.663-0400 7fe2cef6e700 5 Permissions for group not found
2021-07-20T17:35:10.663-0400 7fe2cef6e700 5 Searching permissions for group=2 mask=49
2021-07-20T17:35:10.663-0400 7fe2cef6e700 5 Permissions for group not found
2021-07-20T17:35:10.663-0400 7fe2cef6e700 5 req 1717 0.001000035s s3:list_bucket -- Getting permissions done for identity=rgw::auth::SysReqApplier -> rgw::auth::LocalApplier(acct_user=ownerid, acct_name=ownername, subuser=, perm_mask=15, is_admin=0), owner=ownerid, perm=1
2021-07-20T17:35:10.663-0400 7fe2cef6e700 2 req 1717 0.001000035s s3:list_bucket verifying op params
2021-07-20T17:35:10.663-0400 7fe2cef6e700 2 req 1717 0.001000035s s3:list_bucket pre-executing
2021-07-20T17:35:10.663-0400 7fe2cef6e700 2 req 1717 0.001000035s s3:list_bucket executing
2021-07-20T17:35:10.672-0400 7fe2cef6e700 2 req 1717 0.010000351s s3:list_bucket completing
2021-07-20T17:35:10.682-0400 7fe2cef6e700 2 req 1717 0.020000702s s3:list_bucket op status=0
2021-07-20T17:35:10.682-0400 7fe2cef6e700 2 req 1717 0.020000702s s3:list_bucket http status=200
2021-07-20T17:35:10.682-0400 7fe2cef6e700 1 ====== req done req=0x7fe3337246d0 op status=0 http_status=200 latency=0.020000702s ======
2021-07-20T17:35:10.682-0400 7fe2cef6e700 1 beast: 0x7fe3337246d0: 127.0.0.1 - - [2021-07-20T17:35:10.682450-0400] "GET /bucketname/?prefix=carto/DATA/site/Works/vq/ HTTP/1.1" 200 371849 - "PostmanRuntime/7.28.0" -
2021-07-20T17:35:26.475-0400 7fe306fde700 2 RGWDataChangesLog::ChangesRenewThread: start
2021-07-20T17:35:48.475-0400 7fe306fde700 2 RGWDataChangesLog::ChangesRenewThread: start
2021-07-20T17:35:57.322-0400 7fe2be74d700 1 ====== starting new request req=0x7fe3337246d0 =====
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s initializing for trans_id = tx0000000000000000006b6-0060f741bd-69e0a08-campusprcc
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s getting op 0
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket verifying requester
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket normalizing buckets and tenants
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket init permissions
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket recalculating target
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket reading permissions
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket init op
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket verifying op mask
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket verifying op permissions
2021-07-20T17:35:57.322-0400 7fe2be74d700 5 req 1718 0s s3:list_bucket Searching permissions for identity=rgw::auth::SysReqApplier -> rgw::auth::LocalApplier(acct_user=ownerid, acct_name=ownername, subuser=, perm_mask=15, is_admin=0) mask=49
2021-07-20T17:35:57.322-0400 7fe2be74d700 5 Searching permissions for uid=ownerid
2021-07-20T17:35:57.322-0400 7fe2be74d700 5 Found permission: 15
2021-07-20T17:35:57.322-0400 7fe2be74d700 5 Searching permissions for group=1 mask=49
2021-07-20T17:35:57.322-0400 7fe2be74d700 5 Permissions for group not found
2021-07-20T17:35:57.322-0400 7fe2be74d700 5 Searching permissions for group=2 mask=49
2021-07-20T17:35:57.322-0400 7fe2be74d700 5 Permissions for group not found
2021-07-20T17:35:57.322-0400 7fe2be74d700 5 req 1718 0s s3:list_bucket -- Getting permissions done for identity=rgw::auth::SysReqApplier -> rgw::auth::LocalApplier(acct_user=ownerid, acct_name=ownername, subuser=, perm_mask=15, is_admin=0), owner=ownerid, perm=1
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket verifying op params
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket pre-executing
2021-07-20T17:35:57.322-0400 7fe2be74d700 2 req 1718 0s s3:list_bucket executing
2021-07-20T17:35:57.338-0400 7fe2be74d700 2 req 1718 0.016000562s s3:list_bucket completing
2021-07-20T17:35:57.339-0400 7fe2be74d700 2 req 1718 0.017000597s s3:list_bucket op status=0
2021-07-20T17:35:57.339-0400 7fe2be74d700 2 req 1718 0.017000597s s3:list_bucket http status=200
2021-07-20T17:35:57.339-0400 7fe2be74d700 1 ====== req done req=0x7fe3337246d0 op status=0 http_status=200 latency=0.017000597s ======
2021-07-20T17:35:57.339-0400 7fe2be74d700 1 beast: 0x7fe3337246d0: 127.0.0.1 - - [2021-07-20T17:35:57.339089-0400] "GET /bucketname/?prefix=carto/DATA/site/Works/vq/&delimiter=/ HTTP/1.1" 200 640 - "PostmanRuntime/7.28.0" -
2021-07-20T17:36:10.476-0400 7fe306fde700 2 RGWDataChangesLog::ChangesRenewThread: start
2021-07-20T17:36:12.142-0400 7fe268ea2700 1 ====== starting new request req=0x7fe3337246d0 =====
2021-07-20T17:36:12.142-0400 7fe268ea2700 2 req 1719 0s initializing for trans_id = tx0000000000000000006b7-0060f741cc-69e0a08-campusprcc
2021-07-20T17:36:12.142-0400 7fe268ea2700 2 req 1719 0s getting op 0
2021-07-20T17:36:12.142-0400 7fe268ea2700 2 req 1719 0s s3:list_bucket verifying requester
2021-07-20T17:36:12.142-0400 7fe268ea2700 2 req 1719 0s s3:list_bucket normalizing buckets and tenants
2021-07-20T17:36:12.142-0400 7fe268ea2700 2 req 1719 0s s3:list_bucket init permissions
2021-07-20T17:36:12.142-0400 7fe268ea2700 2 req 1719 0s s3:list_bucket recalculating target
2021-07-20T17:36:12.142-0400 7fe268ea2700 2 req 1719 0s s3:list_bucket reading permissions
2021-07-20T17:36:12.142-0400 7fe268ea2700 2 req 1719 0s s3:list_bucket init op
2021-07-20T17:36:12.142-0400 7fe268ea2700 2 req 1719 0s s3:list_bucket verifying op mask
2021-07-20T17:36:12.143-0400 7fe268ea2700 2 req 1719 0.001000035s s3:list_bucket verifying op permissions
2021-07-20T17:36:12.143-0400 7fe268ea2700 5 req 1719 0.001000035s s3:list_bucket Searching permissions for identity=rgw::auth::SysReqApplier -> rgw::auth::LocalApplier(acct_user=ownerid, acct_name=ownername, subuser=, perm_mask=15, is_admin=0) mask=49
2021-07-20T17:36:12.143-0400 7fe268ea2700 5 Searching permissions for uid=ownerid
2021-07-20T17:36:12.143-0400 7fe268ea2700 5 Found permission: 15
2021-07-20T17:36:12.143-0400 7fe268ea2700 5 Searching permissions for group=1 mask=49
2021-07-20T17:36:12.143-0400 7fe268ea2700 5 Permissions for group not found
2021-07-20T17:36:12.143-0400 7fe268ea2700 5 Searching permissions for group=2 mask=49
2021-07-20T17:36:12.143-0400 7fe268ea2700 5 Permissions for group not found
2021-07-20T17:36:12.143-0400 7fe268ea2700 5 req 1719 0.001000035s s3:list_bucket -- Getting permissions done for identity=rgw::auth::SysReqApplier -> rgw::auth::LocalApplier(acct_user=ownerid, acct_name=ownername, subuser=, perm_mask=15, is_admin=0), owner=ownerid, perm=1
2021-07-20T17:36:12.143-0400 7fe268ea2700 2 req 1719 0.001000035s s3:list_bucket verifying op params
2021-07-20T17:36:12.143-0400 7fe268ea2700 2 req 1719 0.001000035s s3:list_bucket pre-executing
2021-07-20T17:36:12.143-0400 7fe268ea2700 2 req 1719 0.001000035s s3:list_bucket executing
2021-07-20T17:36:12.151-0400 7fe268ea2700 2 req 1719 0.009000316s s3:list_bucket completing
2021-07-20T17:36:12.152-0400 7fe268ea2700 2 req 1719 0.010000351s s3:list_bucket op status=0
2021-07-20T17:36:12.152-0400 7fe268ea2700 2 req 1719 0.010000351s s3:list_bucket http status=200
2021-07-20T17:36:12.152-0400 7fe268ea2700 1 ====== req done req=0x7fe3337246d0 op status=0 http_status=200 latency=0.010000351s ======
2021-07-20T17:36:12.152-0400 7fe268ea2700 1 beast: 0x7fe3337246d0: 127.0.0.1 - - [2021-07-20T17:36:12.152609-0400] "GET /bucketname/?prefix=carto/DATA/site/Works/vq/&delimiter=/ HTTP/1.1" 200 640 - "PostmanRuntime/7.28.0" -

(2-2/8)