Project

General

Profile

Bug #63973 ยป rgw-debug.log

radosgw debug log (level 10) - Markus Schuster, 01/26/2024 04:35 PM

 
Jan 25 15:49:26: debug 2024-01-25T15:49:26.655+0000 7fb4c6a21700 1 ====== starting new request req=0x7fb58d9ad620 =====
Jan 25 15:49:26: debug 2024-01-25T15:49:26.655+0000 7fb4c6a21700 2 req 8408276331978817744 0.000000000s initializing for trans_id = tx0000074b032c664ec00d0-0065b28306-288cb-ams-3
Jan 25 15:49:26: debug 2024-01-25T15:49:26.655+0000 7fb4c6a21700 10 req 8408276331978817744 0.000000000s rgw api priority: s3=8 s3website=7
Jan 25 15:49:26: debug 2024-01-25T15:49:26.655+0000 7fb4c6a21700 10 req 8408276331978817744 0.000000000s host=s3.ams3.srv.xxx
Jan 25 15:49:26: debug 2024-01-25T15:49:26.655+0000 7fb4c6a21700 10 req 8408276331978817744 0.000000000s meta>> HTTP_X_AMZ_CONTENT_SHA256
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s meta>> HTTP_X_AMZ_DATE
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s x>> x-amz-content-sha256:UNSIGNED-PAYLOAD
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s x>> x-amz-date:20240125T154926Z
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s handler=22RGWHandler_REST_Obj_S3
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 2 req 8408276331978817744 0.001000032s getting op 3
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s s3:get_obj scheduling with throttler client=2 cost=1
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s s3:get_obj op=21RGWGetObj_ObjStore_S3
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 2 req 8408276331978817744 0.001000032s s3:get_obj verifying requester
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s v4 signature format = b29a3ca43d40a227c6a742bd8af8af3313af49d53f1fee768a74558d8c0e8285
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s v4 credential format = AM9QC9KV786UCK6PV4DX/20240125/europe/s3/aws4_request
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s access key id = AM9QC9KV786UCK6PV4DX
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s credential scope = 20240125/europe/s3/aws4_request
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s canonical headers format = host:s3.ams3.srv.xxx
Jan 25 15:49:26: x-amz-content-sha256:UNSIGNED-PAYLOAD
Jan 25 15:49:26: x-amz-date:20240125T154926Z
Jan 25 15:49:26:
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s payload request hash = UNSIGNED-PAYLOAD
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s canonical request = HEAD
Jan 25 15:49:26: /transcodeit-tus-temp/58f14c41-1425-4606-8ca7-c6b7f1423945/uploadlength.txt
Jan 25 15:49:26:
Jan 25 15:49:26: host:s3.ams3.srv.xxx
Jan 25 15:49:26: x-amz-content-sha256:UNSIGNED-PAYLOAD
Jan 25 15:49:26: x-amz-date:20240125T154926Z
Jan 25 15:49:26:
Jan 25 15:49:26: host;x-amz-content-sha256;x-amz-date
Jan 25 15:49:26: UNSIGNED-PAYLOAD
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s canonical request hash = 8f72516d52c8599da52b391a0058fe07427704f62c50bd1263846add4e9f37ac
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s string to sign = AWS4-HMAC-SHA256
Jan 25 15:49:26: 20240125T154926Z
Jan 25 15:49:26: 20240125/europe/s3/aws4_request
Jan 25 15:49:26: 8f72516d52c8599da52b391a0058fe07427704f62c50bd1263846add4e9f37ac
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s date_k = 4f0db7d15806a7b043d7f4f5a9e6f32a7fc64a7b339e0f801f4111960b59dd1a
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s region_k = 1249b9f092e05002fa1a88c00c1ddd3e2a0367593b942c87705f544605ca85b8
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s service_k = d9118f6c06d35f9a34f4e820ef6fc55f2f395e62a2c401ad57a86804483fde31
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s signing_k = 1d647c272a465757cb23bd013776ca70ca829529bf5eca85bde1c0b224538a6c
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s generated signature = b29a3ca43d40a227c6a742bd8af8af3313af49d53f1fee768a74558d8c0e8285
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 2 req 8408276331978817744 0.001000032s s3:get_obj normalizing buckets and tenants
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s s->object=58f14c41-1425-4606-8ca7-c6b7f1423945/uploadlength.txt s->bucket=transcodeit-tus-temp
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 2 req 8408276331978817744 0.001000032s s3:get_obj init permissions
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s s3:get_obj cache get: name=ams-3.rgw.meta+root+transcodeit-tus-temp : hit (requested=0x16, cached=0x17)
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s s3:get_obj cache get: name=ams-3.rgw.meta+root+transcodeit-tus-temp : hit (requested=0x11, cached=0x17)
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s s3:get_obj cache get: name=ams-3.rgw.meta+users.uid+transcodeit : hit (requested=0x6, cached=0x17)
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 10 req 8408276331978817744 0.001000032s s3:get_obj cache get: name=ams-3.rgw.meta+users.uid+transcodeit : hit (requested=0x3, cached=0x17)
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 2 req 8408276331978817744 0.001000032s s3:get_obj recalculating target
Jan 25 15:49:26: debug 2024-01-25T15:49:26.656+0000 7fb4c6a21700 2 req 8408276331978817744 0.001000032s s3:get_obj reading permissions
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 received header:HTTP/1.1 200 OK
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 received header:x-amz-request-id: tx0000031e9ab4a80335987-0065b28306-23318-ams-1
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 received header:Content-Length: 490
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 received header:Date: Thu, 25 Jan 2024 15:49:26 GMT
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 received header:Connection: Keep-Alive
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58b3aa700 10 received header:
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58cbad700 10 RGW-SYNC:data:sync:shard[95]:entry:bucket_sync_sources[target=:[]):source_bucket=:[]):source_zone=42400e58-1700-4962-8c46-15259701027e]:bucket[transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:0<-transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:0]:inc_sync[transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:0]:entry[cf1b7491-e7e4-4629-a637-2e52156b3f66/uploadoffset.txt]: removing obj: 42400e58-1700-4962-8c46-15259701027e/:transcodeit-tus-temp[42400e58-1700-4962-8c46-15259701027e.95908.3488])/cf1b7491-e7e4-4629-a637-2e52156b3f66/uploadoffset.txt[0]
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb59c5d3700 0 rgw async rados processor: _send_request(): deleting obj=transcodeit-tus-temp:cf1b7491-e7e4-4629-a637-2e52156b3f66/uploadoffset.txt
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58cbad700 10 rgw rados thread: get_canon_resource(): dest=/admin/log
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58cbad700 10 rgw rados thread: generated canonical header: GET
Jan 25 15:49:26:
Jan 25 15:49:26:
Jan 25 15:49:26: Thu, 25 Jan 2024 15:49:26 +0000
Jan 25 15:49:26: /admin/log
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58cbad700 10 rgw rados thread: get_canon_resource(): dest=/admin/log/
Jan 25 15:49:26: debug 2024-01-25T15:49:26.657+0000 7fb58cbad700 10 rgw rados thread: generated canonical header: GET
Jan 25 15:49:26:
Jan 25 15:49:26:
Jan 25 15:49:26: Thu, 25 Jan 2024 15:49:26 +0000
Jan 25 15:49:26: /admin/log/
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 received header:HTTP/1.1 200 OK
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 received header:x-amz-request-id: tx00000fc7285b9938caad9-0065b28306-23318-ams-1
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 received header:Content-Length: 2
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 received header:Date: Thu, 25 Jan 2024 15:49:26 GMT
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 received header:Connection: Keep-Alive
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.658+0000 7fb58b3aa700 10 received header:
Jan 25 15:49:26: debug 2024-01-25T15:49:26.660+0000 7fb59c5d3700 10 rgw async rados processor: manifest: total_size = 9
Jan 25 15:49:26: debug 2024-01-25T15:49:26.660+0000 7fb59c5d3700 10 rgw async rados processor: If-UnModified-Since: 2024-01-25T15:48:18.869605+0000 Last-Modified: 2024-01-25T15:48:18.869605+0000
Jan 25 15:49:26: debug 2024-01-25T15:49:26.663+0000 7fb5a05db700 10 rgw async rados processor: setting object write_tag=_-a6b87X3SmJaV-65AwZbTARo72Fo7Hk
Jan 25 15:49:26: debug 2024-01-25T15:49:26.669+0000 7fb58cbad700 10 rgw rados thread: get_canon_resource(): dest=/admin/log/
Jan 25 15:49:26: debug 2024-01-25T15:49:26.669+0000 7fb58cbad700 10 rgw rados thread: generated canonical header: GET
Jan 25 15:49:26:
Jan 25 15:49:26:
Jan 25 15:49:26: Thu, 25 Jan 2024 15:49:26 +0000
Jan 25 15:49:26: /admin/log/
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 received header:HTTP/1.1 200 OK
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 received header:x-amz-request-id: tx00000c975fec91d7fe03c-0065b28306-23318-ams-1
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 received header:Content-Length: 44
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 received header:Date: Thu, 25 Jan 2024 15:49:26 GMT
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 received header:Connection: Keep-Alive
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.670+0000 7fb58b3aa700 10 received header:
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 received header:HTTP/1.1 200 OK
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 received header:x-amz-request-id: tx00000f82eea8bbf99412d-0065b28306-23318-ams-1
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 received header:Content-Length: 44
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 received header:Date: Thu, 25 Jan 2024 15:49:26 GMT
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 received header:Connection: Keep-Alive
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.671+0000 7fb58b3aa700 10 received header:
Jan 25 15:49:26: debug 2024-01-25T15:49:26.676+0000 7fb5a2de0700 10 rgw async rados processor: cache put: name=ams-3.rgw.log++bucket.sync-status.42400e58-1700-4962-8c46-15259701027e:transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:2 info.flags=0x18
Jan 25 15:49:26: debug 2024-01-25T15:49:26.676+0000 7fb5a2de0700 10 rgw async rados processor: moving ams-3.rgw.log++bucket.sync-status.42400e58-1700-4962-8c46-15259701027e:transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:2 to cache LRU end
Jan 25 15:49:26: debug 2024-01-25T15:49:26.676+0000 7fb5a2de0700 10 rgw async rados processor: appending xattr: name=user.rgw.bucket-sync.inc_marker bl.length()=39
Jan 25 15:49:26: debug 2024-01-25T15:49:26.676+0000 7fb5a2de0700 10 rgw async rados processor: distributing notification oid=ams-3.rgw.control:notify.1 cni=[op: 0, obj: ams-3.rgw.log:bucket.sync-status.42400e58-1700-4962-8c46-15259701027e:transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:2, ofs0, ns]
Jan 25 15:49:26: debug 2024-01-25T15:49:26.676+0000 7fb5a7dea700 10 rgw watcher librados: RGWWatcher::handle_notify() notify_id 129501860563548 cookie 94796429316480 notifier 166091 bl.length()=365
Jan 25 15:49:26: debug 2024-01-25T15:49:26.676+0000 7fb5a7dea700 10 rgw watcher librados: cache put: name=ams-3.rgw.log++bucket.sync-status.42400e58-1700-4962-8c46-15259701027e:transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:2 info.flags=0x18
Jan 25 15:49:26: debug 2024-01-25T15:49:26.676+0000 7fb5a7dea700 10 rgw watcher librados: moving ams-3.rgw.log++bucket.sync-status.42400e58-1700-4962-8c46-15259701027e:transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:2 to cache LRU end
Jan 25 15:49:26: debug 2024-01-25T15:49:26.676+0000 7fb5a7dea700 10 rgw watcher librados: appending xattr: name=user.rgw.bucket-sync.inc_marker bl.length()=39
Jan 25 15:49:26: debug 2024-01-25T15:49:26.676+0000 7fb58cbad700 10 RGW-SYNC:data:sync:shard[97]:entry: sync finished on transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:2 progress=2024-01-25T15:49:26.562582+0000 key=transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:2 r=0
Jan 25 15:49:26: debug 2024-01-25T15:49:26.677+0000 7fb58cbad700 10 rgw rados thread: get_canon_resource(): dest=/admin/log/
Jan 25 15:49:26: debug 2024-01-25T15:49:26.677+0000 7fb58cbad700 10 rgw rados thread: generated canonical header: GET
Jan 25 15:49:26:
Jan 25 15:49:26:
Jan 25 15:49:26: Thu, 25 Jan 2024 15:49:26 +0000
Jan 25 15:49:26: /admin/log/
Jan 25 15:49:26: debug 2024-01-25T15:49:26.679+0000 7fb58cbad700 10 RGW-SYNC:data:sync:shard[103]:entry: sync finished on transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:8 progress=2024-01-25T15:43:46.652140+0000 key=transcodeit-tus-temp:42400e58-1700-4962-8c46-15259701027e.95908.3488:8 r=0
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 received header:HTTP/1.1 200 OK
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 received header:x-amz-request-id: tx00000a98864c44d0027bd-0065b28306-23318-ams-1
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 received header:Content-Length: 44
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 received header:Date: Thu, 25 Jan 2024 15:49:26 GMT
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 received header:Connection: Keep-Alive
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 receive_http_header
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb58b3aa700 10 received header:
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 1 ====== starting new request req=0x7fb47b189620 =====
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s initializing for trans_id = tx00000cb0df7c1d7d544d9-0065b28306-288cb-ams-3
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s rgw api priority: s3=8 s3website=7
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s host=s3.ams3.srv.xxx
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s name: type val: data
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s name: id val: 113
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s name: marker val: 00000000000000000025:00000000000000197435
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s name: extra-info val: true
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s name: rgwx-zonegroup val: 79dab89b-da63-402c-9427-48746515ff95
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s handler=14RGWHandler_Log
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s getting op 0
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s :list_data_changes_log scheduling with throttler client=0 cost=1
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s :list_data_changes_log op=18RGWOp_DATALog_List
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log verifying requester
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s get_canon_resource(): dest=/admin/log/
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s string_to_sign:
Jan 25 15:49:26: GET
Jan 25 15:49:26:
Jan 25 15:49:26:
Jan 25 15:49:26: Thu, 25 Jan 2024 15:49:26 +0000
Jan 25 15:49:26: /admin/log/
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log normalizing buckets and tenants
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log init permissions
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s :list_data_changes_log cache get: name=ams-3.rgw.meta+users.uid+synchronization-user : hit (requested=0x6, cached=0x17)
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 10 req 14631623176318436569 0.000000000s :list_data_changes_log cache get: name=ams-3.rgw.meta+users.uid+synchronization-user : hit (requested=0x3, cached=0x17)
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log recalculating target
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log reading permissions
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log init op
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log verifying op mask
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log verifying op permissions
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 overriding permissions due to system operation
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log verifying op params
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log pre-executing
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log executing
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log completing
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 10 req 8408276331978817744 0.025000792s s3:get_obj manifest: total_size = 9
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 2 req 8408276331978817744 0.025000792s s3:get_obj init op
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 10 req 8408276331978817744 0.025000792s s3:get_obj cache get: name=ams-3.rgw.meta+users.uid+transcodeit-tus-temp : hit (requested=0x6, cached=0x7)
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 10 req 8408276331978817744 0.025000792s s3:get_obj cache get: name=ams-3.rgw.meta+users.uid+transcodeit-tus-temp : hit (requested=0x1, cached=0x7)
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 2 req 8408276331978817744 0.025000792s s3:get_obj verifying op mask
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 2 req 8408276331978817744 0.025000792s s3:get_obj verifying op permissions
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log op status=0
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 2 req 14631623176318436569 0.000000000s :list_data_changes_log http status=200
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 1 ====== req done req=0x7fb47b189620 op status=0 http_status=200 latency=0.000000000s ======
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 2 req 8408276331978817744 0.025000792s s3:get_obj verifying op params
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 2 req 8408276331978817744 0.025000792s s3:get_obj pre-executing
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 2 req 8408276331978817744 0.025000792s s3:get_obj executing
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb50caad700 10 req 8408276331978817744 0.025000792s rule: Delete TUS upload fragments after 7 days and clean-up incomplete uploads prefix: expiration: date: days: 7 noncur_expiration: date: days: 0
Jan 25 15:49:26: debug 2024-01-25T15:49:26.680+0000 7fb559b47700 1 beast: 0x7fb47b189620: 127.0.0.1 - synchronization-user [02/Feb/2024:00:49:26.680 +0000] "GET /admin/log/?type=data&id=113&marker=00000000000000000025%3A00000000000000197435&extra-info=true&rgwx-zonegroup=79dab89b-da63-402c-9427-48746515ff95 HTTP/1.1" 200 340 - - - latency=0.000000000s
Jan 25 15:49:26: debug 2024-01-25T15:49:26.681+0000 7fb50caad700 2 req 8408276331978817744 0.026000824s s3:get_obj completing
Jan 25 15:49:26: debug 2024-01-25T15:49:26.681+0000 7fb50caad700 2 req 8408276331978817744 0.026000824s s3:get_obj op status=0
Jan 25 15:49:26: debug 2024-01-25T15:49:26.681+0000 7fb50caad700 2 req 8408276331978817744 0.026000824s s3:get_obj http status=200
Jan 25 15:49:26: debug 2024-01-25T15:49:26.681+0000 7fb50caad700 1 ====== req done req=0x7fb58d9ad620 op status=0 http_status=200 latency=0.026000824s ======
Jan 25 15:49:26: debug 2024-01-25T15:49:26.681+0000 7fb50caad700 1 beast: 0x7fb58d9ad620: 127.0.0.1 - transcodeit [25/Jan/2024:15:49:26.655 +0000] "HEAD /transcodeit-tus-temp/58f14c41-1425-4606-8ca7-c6b7f1423945/uploadlength.txt HTTP/1.1" 200 0 - - - latency=0.026000824s
    (1-1/1)